Test Chat on Web API to Agent

[Peter Parker]

Hi experts,

I have edited a scenario ABC Simple Chat BP on IRD, then I'm trying to test this scenario by sending a message to Agent through feature chat on Web API, but it is failed to route to an Agent. Can someone help me suggest a few ideas to fix the error as below.

Logs:

AttributeThisDN [str] = "asl_Chat inbound strategy"

2017-06-22T15:31:17.801 Trc 24016 Reference identifier mapping for 'RequestUpdate/Attach/Delete user data' regarding interaction '00005aCMF6QG0046', mapped original reference id 5227 to internal 100002509
2017-06-22T15:31:17.801 Trc 24101 Received from Universal Routing Server: e_urs: 'RequestRouteCall' (36) message:
AttributeReferenceID [int] = 5228
AttributeRouteType [int] = 7
AttributeOtherDN [str] = ""
AttributeConnID [long] = 28ff02a4517439ee
AttributeThisDN [str] = "asl_Chat inbound strategy"

2017-06-22T15:31:17.801 Trc 24003 Universal Routing Server 'e_urs' (client 1:1832) has rejected interaction '00005aCMF6QG0046', placing interaction back into queue
2017-06-22T15:31:17.801 Dbg 25020 Queuing dbmanager request: request id: '8249', request:[Bind:Update; update all properties], stack:'default:standard', stack size: '1', interaction id:'00005aCMF6QG0046'
2017-06-22T15:31:17.801 Dbg 25021 Getting ready to send dbmanager request: request id: '8249', request:[update all properties], stack size: '1', interaction id:'00005aCMF6QG0046'
2017-06-22T15:31:17.801 Dbg 25030 Database request sent: DAP:'e_ixn_dap'(0), request id: '28332', dbchannel stack id: '1', interaction id:'', sql statement: [Begin Transaction]
2017-06-22T15:31:17.801 Trc 26020 Distributing message 'EventPropertiesChanged' ('104'), message attributes:
attr_extension [list, size (unpacked)=45] =
  'ReportingEventSequenceNumber' [str] = "38976888"
attr_actor_type [int] = 1 [STRATEGY]
attr_actor_tenant_id [int] = 1
attr_actor_strategy_id [str] = "Chat inbound strategy"
attr_actor_router_id [str] = "e_urs"
attr_event_time [str] = "2017-06-22T08:31:17Z"
attr_itx_id [str] = "00005aCMF6QG0046"
attr_itx_type [str] = "Inbound"
attr_itx_subtype [str] = "InboundNew"
attr_itx_tenant_id [int] = 1
attr_itx_queue [str] = "Chat inbound queue"
attr_itx_media_type [str] = "chat"
attr_itx_submitted_by [str] = "e_chat_srv"
attr_itx_state [int] = 2 [ROUTING]
attr_itx_is_online [int] = 0
attr_itx_is_locked [int] = 0
attr_itx_received_at [str] = "2017-06-21T03:16:31Z"
attr_itx_submitted_at [str] = "2017-06-21T03:16:31Z"
attr_itx_submitted_to_router_at [str] = "2017-06-22T08:31:02Z"
attr_itx_placed_in_queue_at [str] = "2017-06-22T08:30:59Z"
attr_itx_moved_to_queue_at [str] = "2017-06-21T03:16:31Z"
attr_abandoned_at [str] = "2017-06-21T03:36:09Z"
attr_itx_submit_seq [str] = "27330421"
attr_itx_place_in_queue_seq [str] = "38976727"
attr_itx_user_data [list, size (unpacked)=238, user data may be hidden] =
attr_itx_is_held [int] = 0
attr_changed_prop [list, size (unpacked)=24, user data may be hidden] =

2017-06-22T15:31:17.801 Dbg 25032 Database request executed: DAP:'e_ixn_dap'(0), request id: '28332', dbchannel stack id: '1', interaction id:'', sql statement: [Begin Transaction]
2017-06-22T15:31:17.801 Dbg 25030 Database request sent: DAP:'e_ixn_dap'(0), request id: '28333', dbchannel stack id: '2', interaction id:'00005aCMF6QG0046', sql statement: [execute binding (update all)]
2017-06-22T15:31:17.801 Trc 24101 Received from Universal Routing Server: e_urs: 'RequestUpdateUserData' (31) message:
AttributeReferenceID [int] = 5229
AttributeUserData [list, size (unpacked)=120, user data may be hidden] =
AttributeConnID [long] = 28ff02a4517439ef
AttributeThisDN [str] = "asl_Chat inbound strategy"

2017-06-22T15:31:17.801 Trc 24016 Reference identifier mapping for 'RequestUpdate/Attach/Delete user data' regarding interaction '00005aCMF6QG0048', mapped original reference id 5229 to internal 100002510
2017-06-22T15:31:17.801 Trc 24101 Received from Universal Routing Server: e_urs: 'RequestRouteCall' (36) message:
AttributeReferenceID [int] = 5230
AttributeRouteType [int] = 7
AttributeOtherDN [str] = ""
AttributeConnID [long] = 28ff02a4517439ef
AttributeThisDN [str] = "asl_Chat inbound strategy"

2017-06-22T15:31:17.801 Trc 24003 Universal Routing Server 'e_urs' (client 1:1832) has rejected interaction '00005aCMF6QG0048', placing interaction back into queue
2017-06-22T15:31:17.801 Trc 24101 Received from Universal Routing Server: e_urs: 'RequestUpdateUserData' (31) message:
AttributeReferenceID [int] = 5231
AttributeUserData [list, size (unpacked)=120, user data may be hidden] =
AttributeConnID [long] = 28ff02a4517439f0
AttributeThisDN [str] = "asl_Chat inbound strategy"

2017-06-22T15:31:17.801 Trc 24016 Reference identifier mapping for 'RequestUpdate/Attach/Delete user data' regarding interaction '00005aCMF6QG004D', mapped original reference id 5231 to internal 100002511
2017-06-22T15:31:17.801 Trc 24101 Received from Universal Routing Server: e_urs: 'RequestRouteCall' (36) message:
AttributeReferenceID [int] = 5232
AttributeRouteType [int] = 7
AttributeOtherDN [str] = ""
AttributeConnID [long] = 28ff02a4517439f0
AttributeThisDN [str] = "asl_Chat inbound strategy"

2017-06-22T15:31:17.801 Trc 24003 Universal Routing Server 'e_urs' (client 1:1832) has rejected interaction '00005aCMF6QG004D', placing interaction back into queue
2017-06-22T15:31:17.801 Trc 24101 Received from Universal Routing Server: e_urs: 'RequestUpdateUserData' (31) message:
AttributeReferenceID [int] = 5233
AttributeUserData [list, size (unpacked)=120, user data may be hidden] =
AttributeConnID [long] = 28ff02a4517439f1
AttributeThisDN [str] = "asl_Chat inbound strategy"

2017-06-22T15:31:17.801 Trc 24016 Reference identifier mapping for 'RequestUpdate/Attach/Delete user data' regarding interaction '00005aCMF6QG004F', mapped original reference id 5233 to internal 100002512
2017-06-22T15:31:17.801 Trc 24101 Received from Universal Routing Server: e_urs: 'RequestRouteCall' (36) message:
AttributeReferenceID [int] = 5234
AttributeRouteType [int] = 7
AttributeOtherDN [str] = ""
AttributeConnID [long] = 28ff02a4517439f1
AttributeThisDN [str] = "asl_Chat inbound strategy"

Chat log:

  Version [str] = "1.0"
    AppName [str] = "e_chat_srv"
    Service [str] = "Chat"
    Method [str] = "Message"
    Parameters = [kvlist:]
        MessageText [str] = ****
        Nickname [str] = "Peter Parker"
2017-06-22T15:38:53.721 Trc 59462 ESP: failed to process request from conn_id=636:1 with esp_refid=216 for intx=00005aCMF6QG006R. Could not process request: interaction with specified id was not found
2017-06-22T15:38:53.721 Trc 59464 ESP: sending error for conn_id=636:1 with esp_refid=216 ... succeeded
2017-06-22T15:38:53.721 Trc 59461 ESP: received request from conn_id=636:1 with esp_refid=217 for intx=00005aCMF6QG0071. Content:
    Version [str] = "1.0"
    AppName [str] = "e_chat_srv"
    Service [str] = "Chat"
    Method [str] = "Message"
    Parameters = [kvlist:]
        MessageText [str] = ****
        Nickname [str] = "Peter Parker"
2017-06-22T15:38:53.721 Int 59463 ESP: processing request {Message} from conn_id=636:1 with esp_refid=217 for intx=00005aCMF6QG0071 with parameters:
        Nickname = Peter Parker
        Visibility = ALL
        MessageText = ****
2017-06-22T15:38:53.721 Trc 59464 ESP: sending reply for conn_id=636:1 with esp_refid=217 ... succeeded
2017-06-22T15:38:55.266 Trc 59201 new connection created with conn_id=920:402 (type=UNKNOWN, port=8778)
2017-06-22T15:38:55.266 Trc 59220 input (data received) from conn_id=920:402 (type=FLEX): flex requestRefresh (ping)
refId=1, userId=00EC594B7AAF0006, secureKey=f8e44dd56242eb8c, fromPosition=98
2017-06-22T15:38:55.266 Trc 59221 output (data sent) for conn_id=920:402+ : flex eventStatus (ping)
refId=1, userId=00EC594B7AAF0006, secureKey=f8e44dd56242eb8c, sessionId=00005aCMF6QG0071, lastPosition=98, flexTranscript:
MESSAGE: sc='1902', ei='98', nk='Peter Parker', ut='EXTERNAL', pd='3', tx='' [truncated from size (bytes) = 41]
2017-06-22T15:38:55.266 Trc 59202 connection was closed for conn_id=920:402 (type=FLEX, port=8778)
2017-06-22T15:39:00.371 Trc 59201 new connection created with conn_id=920:403 (type=UNKNOWN, port=8778)
2017-06-22T15:39:00.371 Trc 59220 input (data received) from conn_id=920:403 (type=FLEX): flex requestRefresh (ping)
refId=1, userId=00EC594B7AAF0006, secureKey=f8e44dd56242eb8c, fromPosition=99
2017-06-22T15:39:00.371 Trc 59221 output (data sent) for conn_id=920:403+ : flex eventStatus (ping)
refId=1, userId=00EC594B7AAF0006, secureKey=f8e44dd56242eb8c, sessionId=00005aCMF6QG0071, lastPosition=98
2017-06-22T15:39:00.371 Trc 59202 connection was closed for conn_id=920:403 (type=FLEX, port=8778)
2017-06-22T15:39:05.489 Trc 59201 new connection created with conn_id=908:404 (type=UNKNOWN, port=8778)
2017-06-22T15:39:05.489 Trc 59220 input (data received) from conn_id=908:404 (type=FLEX): flex requestRefresh (ping)
refId=1, userId=00EC594B7AAF0006, secureKey=f8e44dd56242eb8c, fromPosition=99
2017-06-22T15:39:05.489 Trc 59221 output (data sent) for conn_id=908:404+ : flex eventStatus (ping)
refId=1, userId=00EC594B7AAF0006, secureKey=f8e44dd56242eb8c, sessionId=00005aCMF6QG0071, lastPosition=98
2017-06-22T15:39:05.489 Trc 59202 connection was closed for conn_id=908:404 (type=FLEX, port=8778)
2017-06-22T15:39:10.653 Trc 59201 new connection created with conn_id=920:405 (type=UNKNOWN, port=8778)
2017-06-22T15:39:10.653 Trc 59220 input (data received) from conn_id=920:405 (type=FLEX): flex requestRefresh (ping)
refId=1, userId=00EC594B7AAF0006, secureKey=f8e44dd56242eb8c, fromPosition=99
2017-06-22T15:39:10.653 Trc 59221 output (data sent) for conn_id=920:405+ : flex eventStatus (ping)
refId=1, userId=00EC594B7AAF0006, secureKey=f8e44dd56242eb8c, sessionId=00005aCMF6QG0071, lastPosition=98
2017-06-22T15:39:10.668 Trc 59202 connection was closed for conn_id=920:405 (type=FLEX, port=8778)
2017-06-22T15:39:11.823 Trc 59461 ESP: received request from conn_id=636:1 with esp_refid=218 for intx=00005aCMF6QG006R. Content:
    Version [str] = "1.0"
    AppName [str] = "e_chat_srv"
    Service [str] = "Chat"
    Method [str] = "Message"
    Parameters = [kvlist:]
        MessageText [str] = ****
        Nickname [str] = "Peter Parker"
2017-06-22T15:39:11.823 Trc 59462 ESP: failed to process request from conn_id=636:1 with esp_refid=218 for intx=00005aCMF6QG006R. Could not process request: interaction with specified id was not found
2017-06-22T15:39:11.823 Trc 59464 ESP: sending error for conn_id=636:1 with esp_refid=218 ... succeeded
2017-06-22T15:39:11.823 Trc 59461 ESP: received request from conn_id=636:1 with esp_refid=219 for intx=00005aCMF6QG0071. Content:
    Version [str] = "1.0"
    AppName [str] = "e_chat_srv"
    Service [str] = "Chat"
    Method [str] = "Message"
    Parameters = [kvlist:]
        MessageText [str] = ****
        Nickname [str] = "Peter Parker"
2017-06-22T15:39:11.823 Int 59463 ESP: processing request {Message} from conn_id=636:1 with esp_refid=219 for intx=00005aCMF6QG0071 with parameters:
        Nickname = Peter Parker
        Visibility = ALL
        MessageText = ****
2017-06-22T15:39:11.823 Trc 59464 ESP: sending reply for conn_id=636:1 with esp_refid=219 ... succeeded

screenshot

https://ibb.co/n4pfiQ
https://ibb.co/bLNBq5
https://ibb.co/cydHV5

From in my opinion, I see that after sending a message on  Web API, basing this logs  seem it routed to Agent, so why Agent can't receive a message from Web API. 

[cavagnaro]

I see error on Queue again...verify that, quite explicit error

Enviado de meu E6633 usando Tapatalk

[terry]

Not looks like it was routed to agent, URS rejects interaction (= Exit block is executed in strategy or URS fails to start strategy at all, no any routing).
May be check URS log?

[Peter Parker]

Hi terry, cavagnaro

Can you please help me check log as below?

URS log:

received from 65200(e_ixn)hcmors01:4420(fd=) message EventRouteRequest
AttributeTimeinuSecs 748580
AttributeTimeinSecs 1498183804 (09:10:04)
AttributeExtensions [39] 00 01 00 00..
'STRATEGY_ID' 'Chat inbound strategy'
AttributeUserData [712] 00 1b 00 00..
'IdentifyCreateContact' '3'
'EmailAddress' 'vuvanthan@ansv.vn'
'FirstName' 'Vu Van'
'ContactId' '00005aCMF6QG005J'
'LastName' 'Than'
'ChatServerHost' 'hcmors01'
'ChatServerPort' '4800'
'ChatServerDBID' '236'
'ChatServerAppName' 'e_chat_srv'
'Subject' 'Test Chat'
'IsHeld' 0
'InteractionId' '00006aCMQ678001B'
'TenantId' 1
'MediaType' 'chat'
'InteractionType' 'Inbound'
'InteractionSubtype' 'InboundNew'
'InteractionState' 1
'IsOnline' 1
'IsLocked' 0
'Queue' 'Chat inbound queue'
'SubmittedBy' 'e_chat_srv'
'ReceivedAt' '2017-06-23T02:10:01Z'
'SubmittedAt' '2017-06-23T02:10:01Z'
'PlacedInQueueAt' '2017-06-23T02:10:01Z'
'MovedToQueueAt' '2017-06-23T02:10:01Z'
'SubmitSeq' '43778632'
'PlaceInQueueSeq' '43778632'
AttributeThisQueue 'asl_Chat inbound strategy'
AttributeThisDN 'asl_Chat inbound strategy'
AttributeCallType 0
AttributeCallID 15852
AttributeMediaType 4294967295
AttributeConnID 2d1f02a46174e06d
AttributeCustomerID 'Environment'
09:10:04.748_T_I_0000000000000000 [14:0c] EventRouteRequest is received for ts e_ixn[MultiMediaSwitch] (this dn=asl_Chat inbound strategy)
    _T_I_2d1f02a46174e06d [01:11] connid 2d1f02a46174e06d is bound to the call 109-0000000001e92d60
09:10:04.748_I_I_2d1f02a46174e06d [01:01] call (109-0000000001e92d60) for Environment created (del=0 ts=0,12,0)
    _T_I_2d1f02a46174e06d [14:09] add DN e_ixn asl_Chat inbound strategy <asl_Chat inbound strategy@MultiMediaSwitch> (CDN 71 2d1f02a46174e06d 0000000000000000) to the call 109-0000000001e92d60 truly:22
    _T_I_2d1f02a46174e06d [14:08] check delayed proc
    _T_I_2d1f02a46174e06d [14:43] call data updated
    _I_I_2d1f02a46174e06d [09:0e] script 0000000001e0aff8 DS 0000000001e93fc0 [58] +c (7 3) (12 0000000000000000)
    _I_I_ [25:01] script *0x1*Chat inbound strategy usage +1=15
    _I_I_2d1f02a46174e06d [14:33] strategy: *0x1*Chat inbound strategy (3089101847) is attached to the call
09:10:04.748 Int 20001 interaction 2d1f02a46174e06d is started
    _I_I_2d1f02a46174e06d [01:14] current call classification: media=chat(105), service=default(200), segment=default(300)
09:10:04.748_I_I_2d1f02a46174e06d [09:06] >>>>>>>>>>>>start interp()
    _I_I_2d1f02a46174e06d [09:04] ASSIGN: __Return(SCRIPT) <- STRING[1,0]: ""
    _I_I_2d1f02a46174e06d [07:46] no error mode for this call
    _I_I_2d1f02a46174e06d [09:04] ASSIGN: __Return(SCRIPT) <- STRING[5,0]: ""
    _I_I_2d1f02a46174e06d [09:04] ASSIGN: __DBReturn(SCRIPT) <- STRING[6,0]: ""
    _I_I_2d1f02a46174e06d [09:04] ASSIGN: __DBStrReturn(SCRIPT) <- STRING[6,0]: ""
    _I_I_2d1f02a46174e06d [09:04] ASSIGN: __TargetVar(SCRIPT) <- STRING[6,0]: ""
    _I_I_2d1f02a46174e06d [09:04] ASSIGN: application_name(LOCAL) <- STRING[2,10]: "e_chat_srv"
09:10:04.748_T_I_2d1f02a46174e06d [14:1e] send to ts e_ixn[MultiMediaSwitch] TRequestPrivateService on dn asl_Chat inbound strategy: server e_chat_srv, type 59, service Chat, method Message
request to 65200(--) message RequestPrivateService
AttributeReferenceID 170
AttributeExtensions [254] 00 03 00 00..
'SWITCH' 'MultiMediaSwitch'
'CUSTOMER_ID' 'Environment'
'Envelope3rdServer'(list) 'Version' '1.0'
                          'AppName' 'e_chat_srv'
                          'Service' 'Chat'
                          'Method' 'Message'
                          'Parameters'(list) 'MessageText' 'Peter Parker will be with you shortly ...'
                                            'Nickname' 'Peter Parker'
AttributeUserData [712] 00 1b 00 00..
'IdentifyCreateContact' '3'
'EmailAddress' 'vuvanthan@ansv.vn'
'FirstName' 'Vu Van'
'ContactId' '00005aCMF6QG005J'
'LastName' 'Than'
'ChatServerHost' 'hcmors01'
'ChatServerPort' '4800'
'ChatServerDBID' '236'
'ChatServerAppName' 'e_chat_srv'
'Subject' 'Test Chat'
'IsHeld' 0
'InteractionId' '00006aCMQ678001B'
'TenantId' 1
'MediaType' 'chat'
'InteractionType' 'Inbound'
'InteractionSubtype' 'InboundNew'
'InteractionState' 1
'IsOnline' 1
'IsLocked' 0
'Queue' 'Chat inbound queue'
'SubmittedBy' 'e_chat_srv'
'ReceivedAt' '2017-06-23T02:10:01Z'
'SubmittedAt' '2017-06-23T02:10:01Z'
'PlacedInQueueAt' '2017-06-23T02:10:01Z'
'MovedToQueueAt' '2017-06-23T02:10:01Z'
'SubmitSeq' '43778632'
'PlaceInQueueSeq' '43778632'
AttributeConnID 2d1f02a46174e06d
AttributeThisDN 'asl_Chat inbound strategy'
AttributePrivateMsgID 3
..sent to hcmors01:4420(fd=668)
09:10:04.748_I_I_2d1f02a46174e06d [09:04] <<<<<<<<<<<<suspend interp(WAIT_EVENT_N), func:RequestServiceX timers:00010
received from 65200(e_ixn)hcmors01:4420(fd=) message EventPrivateInfo
AttributeTimeinuSecs 748580
AttributeTimeinSecs 1498183804 (09:10:04)
AttributeExtensions [115] 00 01 03 00..
'Envelope3rdServer'(list) 'Parameters'(list) 'OccuredAt' '2017-06-23T02:10:04Z'
                          'Service' 'Chat'
                          'Method' 'Message'
AttributeConnID 2d1f02a46174e06d
AttributeCustomerID 'Environment'
AttributeReferenceID 170
AttributePrivateMsgID 4
09:10:04.748_T_I_2d1f02a46174e06d [14:32] EventPrivateInfo is received for ts e_ixn[MultiMediaSwitch] (this dn=)
09:10:04.748_I_I_2d1f02a46174e06d [09:05] >>>>>>>>>>>>resume interp(0), func:RequestServiceX
09:10:04.748_I_I_2d1f02a46174e06d [07:07] HERE IS TARGETS
TARGETS: thanvv@e_rout_start_srv.A
    _M_I_2d1f02a46174e06d [17:0f] VQ 0000000001e47bc8 [at all 3 0/0 0] 1 Target(s), flag=940aa, guid: 0Environment|||1|m38|1|00|0|0|---|||||||01StatAgentsAvailable|00{}{}{}[]thanvv@e_rout_start_srv.A
09:10:04.748_M_I_2d1f02a46174e06d [13:01] current vq: 0000000001e94f30 id=109(), nVQ=1-1e0aff8, priority=0, time=1498183801.0
09:10:04.748_M_I_2d1f02a46174e06d [13:03] call (vq 0000000001e94f30, id=109, priority 0, time 1498183801.0) waits for VQ 0000000001e47bc8 (name="") now (14 1498183794.685)
09:10:04.748_M_I_2d1f02a46174e06d [17:0e] VQ 0000000001e47bc8 first ready call is 2d1f02a46174e04b (0011)
    _I_I_2d1f02a46174e06d [07:0a] HERE IS WAIT (-1 sec)
    _I_I_2d1f02a46174e06d [07:0a] HERE IS WAIT (15 sec)
09:10:04.748_B_I_2d1f02a46174e06d [07:0a] delay treatments for 0 msec
09:10:04.748_I_I_2d1f02a46174e06d [09:04] <<<<<<<<<<<<suspend interp(WAIT), func:Wait timers:11000
09:10:04.748_B_I_2d1f02a46174e06d [07:64] start chain of treatments
09:10:06.012_M_I_ [10:1d] PULSE (calls: 41(41)=40+1-0, trgts=2, first=3,0, time=1498183806, mem=26957,152,182,262[4825],0,0 - 2,0)
09:10:06.012_M_I_ [10:1d] vqs allocation pattern: static=(13 256 256) dynamic=(109 3/1024)
09:10:06.012_M_I_ [10:1d] check queue for SO <tinnt> type <Agent> stat <##state> (2 trgs 0 cpu)
    _M_I_0000000000000000 [10:20] try to route to ag "tinnt" (pl "2005", 1 ready DNs, 2 trgs): TRG 0000000001e1b538 VQ 0000000001e2c7f0 "" (20 calls), 0, 0, 940aa
    _M_I_ [10:20] VQ 0000000001e2c7f0 is ignored: other dn type(cfg)
    _M_I_0000000000000000 [10:20] try to route to ag "tinnt" (pl "2005", 1 ready DNs, 2 trgs): TRG 0000000001e39ca8 VQ 0000000001e39b20 "" (6 calls), 0, 0, 940aa
    _M_I_ [10:20] VQ 0000000001e39b20 is ignored: other dn type(cfg)
09:10:06.012_M_I_ [10:1d] check queue for SO <thanvv> type <Agent> stat <##state> (2 trgs 0 cpu)
    _M_I_0000000000000000 [10:20] try to route to ag "thanvv" (pl "7001", 1 ready DNs, 2 trgs): TRG 0000000001e47d50 VQ 0000000001e47bc8 "" (15 calls), 0, 0, 940aa
    _M_I_ [10:20] VQ 0000000001e47bc8 is ignored: other dn type(cfg)
    _M_I_0000000000000000 [10:20] try to route to ag "thanvv" (pl "7001", 1 ready DNs, 2 trgs): TRG 0000000001e39ca8 VQ 0000000001e39b20 "" (6 calls), 0, 0, 940aa
    _M_I_ [10:20] VQ 0000000001e39b20 is ignored: other dn type(cfg)
09:10:06.012_M_I_ [10:1d] PULSE over: 0 cpu 2 objects 4 trgs
09:10:08.009_M_I_ [10:1d] PULSE (calls: 41(41)=41+0-0, trgts=2, first=3,0, time=1498183808, mem=26957,152,182,262[4825],0,0 - 2,0)
09:10:08.009_M_I_ [10:1d] vqs allocation pattern: static=(13 256 256) dynamic=(109 3/1024)
09:10:08.009_M_I_ [10:1d] check queue for SO <tinnt> type <Agent> stat <##state> (2 trgs 0 cpu)
    _M_I_0000000000000000 [10:20] try to route to ag "tinnt" (pl "2005", 1 ready DNs, 2 trgs): TRG 0000000001e1b538 VQ 0000000001e2c7f0 "" (20 calls), 0, 0, 940aa
    _M_I_ [10:20] VQ 0000000001e2c7f0 is ignored: other dn type(cfg)
    _M_I_0000000000000000 [10:20] try to route to ag "tinnt" (pl "2005", 1 ready DNs, 2 trgs): TRG 0000000001e39ca8 VQ 0000000001e39b20 "" (6 calls), 0, 0, 940aa
    _M_I_ [10:20] VQ 0000000001e39b20 is ignored: other dn type(cfg)
09:10:08.009_M_I_ [10:1d] check queue for SO <thanvv> type <Agent> stat <##state> (2 trgs 0 cpu)
    _M_I_0000000000000000 [10:20] try to route to ag "thanvv" (pl "7001", 1 ready DNs, 2 trgs): TRG 0000000001e47d50 VQ 0000000001e47bc8 "" (15 calls), 0, 0, 940aa
    _M_I_ [10:20] VQ 0000000001e47bc8 is ignored: other dn type(cfg)
    _M_I_0000000000000000 [10:20] try to route to ag "thanvv" (pl "7001", 1 ready DNs, 2 trgs): TRG 0000000001e39ca8 VQ 0000000001e39b20 "" (6 calls), 0, 0, 940aa
    _M_I_ [10:20] VQ 0000000001e39b20 is ignored: other dn type(cfg)
09:10:08.009_M_I_ [10:1d] PULSE over: 0 cpu 2 objects 4 trgs
09:10:09.695_I_I_2d1f02a46174e04b [07:08] wait time is over
09:10:09.695_I_I_2d1f02a46174e04b [09:05] >>>>>>>>>>>>resume interp(0), func:Wait
09:10:09.695_M_I_2d1f02a46174e04b [17:11] VQ 0000000001e47bc8 first available call: 2d1f02a46174e04c, reason=(0)binding
09:10:09.695_M_I_2d1f02a46174e04b [13:03] call (vq 0000000001e2d6e8, id=75, priority 0) doesn't wait for VQ 0000000001e47bc8 (name="" stats=15010 2149 10 1498183809.695) now (0)
request to 65200(--) message RequestUpdateUserData
AttributeReferenceID 171
AttributeUserData [22] 00 01 01 00..
'PegRejected' 9085
AttributeConnID 2d1f02a46174e04b
AttributeThisDN 'asl_Chat inbound strategy'
..sent to hcmors01:4420(fd=668)
request to 65200(--) message RequestRouteCall
AttributeReferenceID 172
AttributeRouteType 7 (RouteTypeReject)
AttributeOtherDN ''
AttributeConnID 2d1f02a46174e04b
AttributeThisDN 'asl_Chat inbound strategy'
..sent to hcmors01:4420(fd=668)
    _I_I_2d1f02a46174e04b [09:04] OP_XCALL_NO_RESULT: func Exit returns INTERP_STOP(0), go to stop handling
    _I_I_2d1f02a46174e04b [01:0a] <<<<<<<<<<<<stop interp
09:10:09.695_I_I_2d1f02a46174e04b [01:08] call (75-0000000001e1efc0) deleting truly
2d1f02a46174e04b, 06/23/17@09:10:09.695, asl_Chat inbound strategy(e_ixn) -> @ [ 0 0 5 0], tms: 15010= (N)0 + (T)0 + (X)0 + (S)0 + (W)15010 + (F)0 + (R)0, trg:
    _I_I_2d1f02a46174e04b [09:0f] script 0000000001e0aff8 DS 0000000001e47050 [58] -s (7 3) (13 0000000001e47050)
    _I_I_ [25:01] script *0x1*Chat inbound strategy usage -1=14
09:10:09.695_I_I_2d1f02a46174e04c [07:08] wait time is over
09:10:09.695_I_I_2d1f02a46174e04c [09:05] >>>>>>>>>>>>resume interp(0), func:Wait
09:10:09.695_M_I_2d1f02a46174e04c [17:11] VQ 0000000001e47bc8 first available call: 2d1f02a46174e04d, reason=(0)binding
09:10:09.695_M_I_2d1f02a46174e04c [13:03] call (vq 0000000001e30098, id=76, priority 0) doesn't wait for VQ 0000000001e47bc8 (name="" stats=0 1934 10 1498183809.695) now (0)
request to 65200(--) message RequestUpdateUserData
AttributeReferenceID 173
AttributeUserData [22] 00 01 01 00..
'PegRejected' 9023
AttributeConnID 2d1f02a46174e04c
AttributeThisDN 'asl_Chat inbound strategy'
..sent to hcmors01:4420(fd=668)
request to 65200(--) message RequestRouteCall
AttributeReferenceID 174
AttributeRouteType 7 (RouteTypeReject)
AttributeOtherDN ''
AttributeConnID 2d1f02a46174e04c
AttributeThisDN 'asl_Chat inbound strategy'
..sent to hcmors01:4420(fd=668)
    _I_I_2d1f02a46174e04c [09:04] OP_XCALL_NO_RESULT: func Exit returns INTERP_STOP(0), go to stop handling
    _I_I_2d1f02a46174e04c [01:0a] <<<<<<<<<<<<stop interp
09:10:09.695_I_I_2d1f02a46174e04c [01:08] call (76-0000000001e54318) deleting truly
2d1f02a46174e04c, 06/23/17@09:10:09.695, asl_Chat inbound strategy(e_ixn) -> @ [ 0 0 5 0], tms: 15010= (N)0 + (T)0 + (X)0 + (S)0 + (W)15010 + (F)0 + (R)0, trg:
    _I_I_2d1f02a46174e04c [09:0f] script 0000000001e0aff8 DS 0000000001e6a3c0 [58] -s (7 3) (14 0000000001e6a3c0)
    _I_I_ [25:01] script *0x1*Chat inbound strategy usage -1=13
09:10:09.695_I_I_2d1f02a46174e04d [07:08] wait time is over
09:10:09.695_I_I_2d1f02a46174e04d [09:05] >>>>>>>>>>>>resume interp(0), func:Wait
09:10:09.695_M_I_2d1f02a46174e04d [17:11] VQ 0000000001e47bc8 first available call: 2d1f02a46174e04e, reason=(0)binding
09:10:09.695_M_I_2d1f02a46174e04d [13:03] call (vq 0000000001e34df0, id=77, priority 0) doesn't wait for VQ 0000000001e47bc8 (name="" stats=0 1740 10 1498183809.695) now (0)
request to 65200(--) message RequestUpdateUserData
AttributeReferenceID 175
AttributeUserData [22] 00 01 01 00..
'PegRejected' 8963
AttributeConnID 2d1f02a46174e04d
AttributeThisDN 'asl_Chat inbound strategy'
..sent to hcmors01:4420(fd=668)
request to 65200(--) message RequestRouteCall
AttributeReferenceID 176
AttributeRouteType 7 (RouteTypeReject)
AttributeOtherDN ''
AttributeConnID 2d1f02a46174e04d
AttributeThisDN 'asl_Chat inbound strategy'
..sent to hcmors01:4420(fd=668)
    _I_I_2d1f02a46174e04d [09:04] OP_XCALL_NO_RESULT: func Exit returns INTERP_STOP(0), go to stop handling
    _I_I_2d1f02a46174e04d [01:0a] <<<<<<<<<<<<stop interp
09:10:09.695_I_I_2d1f02a46174e04d [01:08] call (77-0000000001e2dcd0) deleting truly
2d1f02a46174e04d, 06/23/17@09:10:09.695, asl_Chat inbound strategy(e_ixn) -> @ [ 0 0 5 0], tms: 15010= (N)0 + (T)0 + (X)0 + (S)0 + (W)15010 + (F)0 + (R)0, trg:
    _I_I_2d1f02a46174e04d [09:0f] script 0000000001e0aff8 DS 0000000001e4a5b8 [58] -s (7 3) (15 0000000001e4a5b8)
    _I_I_ [25:01] script *0x1*Chat inbound strategy usage -1=12
09:10:09.695_I_I_2d1f02a46174e04e [07:08] wait time is over
09:10:09.695_I_I_2d1f02a46174e04e [09:05] >>>>>>>>>>>>resume interp(0), func:Wait
09:10:09.695_M_I_2d1f02a46174e04e [17:11] VQ 0000000001e47bc8 first available call: 2d1f02a46174e04f, reason=(0)binding
09:10:09.695_M_I_2d1f02a46174e04e [13:03] call (vq 0000000001e48aa0, id=78, priority 0) doesn't wait for VQ 0000000001e47bc8 (name="" stats=0 1566 10 1498183809.695) now (0)
request to 65200(--) message RequestUpdateUserData
AttributeReferenceID 177
AttributeUserData [22] 00 01 01 00..
'PegRejected' 8318
AttributeConnID 2d1f02a46174e04e
AttributeThisDN 'asl_Chat inbound strategy'
..sent to hcmors01:4420(fd=668)
request to 65200(--) message RequestRouteCall
AttributeReferenceID 178
AttributeRouteType 7 (RouteTypeReject)
AttributeOtherDN ''
AttributeConnID 2d1f02a46174e04e
AttributeThisDN 'asl_Chat inbound strategy'
..sent to hcmors01:4420(fd=668)
    _I_I_2d1f02a46174e04e [09:04] OP_XCALL_NO_RESULT: func Exit returns INTERP_STOP(0), go to stop handling
    _I_I_2d1f02a46174e04e [01:0a] <<<<<<<<<<<<stop interp
09:10:09.695_I_I_2d1f02a46174e04e [01:08] call (78-0000000001e30338) deleting truly
2d1f02a46174e04e, 06/23/17@09:10:09.695, asl_Chat inbound strategy(e_ixn) -> @ [ 0 0 5 0], tms: 15010= (N)0 + (T)0 + (X)0 + (S)0 + (W)15010 + (F)0 + (R)0, trg:
    _I_I_2d1f02a46174e04e [09:0f] script 0000000001e0aff8 DS 0000000001e4c8a8 [58] -d (7 3) (0 0000000001e4a5b8)
    _I_I_ [25:01] script *0x1*Chat inbound strategy usage -1=11
09:10:09.695_I_I_2d1f02a46174e04f [07:08] wait time is over
09:10:09.695_I_I_2d1f02a46174e04f [09:05] >>>>>>>>>>>>resume interp(0), func:Wait
09:10:09.695_M_I_2d1f02a46174e04f [17:11] VQ 0000000001e47bc8 first available call: 2d1f02a46174e05a, reason=(0)binding
09:10:09.695_M_I_2d1f02a46174e04f [13:03] call (vq 0000000001e4b0d8, id=79, priority 0) doesn't wait for VQ 0000000001e47bc8 (name="" stats=0 1409 10 1498183809.695) now (0)
request to 65200(--) message RequestUpdateUserData
AttributeReferenceID 179
AttributeUserData [22] 00 01 01 00..
'PegRejected' 8173
AttributeConnID 2d1f02a46174e04f
AttributeThisDN 'asl_Chat inbound strategy'
..sent to hcmors01:4420(fd=668)
request to 65200(--) message RequestRouteCall
AttributeReferenceID 180
AttributeRouteType 7 (RouteTypeReject)
AttributeOtherDN ''
AttributeConnID 2d1f02a46174e04f
AttributeThisDN 'asl_Chat inbound strategy'
..sent to hcmors01:4420(fd=668)
    _I_I_2d1f02a46174e04f [09:04] OP_XCALL_NO_RESULT: func Exit returns INTERP_STOP(0), go to stop handling
    _I_I_2d1f02a46174e04f [01:0a] <<<<<<<<<<<<stop interp
09:10:09.695_I_I_2d1f02a46174e04f [01:08] call (79-0000000001e329e8) deleting truly
2d1f02a46174e04f, 06/23/17@09:10:09.695, asl_Chat inbound strategy(e_ixn) -> @ [ 0 0 5 0], tms: 15010= (N)0 + (T)0 + (X)0 + (S)0 + (W)15010 + (F)0 + (R)0, trg:
    _I_I_2d1f02a46174e04f [09:0f] script 0000000001e0aff8 DS 0000000001e4ec10 [58] -s (7 3) (1 0000000001e4ec10)
    _I_I_ [25:01] script *0x1*Chat inbound strategy usage -1=10
09:10:09.695_I_I_2d1f02a46174e050 [07:08] wait time is over
09:10:09.695_I_I_2d1f02a46174e050 [09:05] >>>>>>>>>>>>resume interp(0), func:Wait
09:10:09.695_M_I_2d1f02a46174e050 [17:11] VQ 0000000001e2c7f0 first available call: 2d1f02a46174e051, reason=(0)binding
09:10:09.695_M_I_2d1f02a46174e050 [13:03] call (vq 0000000001e4d3c8, id=80, priority 0) doesn't wait for VQ 0000000001e2c7f0 (name="" stats=15010 1815 10 1498183809.695) now (0)
request to 65200(--) message RequestUpdateUserData
AttributeReferenceID 181
AttributeUserData [22] 00 01 01 00..
'PegRejected' 7450
AttributeConnID 2d1f02a46174e050
AttributeThisDN 'asl_Process ABC'
..sent to hcmors01:4420(fd=668)
request to 65200(--) message RequestRouteCall
AttributeReferenceID 182
AttributeRouteType 7 (RouteTypeReject)
AttributeOtherDN ''
AttributeConnID 2d1f02a46174e050
AttributeThisDN 'asl_Process ABC'
..sent to hcmors01:4420(fd=668)
    _I_I_2d1f02a46174e050 [09:04] OP_XCALL_NO_RESULT: func Exit returns INTERP_STOP(0), go to stop handling
    _I_I_2d1f02a46174e050 [01:0a] <<<<<<<<<<<<stop interp
09:10:09.695_I_I_2d1f02a46174e050 [01:08] call (80-0000000001e35090) deleting truly
2d1f02a46174e050, 06/23/17@09:10:09.695, asl_Process ABC(e_ixn) -> @ [ 0 0 3 0], tms: 15010= (N)0 + (T)0 + (X)0 + (S)0 + (W)15010 + (F)0 + (R)0, trg:
    _I_I_2d1f02a46174e050 [09:0f] script 0000000000dd9f60 DS 0000000001e87c88 [39] -s (5 2) (9 0000000001e87c88)
    _I_I_ [25:01] script *0x1*Process ABC usage -1=19
09:10:09.695_I_I_2d1f02a46174e051 [07:08] wait time is over
09:10:09.695_I_I_2d1f02a46174e051 [09:05] >>>>>>>>>>>>resume interp(0), func:Wait
09:10:09.695_M_I_2d1f02a46174e051 [17:11] VQ 0000000001e2c7f0 first available call: 2d1f02a46174e052, reason=(0)binding
09:10:09.695_M_I_2d1f02a46174e051 [13:03] call (vq 0000000001e4d0f8, id=81, priority 0) doesn't wait for VQ 0000000001e2c7f0 (name="" stats=0 1633 10 1498183809.695) now (0)
request to 65200(--) message RequestUpdateUserData
AttributeReferenceID 183
AttributeUserData [22] 00 01 01 00..
'PegRejected' 7450
AttributeConnID 2d1f02a46174e051
AttributeThisDN 'asl_Process ABC'
..sent to hcmors01:4420(fd=668)
request to 65200(--) message RequestRouteCall
AttributeReferenceID 184
AttributeRouteType 7 (RouteTypeReject)
AttributeOtherDN ''
AttributeConnID 2d1f02a46174e051
AttributeThisDN 'asl_Process ABC'
..sent to hcmors01:4420(fd=668)
    _I_I_2d1f02a46174e051 [09:04] OP_XCALL_NO_RESULT: func Exit returns INTERP_STOP(0), go to stop handling
    _I_I_2d1f02a46174e051 [01:0a] <<<<<<<<<<<<stop interp
09:10:09.695_I_I_2d1f02a46174e051 [01:08] call (81-0000000001e45a70) deleting truly
2d1f02a46174e051, 06/23/17@09:10:09.695, asl_Process ABC(e_ixn) -> @ [ 0 0 3 0], tms: 15010= (N)0 + (T)0 + (X)0 + (S)0 + (W)15010 + (F)0 + (R)0, trg:
    _I_I_2d1f02a46174e051 [09:0f] script 0000000000dd9f60 DS 0000000001e2b610 [39] -s (5 2) (10 0000000001e2b610)
    _I_I_ [25:01] script *0x1*Process ABC usage -1=18
09:10:09.695_I_I_2d1f02a46174e052 [07:08] wait time is over
09:10:09.695_I_I_2d1f02a46174e052 [09:05] >>>>>>>>>>>>resume interp(0), func:Wait
09:10:09.695_M_I_2d1f02a46174e052 [17:11] VQ 0000000001e2c7f0 first available call: 2d1f02a46174e053, reason=(0)binding
09:10:09.695_M_I_2d1f02a46174e052 [13:03] call (vq 0000000001e4f730, id=82, priority 0) doesn't wait for VQ 0000000001e2c7f0 (name="" stats=0 1469 10 1498183809.695) now (0)
request to 65200(--) message RequestUpdateUserData
AttributeReferenceID 185
AttributeUserData [22] 00 01 01 00..
'PegRejected' 7450
AttributeConnID 2d1f02a46174e052
AttributeThisDN 'asl_Process ABC'
..sent to hcmors01:4420(fd=668)
request to 65200(--) message RequestRouteCall
AttributeReferenceID 186
AttributeRouteType 7 (RouteTypeReject)
AttributeOtherDN ''
AttributeConnID 2d1f02a46174e052
AttributeThisDN 'asl_Process ABC'
..sent to hcmors01:4420(fd=668)
    _I_I_2d1f02a46174e052 [09:04] OP_XCALL_NO_RESULT: func Exit returns INTERP_STOP(0), go to stop handling
    _I_I_2d1f02a46174e052 [01:0a] <<<<<<<<<<<<stop interp
09:10:09.695_I_I_2d1f02a46174e052 [01:08] call (82-0000000001e49028) deleting truly
2d1f02a46174e052, 06/23/17@09:10:09.695, asl_Process ABC(e_ixn) -> @ [ 0 0 3 0], tms: 15010= (N)0 + (T)0 + (X)0 + (S)0 + (W)15010 + (F)0 + (R)0, trg:
    _I_I_2d1f02a46174e052 [09:0f] script 0000000000dd9f60 DS 0000000001e2f698 [39] -s (5 2) (11 0000000001e2f698)
    _I_I_ [25:01] script *0x1*Process ABC usage -1=17
09:10:09.695_I_I_2d1f02a46174e053 [07:08] wait time is over
09:10:09.695_I_I_2d1f02a46174e053 [09:05] >>>>>>>>>>>>resume interp(0), func:Wait
09:10:09.695_M_I_2d1f02a46174e053 [17:11] VQ 0000000001e2c7f0 first available call: 2d1f02a46174e054, reason=(0)binding
09:10:09.695_M_I_2d1f02a46174e053 [13:03] call (vq 0000000001e4f460, id=83, priority 0) doesn't wait for VQ 0000000001e2c7f0 (name="" stats=0 1322 10 1498183809.695) now (0)
request to 65200(--) message RequestUpdateUserData
AttributeReferenceID 187
AttributeUserData [22] 00 01 01 00..
'PegRejected' 7450
AttributeConnID 2d1f02a46174e053
AttributeThisDN 'asl_Process ABC'
..sent to hcmors01:4420(fd=668)
request to 65200(--) message RequestRouteCall
AttributeReferenceID 188
AttributeRouteType 7 (RouteTypeReject)
AttributeOtherDN ''
AttributeConnID 2d1f02a46174e053
AttributeThisDN 'asl_Process ABC'
..sent to hcmors01:4420(fd=668)
    _I_I_2d1f02a46174e053 [09:04] OP_XCALL_NO_RESULT: func Exit returns INTERP_STOP(0), go to stop handling
    _I_I_2d1f02a46174e053 [01:0a] <<<<<<<<<<<<stop interp
09:10:09.695_I_I_2d1f02a46174e053 [01:08] call (83-0000000001e4b318) deleting truly
2d1f02a46174e053, 06/23/17@09:10:09.695, asl_Process ABC(e_ixn) -> @ [ 0 0 3 0], tms: 15010= (N)0 + (T)0 + (X)0 + (S)0 + (W)15010 + (F)0 + (R)0, trg:
    _I_I_2d1f02a46174e053 [09:0f] script 0000000000dd9f60 DS 0000000001e31d48 [39] -s (5 2) (12 0000000001e31d48)
    _I_I_ [25:01] script *0x1*Process ABC usage -1=16
09:10:09.695_I_I_2d1f02a46174e054 [07:08] wait time is over
09:10:09.695_I_I_2d1f02a46174e054 [09:05] >>>>>>>>>>>>resume interp(0), func:Wait
09:10:09.695_M_I_2d1f02a46174e054 [17:11] VQ 0000000001e2c7f0 first available call: 2d1f02a46174e055, reason=(0)binding
09:10:09.695_M_I_2d1f02a46174e054 [13:03] call (vq 0000000001e51a30, id=84, priority 0) doesn't wait for VQ 0000000001e2c7f0 (name="" stats=0 1189 10 1498183809.695) now (0)
request to 65200(--) message RequestUpdateUserData
AttributeReferenceID 189
AttributeUserData [22] 00 01 01 00..
'PegRejected' 7136
AttributeConnID 2d1f02a46174e054
AttributeThisDN 'asl_Process ABC'
..sent to hcmors01:4420(fd=668)
request to 65200(--) message RequestRouteCall
AttributeReferenceID 190
AttributeRouteType 7 (RouteTypeReject)
AttributeOtherDN ''
AttributeConnID 2d1f02a46174e054
AttributeThisDN 'asl_Process ABC'
..sent to hcmors01:4420(fd=668)
    _I_I_2d1f02a46174e054 [09:04] OP_XCALL_NO_RESULT: func Exit returns INTERP_STOP(0), go to stop handling
    _I_I_2d1f02a46174e054 [01:0a] <<<<<<<<<<<<stop interp
09:10:09.695_I_I_2d1f02a46174e054 [01:08] call (84-0000000001e4d608) deleting truly
2d1f02a46174e054, 06/23/17@09:10:09.695, asl_Process ABC(e_ixn) -> @ [ 0 0 3 0], tms: 15010= (N)0 + (T)0 + (X)0 + (S)0 + (W)15010 + (F)0 + (R)0, trg:
    _I_I_2d1f02a46174e054 [09:0f] script 0000000000dd9f60 DS 0000000001e343f0 [39] -s (5 2) (13 0000000001e343f0)
    _I_I_ [25:01] script *0x1*Process ABC usage -1=15
09:10:09.695_I_I_2d1f02a46174e055 [07:08] wait time is over
09:10:09.695_I_I_2d1f02a46174e055 [09:05] >>>>>>>>>>>>resume interp(0), func:Wait
09:10:09.695_M_I_2d1f02a46174e055 [17:11] VQ 0000000001e2c7f0 first available call: 2d1f02a46174e056, reason=(0)binding
09:10:09.695_M_I_2d1f02a46174e055 [13:03] call (vq 0000000001e54078, id=85, priority 0) doesn't wait for VQ 0000000001e2c7f0 (name="" stats=0 1070 10 1498183809.695) now (0)
request to 65200(--) message RequestUpdateUserData
AttributeReferenceID 191
AttributeUserData [22] 00 01 01 00..
'PegRejected' 3748
AttributeConnID 2d1f02a46174e055
AttributeThisDN 'asl_Process ABC'
..sent to hcmors01:4420(fd=668)
request to 65200(--) message RequestRouteCall
AttributeReferenceID 192
AttributeRouteType 7 (RouteTypeReject)
AttributeOtherDN ''
AttributeConnID 2d1f02a46174e055
AttributeThisDN 'asl_Process ABC'
..sent to hcmors01:4420(fd=668)
    _I_I_2d1f02a46174e055 [09:04] OP_XCALL_NO_RESULT: func Exit returns INTERP_STOP(0), go to stop handling
    _I_I_2d1f02a46174e055 [01:0a] <<<<<<<<<<<<stop interp
09:10:09.695_I_I_2d1f02a46174e055 [01:08] call (85-0000000001e4f970) deleting truly
2d1f02a46174e055, 06/23/17@09:10:09.695, asl_Process ABC(e_ixn) -> @ [ 0 0 3 0], tms: 15010= (N)0 + (T)0 + (X)0 + (S)0 + (W)15010 + (F)0 + (R)0, trg:
    _I_I_2d1f02a46174e055 [09:0f] script 0000000000dd9f60 DS 0000000001e36aa0 [39] -s (5 2) (14 0000000001e36aa0)
    _I_I_ [25:01] script *0x1*Process ABC usage -1=14
09:10:09.695_I_I_2d1f02a46174e056 [07:08] wait time is over
09:10:09.695_I_I_2d1f02a46174e056 [09:05] >>>>>>>>>>>>resume interp(0), func:Wait
09:10:09.695_M_I_2d1f02a46174e056 [17:11] VQ 0000000001e2c7f0 first available call: 2d1f02a46174e057, reason=(0)binding
09:10:09.695_M_I_2d1f02a46174e056 [13:03] call (vq 0000000001e51760, id=86, priority 0) doesn't wait for VQ 0000000001e2c7f0 (name="" stats=0 963 10 1498183809.695) now (0)
request to 65200(--) message RequestUpdateUserData
AttributeReferenceID 193
AttributeUserData [22] 00 01 01 00..
'PegRejected' 3736
AttributeConnID 2d1f02a46174e056
AttributeThisDN 'asl_Process ABC'
..sent to hcmors01:4420(fd=668)
request to 65200(--) message RequestRouteCall
AttributeReferenceID 194
AttributeRouteType 7 (RouteTypeReject)
AttributeOtherDN ''
AttributeConnID 2d1f02a46174e056
AttributeThisDN 'asl_Process ABC'
..sent to hcmors01:4420(fd=668)
    _I_I_2d1f02a46174e056 [09:04] OP_XCALL_NO_RESULT: func Exit returns INTERP_STOP(0), go to stop handling
    _I_I_2d1f02a46174e056 [01:0a] <<<<<<<<<<<<stop interp
09:10:09.695_I_I_2d1f02a46174e056 [01:08] call (86-0000000001e51c70) deleting truly
2d1f02a46174e056, 06/23/17@09:10:09.695, asl_Process ABC(e_ixn) -> @ [ 0 0 3 0], tms: 15010= (N)0 + (T)0 + (X)0 + (S)0 + (W)15010 + (F)0 + (R)0, trg:
    _I_I_2d1f02a46174e056 [09:0f] script 0000000000dd9f60 DS 0000000001e53678 [39] -s (5 2) (15 0000000001e53678)
    _I_I_ [25:01] script *0x1*Process ABC usage -1=13
09:10:09.695_I_I_2d1f02a46174e057 [07:08] wait time is over
09:10:09.695_I_I_2d1f02a46174e057 [09:05] >>>>>>>>>>>>resume interp(0), func:Wait
09:10:09.695_M_I_2d1f02a46174e057 [17:11] VQ 0000000001e2c7f0 first available call: 2d1f02a46174e058, reason=(0)binding
09:10:09.695_M_I_2d1f02a46174e057 [13:03] call (vq 0000000001e58dd8, id=87, priority 0) doesn't wait for VQ 0000000001e2c7f0 (name="" stats=0 866 10 1498183809.695) now (0)
request to 65200(--) message RequestUpdateUserData
AttributeReferenceID 195
AttributeUserData [22] 00 01 01 00..
'PegRejected' 3650
AttributeConnID 2d1f02a46174e057
AttributeThisDN 'asl_Process ABC'
..sent to hcmors01:4420(fd=668)
request to 65200(--) message RequestRouteCall
AttributeReferenceID 196
AttributeRouteType 7 (RouteTypeReject)
AttributeOtherDN ''
AttributeConnID 2d1f02a46174e057
AttributeThisDN 'asl_Process ABC'
..sent to hcmors01:4420(fd=668)
    _I_I_2d1f02a46174e057 [09:04] OP_XCALL_NO_RESULT: func Exit returns INTERP_STOP(0), go to stop handling
    _I_I_2d1f02a46174e057 [01:0a] <<<<<<<<<<<<stop interp
09:10:09.695_I_I_2d1f02a46174e057 [01:08] call (87-0000000001e569c8) deleting truly
2d1f02a46174e057, 06/23/17@09:10:09.695, asl_Process ABC(e_ixn) -> @ [ 0 0 3 0], tms: 15010= (N)0 + (T)0 + (X)0 + (S)0 + (W)15010 + (F)0 + (R)0, trg:
    _I_I_2d1f02a46174e057 [09:0f] script 0000000000dd9f60 DS 0000000001e55d28 [39] -d (5 2) (0 0000000001e53678)
    _I_I_ [25:01] script *0x1*Process ABC usage -1=12
09:10:09.695_I_I_2d1f02a46174e058 [07:08] wait time is over
09:10:09.695_I_I_2d1f02a46174e058 [09:05] >>>>>>>>>>>>resume interp(0), func:Wait
09:10:09.695_M_I_2d1f02a46174e058 [17:11] VQ 0000000001e2c7f0 first available call: 2d1f02a46174e059, reason=(0)binding
09:10:09.695_M_I_2d1f02a46174e058 [13:03] call (vq 0000000001e56728, id=88, priority 0) doesn't wait for VQ 0000000001e2c7f0 (name="" stats=0 779 10 1498183809.695) now (0)
request to 65200(--) message RequestUpdateUserData
AttributeReferenceID 197
AttributeUserData [22] 00 01 01 00..
'PegRejected' 3649
AttributeConnID 2d1f02a46174e058
AttributeThisDN 'asl_Process ABC'
..sent to hcmors01:4420(fd=668)
request to 65200(--) message RequestRouteCall
AttributeReferenceID 198
AttributeRouteType 7 (RouteTypeReject)
AttributeOtherDN ''
AttributeConnID 2d1f02a46174e058
AttributeThisDN 'asl_Process ABC'
..sent to hcmors01:4420(fd=668)
    _I_I_2d1f02a46174e058 [09:04] OP_XCALL_NO_RESULT: func Exit returns INTERP_STOP(0), go to stop handling
    _I_I_2d1f02a46174e058 [01:0a] <<<<<<<<<<<<stop interp
09:10:09.695_I_I_2d1f02a46174e058 [01:08] call (88-0000000001e59078) deleting truly
2d1f02a46174e058, 06/23/17@09:10:09.695, asl_Process ABC(e_ixn) -> @ [ 0 0 3 0], tms: 15010= (N)0 + (T)0 + (X)0 + (S)0 + (W)15010 + (F)0 + (R)0, trg:
    _I_I_2d1f02a46174e058 [09:0f] script 0000000000dd9f60 DS 0000000001e5aa88 [39] -s (5 2) (1 0000000001e5aa88)
    _I_I_ [25:01] script *0x1*Process ABC usage -1=11
09:10:09.695_I_I_2d1f02a46174e059 [07:08] wait time is over
09:10:09.695_I_I_2d1f02a46174e059 [09:05] >>>>>>>>>>>>resume interp(0), func:Wait
09:10:09.695_M_I_2d1f02a46174e059 [17:11] VQ 0000000001e2c7f0 first available call: 2d1f02a46174e05f, reason=(0)binding
09:10:09.695_M_I_2d1f02a46174e059 [13:03] call (vq 0000000001e68bf0, id=89, priority 0) doesn't wait for VQ 0000000001e2c7f0 (name="" stats=0 701 10 1498183809.695) now (0)
request to 65200(--) message RequestUpdateUserData
AttributeReferenceID 199
AttributeUserData [22] 00 01 01 00..
'PegRejected' 3647
AttributeConnID 2d1f02a46174e059
AttributeThisDN 'asl_Process ABC'
..sent to hcmors01:4420(fd=668)
request to 65200(--) message RequestRouteCall
AttributeReferenceID 200
AttributeRouteType 7 (RouteTypeReject)
AttributeOtherDN ''
AttributeConnID 2d1f02a46174e059
AttributeThisDN 'asl_Process ABC'
..sent to hcmors01:4420(fd=668)
    _I_I_2d1f02a46174e059 [09:04] OP_XCALL_NO_RESULT: func Exit returns INTERP_STOP(0), go to stop handling
    _I_I_2d1f02a46174e059 [01:0a] <<<<<<<<<<<<stop interp
09:10:09.695_I_I_2d1f02a46174e059 [01:08] call (89-0000000001e5b728) deleting truly
2d1f02a46174e059, 06/23/17@09:10:09.695, asl_Process ABC(e_ixn) -> @ [ 0 0 3 0], tms: 15010= (N)0 + (T)0 + (X)0 + (S)0 + (W)15010 + (F)0 + (R)0, trg:
    _I_I_2d1f02a46174e059 [09:0f] script 0000000000dd9f60 DS 0000000001e5d138 [39] -s (5 2) (2 0000000001e5d138)
    _I_I_ [25:01] script *0x1*Process ABC usage -1=10
09:10:10.007_M_I_ [10:1d] PULSE (calls: 26(26)=41+0-15, trgts=2, first=3,0, time=1498183810, mem=24712,152,122,227[4790],0,0 - 2,0)
09:10:10.007_M_I_ [10:1d] vqs allocation pattern: static=(13 256 256) dynamic=(109 3/1024)
09:10:10.007_M_I_ [10:1d] check queue for SO <tinnt> type <Agent> stat <##state> (2 trgs 0 cpu)
    _M_I_0000000000000000 [10:20] try to route to ag "tinnt" (pl "2005", 1 ready DNs, 2 trgs): TRG 0000000001e1b538 VQ 0000000001e2c7f0 "" (10 calls), 0, 0, 940aa
    _M_I_ [10:20] VQ 0000000001e2c7f0 is ignored: other dn type(cfg)
    _M_I_0000000000000000 [10:20] try to route to ag "tinnt" (pl "2005", 1 ready DNs, 2 trgs): TRG 0000000001e39ca8 VQ 0000000001e39b20 "" (6 calls), 0, 0, 940aa
    _M_I_ [10:20] VQ 0000000001e39b20 is ignored: other dn type(cfg)
09:10:10.007_M_I_ [10:1d] check queue for SO <thanvv> type <Agent> stat <##state> (2 trgs 0 cpu)
    _M_I_0000000000000000 [10:20] try to route to ag "thanvv" (pl "7001", 1 ready DNs, 2 trgs): TRG 0000000001e47d50 VQ 0000000001e47bc8 "" (10 calls), 0, 0, 940aa
    _M_I_ [10:20] VQ 0000000001e47bc8 is ignored: other dn type(cfg)
    _M_I_0000000000000000 [10:20] try to route to ag "thanvv" (pl "7001", 1 ready DNs, 2 trgs): TRG 0000000001e39ca8 VQ 0000000001e39b20 "" (6 calls), 0, 0, 940aa
    _M_I_ [10:20] VQ 0000000001e39b20 is ignored: other dn type(cfg)
09:10:10.007_M_I_ [10:1d] PULSE over: 0 cpu 2 objects 4 trgs
09:10:11.708_I_I_2d1f02a46174e05a [07:08] wait time is over
09:10:11.708_I_I_2d1f02a46174e05a [09:05] >>>>>>>>>>>>resume interp(0), func:Wait
09:10:11.708_M_I_2d1f02a46174e05a [17:11] VQ 0000000001e47bc8 first available call: 2d1f02a46174e05b, reason=(0)binding
09:10:11.708_M_I_2d1f02a46174e05a [13:03] call (vq 0000000001e6aee0, id=90, priority 0) doesn't wait for VQ 0000000001e47bc8 (name="" stats=2013 1469 10 1498183811.708) now (0)
request to 65200(--) message RequestUpdateUserData
AttributeReferenceID 201
AttributeUserData [22] 00 01 01 00..
'PegRejected' 4635
AttributeConnID 2d1f02a46174e05a
AttributeThisDN 'asl_Chat inbound strategy'
..sent to hcmors01:4420(fd=668)
request to 65200(--) message RequestRouteCall
AttributeReferenceID 202
AttributeRouteType 7 (RouteTypeReject)
AttributeOtherDN ''
AttributeConnID 2d1f02a46174e05a
AttributeThisDN 'asl_Chat inbound strategy'
..sent to hcmors01:4420(fd=668)
    _I_I_2d1f02a46174e05a [09:04] OP_XCALL_NO_RESULT: func Exit returns INTERP_STOP(0), go to stop handling
    _I_I_2d1f02a46174e05a [01:0a] <<<<<<<<<<<<stop interp
09:10:11.708_I_I_2d1f02a46174e05a [01:08] call (90-0000000001e66b18) deleting truly
2d1f02a46174e05a, 06/23/17@09:10:11.708, asl_Chat inbound strategy(e_ixn) -> @ [ 0 0 5 0], tms: 15010= (N)0 + (T)0 + (X)0 + (S)0 + (W)15010 + (F)0 + (R)0, trg:
    _I_I_2d1f02a46174e05a [09:0f] script 0000000001e0aff8 DS 0000000001e50f10 [58] -s (7 3) (2 0000000001e50f10)
    _I_I_ [25:01] script *0x1*Chat inbound strategy usage -1=9
09:10:11.708_I_I_2d1f02a46174e05b [07:08] wait time is over
09:10:11.708_I_I_2d1f02a46174e05b [09:05] >>>>>>>>>>>>resume interp(0), func:Wait
09:10:11.708_M_I_2d1f02a46174e05b [17:11] VQ 0000000001e47bc8 first available call: 2d1f02a46174e05c, reason=(0)binding
09:10:11.708_M_I_2d1f02a46174e05b [13:03] call (vq 0000000001e6ac10, id=91, priority 0) doesn't wait for VQ 0000000001e47bc8 (name="" stats=0 1322 10 1498183811.708) now (0)
request to 65200(--) message RequestUpdateUserData
AttributeReferenceID 203
AttributeUserData [22] 00 01 01 00..
'PegRejected' 4054
AttributeConnID 2d1f02a46174e05b
AttributeThisDN 'asl_Chat inbound strategy'
..sent to hcmors01:4420(fd=668)
request to 65200(--) message RequestRouteCall
AttributeReferenceID 204
AttributeRouteType 7 (RouteTypeReject)
AttributeOtherDN ''
AttributeConnID 2d1f02a46174e05b
AttributeThisDN 'asl_Chat inbound strategy'
..sent to hcmors01:4420(fd=668)
    _I_I_2d1f02a46174e05b [09:04] OP_XCALL_NO_RESULT: func Exit returns INTERP_STOP(0), go to stop handling
    _I_I_2d1f02a46174e05b [01:0a] <<<<<<<<<<<<stop interp
09:10:11.708_I_I_2d1f02a46174e05b [01:08] call (91-0000000001e68e30) deleting truly
2d1f02a46174e05b, 06/23/17@09:10:11.708, asl_Chat inbound strategy(e_ixn) -> @ [ 0 0 5 0], tms: 15010= (N)0 + (T)0 + (X)0 + (S)0 + (W)15010 + (F)0 + (R)0, trg:
    _I_I_2d1f02a46174e05b [09:0f] script 0000000001e0aff8 DS 0000000001e680d0 [58] -s (7 3) (3 0000000001e680d0)
    _I_I_ [25:01] script *0x1*Chat inbound strategy usage -1=8
09:10:11.708_I_I_2d1f02a46174e05d [07:08] wait time is over
09:10:11.708_I_I_2d1f02a46174e05d [09:05] >>>>>>>>>>>>resume interp(0), func:Wait
09:10:11.708_M_I_2d1f02a46174e05d [13:03] call (vq 0000000001e71588, id=92, priority 0) doesn't wait for VQ 0000000001e47bc8 (name="" stats=0 1189 10 1498183811.708) now (1)
request to 65200(--) message RequestUpdateUserData
AttributeReferenceID 205
AttributeUserData [22] 00 01 01 00..
'PegRejected' 3416
AttributeConnID 2d1f02a46174e05d
AttributeThisDN 'asl_Chat inbound strategy'
..sent to hcmors01:4420(fd=668)
request to 65200(--) message RequestRouteCall
AttributeReferenceID 206
AttributeRouteType 7 (RouteTypeReject)
AttributeOtherDN ''
AttributeConnID 2d1f02a46174e05d
AttributeThisDN 'asl_Chat inbound strategy'
..sent to hcmors01:4420(fd=668)
    _I_I_2d1f02a46174e05d [09:04] OP_XCALL_NO_RESULT: func Exit returns INTERP_STOP(0), go to stop handling
    _I_I_2d1f02a46174e05d [01:0a] <<<<<<<<<<<<stop interp
09:10:11.708_I_I_2d1f02a46174e05d [01:08] call (93-0000000001e6f480) deleting truly
2d1f02a46174e05d, 06/23/17@09:10:11.708, asl_Chat inbound strategy(e_ixn) -> @ [ 0 0 5 0], tms: 15010= (N)0 + (T)0 + (X)0 + (S)0 + (W)15010 + (F)0 + (R)0, trg:
    _I_I_2d1f02a46174e05d [09:0f] script 0000000001e0aff8 DS 0000000001e70a68 [58] -s (7 3) (4 0000000001e70a68)
    _I_I_ [25:01] script *0x1*Chat inbound strategy usage -1=7
09:10:11.708_I_I_2d1f02a46174e05e [07:08] wait time is over
09:10:11.708_I_I_2d1f02a46174e05e [09:05] >>>>>>>>>>>>resume interp(0), func:Wait
09:10:11.708_M_I_2d1f02a46174e05e [13:03] call (vq 0000000001e73bd0, id=93, priority 0) doesn't wait for VQ 0000000001e47bc8 (name="" stats=0 1070 10 1498183811.708) now (1)
request to 65200(--) message RequestUpdateUserData
AttributeReferenceID 207
AttributeUserData [22] 00 01 01 00..
'PegRejected' 3391
AttributeConnID 2d1f02a46174e05e
AttributeThisDN 'asl_Chat inbound strategy'
..sent to hcmors01:4420(fd=668)
request to 65200(--) message RequestRouteCall
AttributeReferenceID 208
AttributeRouteType 7 (RouteTypeReject)
AttributeOtherDN ''
AttributeConnID 2d1f02a46174e05e
AttributeThisDN 'asl_Chat inbound strategy'
..sent to hcmors01:4420(fd=668)
    _I_I_2d1f02a46174e05e [09:04] OP_XCALL_NO_RESULT: func Exit returns INTERP_STOP(0), go to stop handling
    _I_I_2d1f02a46174e05e [01:0a] <<<<<<<<<<<<stop interp
09:10:11.708_I_I_2d1f02a46174e05e [01:08] call (94-0000000001e717c8) deleting truly
2d1f02a46174e05e, 06/23/17@09:10:11.708, asl_Chat inbound strategy(e_ixn) -> @ [ 0 0 5 0], tms: 15010= (N)0 + (T)0 + (X)0 + (S)0 + (W)15010 + (F)0 + (R)0, trg:
    _I_I_2d1f02a46174e05e [09:0f] script 0000000001e0aff8 DS 0000000001e7ef30 [58] -s (7 3) (5 0000000001e7ef30)
    _I_I_ [25:01] script *0x1*Chat inbound strategy usage -1=6
09:10:11.708_I_I_2d1f02a46174e05f [07:08] wait time is over
09:10:11.708_I_I_2d1f02a46174e05f [09:05] >>>>>>>>>>>>resume interp(0), func:Wait
09:10:11.708_M_I_2d1f02a46174e05f [17:11] VQ 0000000001e2c7f0 first available call: 2d1f02a46174e060, reason=(0)binding
09:10:11.708_M_I_2d1f02a46174e05f [13:03] call (vq 0000000001e76280, id=94, priority 0) doesn't wait for VQ 0000000001e2c7f0 (name="" stats=2013 832 10 1498183811.708) now (0)
request to 65200(--) message RequestUpdateUserData
AttributeReferenceID 209
AttributeUserData [22] 00 01 01 00..
'PegRejected' 3646
AttributeConnID 2d1f02a46174e05f
AttributeThisDN 'asl_Process ABC'
..sent to hcmors01:4420(fd=668)
request to 65200(--) message RequestRouteCall
AttributeReferenceID 210
AttributeRouteType 7 (RouteTypeReject)
AttributeOtherDN ''
AttributeConnID 2d1f02a46174e05f
AttributeThisDN 'asl_Process ABC'
..sent to hcmors01:4420(fd=668)
    _I_I_2d1f02a46174e05f [09:04] OP_XCALL_NO_RESULT: func Exit returns INTERP_STOP(0), go to stop handling
    _I_I_2d1f02a46174e05f [01:0a] <<<<<<<<<<<<stop interp
09:10:11.708_I_I_2d1f02a46174e05f [01:08] call (95-0000000001e73e70) deleting truly
2d1f02a46174e05f, 06/23/17@09:10:11.708, asl_Process ABC(e_ixn) -> @ [ 0 0 3 0], tms: 15010= (N)0 + (T)0 + (X)0 + (S)0 + (W)15010 + (F)0 + (R)0, trg:
    _I_I_2d1f02a46174e05f [09:0f] script 0000000000dd9f60 DS 0000000001e731d0 [39] -s (5 2) (3 0000000001e731d0)
    _I_I_ [25:01] script *0x1*Process ABC usage -1=9
09:10:11.708_I_I_2d1f02a46174e060 [07:08] wait time is over
09:10:11.708_I_I_2d1f02a46174e060 [09:05] >>>>>>>>>>>>resume interp(0), func:Wait
09:10:11.708_M_I_2d1f02a46174e060 [17:11] VQ 0000000001e2c7f0 first available call: 2d1f02a46174e061, reason=(0)binding
09:10:11.708_M_I_2d1f02a46174e060 [13:03] call (vq 0000000001e78938, id=95, priority 0) doesn't wait for VQ 0000000001e2c7f0 (name="" stats=0 748 10 1498183811.708) now (0)
request to 65200(--) message RequestUpdateUserData
AttributeReferenceID 211
AttributeUserData [22] 00 01 01 00..
'PegRejected' 3642
AttributeConnID 2d1f02a46174e060
AttributeThisDN 'asl_Process ABC'
..sent to hcmors01:4420(fd=668)
request to 65200(--) message RequestRouteCall
AttributeReferenceID 212
AttributeRouteType 7 (RouteTypeReject)
AttributeOtherDN ''
AttributeConnID 2d1f02a46174e060
AttributeThisDN 'asl_Process ABC'
..sent to hcmors01:4420(fd=668)
    _I_I_2d1f02a46174e060 [09:04] OP_XCALL_NO_RESULT: func Exit returns INTERP_STOP(0), go to stop handling
    _I_I_2d1f02a46174e060 [01:0a] <<<<<<<<<<<<stop interp
09:10:11.708_I_I_2d1f02a46174e060 [01:08] call (96-0000000001e76520) deleting truly
2d1f02a46174e060, 06/23/17@09:10:11.708, asl_Process ABC(e_ixn) -> @ [ 0 0 3 0], tms: 15010= (N)0 + (T)0 + (X)0 + (S)0 + (W)15010 + (F)0 + (R)0, trg:
    _I_I_2d1f02a46174e060 [09:0f] script 0000000000dd9f60 DS 0000000001e75880 [39] -s (5 2) (4 0000000001e75880)
    _I_I_ [25:01] script *0x1*Process ABC usage -1=8
09:10:11.708_I_I_2d1f02a46174e061 [07:08] wait time is over
09:10:11.708_I_I_2d1f02a46174e061 [09:05] >>>>>>>>>>>>resume interp(0), func:Wait
09:10:11.708_M_I_2d1f02a46174e061 [17:11] VQ 0000000001e2c7f0 first available call: 2d1f02a46174e062, reason=(0)binding
09:10:11.708_M_I_2d1f02a46174e061 [13:03] call (vq 0000000001e7afe8, id=96, priority 0) doesn't wait for VQ 0000000001e2c7f0 (name="" stats=0 673 10 1498183811.708) now (0)
request to 65200(--) message RequestUpdateUserData

[terry]

Agent thanvv has no available chat (either not logged in on chat at all or have 0 capacity for it).

[Peter Parker]

Hi terry,

I see that URS read strategy successfully.

09:12:39.732 Int 22000 "Offline chat interaction is to be processed"
09:12:39.732_I_I_2d1f02a46174e1ad [07:07] HERE IS TARGETS
TARGETS: thanvv@e_rout_start_srv.A
    _M_I_2d1f02a46174e1ad [17:0f] VQ 0000000001e47bc8 [at all 3 0/0 0] 1 Target(s), flag=940aa, guid: 0Environment|||1|m38|1|00|0|0|---|||||||01StatAgentsAvailable|00{}{}{}[]thanvv@e_rout_start_srv.A
09:12:39.732_M_I_2d1f02a46174e1ad [13:01] current vq: 0000000001e5b488 id=429(), nVQ=1-1e0aff8, priority=0, time=1498106627.0
09:12:39.732_M_I_2d1f02a46174e1ad [13:03] call (vq 0000000001e5b488, id=429, priority 0, time 1498106627.0) waits for VQ 0000000001e47bc8 (name="") now (6 1498183956.626)
09:12:39.732_M_I_2d1f02a46174e1ad [17:0e] VQ 0000000001e47bc8 first ready call is 2d1f02a46174e198 (0011)
    _I_I_2d1f02a46174e1ad [07:0a] HERE IS WAIT (-1 sec)
    _I_I_2d1f02a46174e1ad [07:0a] HERE IS WAIT (15 sec)
09:12:39.732_B_I_2d1f02a46174e1ad [07:0a] delay treatments for 0 msec
09:12:39.732_I_I_2d1f02a46174e1ad [09:04] <<<<<<<<<<<<suspend interp(WAIT), func:Wait timers:11000
received from 65200(e_ixn)hcmors01:4420(fd=) message EventRouteRequest
AttributeTimeinuSecs 732454
AttributeTimeinSecs 1498183959 (09:12:39)
AttributeExtensions [39] 00 01 00 00..
'STRATEGY_ID' 'Chat inbound strategy'
AttributeUserData [712] 00 1c 00 00..
'IdentifyCreateContact' '3'
'EmailAddress' ''
'FirstName' 'Peter'
'ContactId' '00005aCMF6QG006S'
'LastName' 'Parker'
'ChatServerHost' 'hcmors01'
'ChatServerPort' '4800'
'ChatServerDBID' '236'
'ChatServerAppName' 'e_chat_srv'
'Subject' 'Hello'
'PegRejected' 3443
'IsHeld' 0
'InteractionId' '00005aCMF6QG006R'
'TenantId' 1
'MediaType' 'chat'
'InteractionType' 'Inbound'
'InteractionSubtype' 'InboundNew'
'InteractionState' 1
'IsOnline' 1
'IsLocked' 0
'Queue' 'Chat inbound queue'
'SubmittedBy' 'e_chat_srv'
'ReceivedAt' '2017-06-22T07:52:30Z'
'SubmittedAt' '2017-06-22T07:52:30Z'
'PlacedInQueueAt' '2017-06-23T02:12:36Z'
'MovedToQueueAt' '2017-06-22T07:52:30Z'
'SubmitSeq' '37941497'
'PlaceInQueueSeq' '43780604'
AttributeThisQueue 'asl_Chat inbound strategy'
AttributeThisDN 'asl_Chat inbound strategy'
AttributeCallType 0
AttributeCallID 16173
AttributeMediaType 4294967295
AttributeConnID 2d1f02a46174e1ae
AttributeCustomerID 'Environment'
09:12:39.732_T_I_0000000000000000 [14:0c] EventRouteRequest is received for ts e_ixn[MultiMediaSwitch] (this dn=asl_Chat inbound strategy)
    _T_I_2d1f02a46174e1ae [01:11] connid 2d1f02a46174e1ae is bound to the call 430-0000000001e1efc0
09:12:39.732_I_I_2d1f02a46174e1ae [01:01] call (430-0000000001e1efc0) for Environment created (del=0 ts=0,12,0)
    _T_I_2d1f02a46174e1ae [14:09] add DN e_ixn asl_Chat inbound strategy <asl_Chat inbound strategy@MultiMediaSwitch> (CDN 71 2d1f02a46174e1ae 0000000000000000) to the call 430-0000000001e1efc0 truly:22
    _T_I_2d1f02a46174e1ae [14:08] check delayed proc
    _T_I_2d1f02a46174e1ae [14:43] call data updated
    _I_I_2d1f02a46174e1ae [09:0e] script 0000000001e0aff8 DS 0000000001e4c8a8 [58] +r (7 3) (2 0000000001e50f10)
    _I_I_ [25:01] script *0x1*Chat inbound strategy usage +1=9
    _I_I_2d1f02a46174e1ae [14:33] strategy: *0x1*Chat inbound strategy (3089101847) is attached to the call
09:12:39.732 Int 20001 interaction 2d1f02a46174e1ae is started
    _I_I_2d1f02a46174e1ae [01:14] current call classification: media=chat(105), service=default(200), segment=default(300)
09:12:39.732_I_I_2d1f02a46174e1ae [09:06] >>>>>>>>>>>>start interp()
    _I_I_2d1f02a46174e1ae [09:04] ASSIGN: __Return(SCRIPT) <- STRING[1,0]: ""
    _I_I_2d1f02a46174e1ae [07:46] no error mode for this call
    _I_I_2d1f02a46174e1ae [09:04] ASSIGN: __Return(SCRIPT) <- STRING[5,0]: ""
    _I_I_2d1f02a46174e1ae [09:04] ASSIGN: __DBReturn(SCRIPT) <- STRING[6,0]: ""
    _I_I_2d1f02a46174e1ae [09:04] ASSIGN: __DBStrReturn(SCRIPT) <- STRING[6,0]: ""
    _I_I_2d1f02a46174e1ae [09:04] ASSIGN: __TargetVar(SCRIPT) <- STRING[6,0]: ""
    _I_I_2d1f02a46174e1ae [09:04] ASSIGN: application_name(LOCAL) <- STRING[2,10]: "e_chat_srv"
09:12:39.732_T_I_2d1f02a46174e1ae [14:1e] send to ts e_ixn[MultiMediaSwitch] TRequestPrivateService on dn asl_Chat inb

From log of Interaction as below:

2017-06-23T09:09:13.108 Trc 23043 Agent 'thanvv' at place '7001' already has state 'ready' for media specified in request 'RequestCancelNotReadyForMedia' from client 7:1884, reference id: 32
2017-06-23T09:09:13.108 Trc 26019 Sending message 'EventError' ('126') to client '7001' - Agent application:7:1884 through proxy 'WorkspaceDesktop_thanvv' - Proxy:6:1884, message attributes:
attr_ref_id [int] = 32
attr_prxy_client_id [int] = 7
attr_error_code [int] = 73
attr_error_desc [str] = "Already in state 'ready' for specified media"

2017-06-23T09:09:13.139 Trc 26015 Received message 'EventCurrentAgentStatus' ('155') from client 'StatServer[hcmfrw01:2020:e_rout_start_srv]' - Reporting engine:1:1788, message attributes:
attr_ref_id [int] = 100
attr_tenant_id [int] = 1
attr_place_id [str] = "7001"
attr_agent_id [str] = "thanvv"
attr_donot_disturb [int] = 0
attr_media_state_list [list, size (unpacked)=408] =
  'email' [int] = 1
  'chat' [int] = 1
  'outboundpreview' [int] = 1
  'voice' [int] = 1
  'smssession' [int] = 1
  'sms' [int] = 1
attr_media_capacity_list [list, size (unpacked)=408] =
  'email' [int] = 0
  'chat' [int] = 0
  'outboundpreview' [int] = 0
  'voice' [int] = 1
  'smssession' [int] = 0
  'sms' [int] = 0

2017-06-23T09:09:13.139 Trc 26019 Sending message 'EventCurrentAgentStatus' ('155') to client '7001' - Agent application:7:1884 through proxy 'WorkspaceDesktop_thanvv' - Proxy:6:1884, message attributes:
attr_ref_id [int] = -1
attr_prxy_client_id [int] = 7
attr_tenant_id [int] = 1
attr_place_id [str] = "7001"
attr_agent_id [str] = "thanvv"
attr_donot_disturb [int] = 0
attr_media_state_list [list, size (unpacked)=100] =
  'email' [int] = 1
  'chat' [int] = 1
  'outboundpreview' [int] = 1
  'voice' [int] = 1
  'smssession' [int] = 1
  'sms' [int] = 1
attr_media_capacity_list [list, size (unpacked)=100] =
  'email' [int] = 0
  'chat' [int] = 0
  'outboundpreview' [int] = 0
  'voice' [int] = 1
  'smssession' [int] = 0
  'sms' [int] = 0

Can you please give me a few ideas to fix this error?

[cavagnaro]

What error?
Agent is already Ready so another Ready just warns you.
Nothing wrong

[Peter Parker]

Hi Cavagnar,

The error may be on Queue, can you please explain more detail? 

[cavagnaro]

Don't understand your question

[Peter Parker]

Hi Cavagnaro,

As you said " I see error on Queue again...verify that, quite explicit error "

I just want to know a few suggestions to do that, can you please guide me step-by-step? 

[PeteHoyle]

Check your Agents Capacity rule, it is say it has a capacity for zero chat interactions:

  attr_media_capacity_list [list, size (unpacked)=100] =
      'email' [int] = 0
      'chat' [int] = 0
      'outboundpreview' [int] = 0
      'voice' [int] = 1
      'smssession' [int] = 0
      'sms' [int] = 0

[Kubig]

What is your capacity rules?

In general, from your posts I feel you need Genesys training and learn about Genesys a lot.

[Peter Parker]

Hi Everyone,

I have fixed the error, just add capacity rules for Agent
@Kubig, Yep, I'm newbies about Genesys 

[Kubig]

It was not an error, but the incorrect or missing configuration - be clear in your statement

[hieutx]

Your IWS just can not connect to chat server.
Please verify connecting direction from IWS to chat server