Chat Interaction not being routed - GMS ORS

[Maxwell]

Anyone with routing experience able to help:

request to 65201(--) message RequestDistributeEvent
AttributeMediaType 5
AttributeTimeout 60
AttributeTimeinuSecs 315000
AttributeTimeinSecs 1430415059 (13:30:59)
AttributeExtensions [184] 00 06 00 00..
'SIGNATURE' 'router'
'NAME' 'STC_RTG_UR_SRV_51_PoC'
'VERSION' 'Version: 8.1.400.06'
'CLUSTER' 'STC_RTG_UR_SRV_51_PoC'
'VQID' 'Q36SPA4FKD7FV2184UTNGLBH3S000004'
'CUSTOMER_ID' 'Environment'
AttributeUserData [59] 00 02 00 00..
'GMS_ROUTABLE' '0'
'RouterData70' '("t"="1430415059 312")'
AttributeOtherDNRole 1
AttributeThisDNRole 2
AttributeThisQueue 'MobilePoC'
AttributeThisDN 'MobilePoC'
AttributeConnID 00750263bfbf4005
AttributeCustomerID 'Environment'
AttributeReferenceID 4294967295
AttributeUserEvent EventQueued
..sent to stcdevxmevo51.x.dev.can:16130(fd=672)
13:30:59.315_M_I_00750263bfbf4005 [13:02] entering vq "MobilePoC"
    _M_I_00750263bfbf4005 [17:0f] VQ 0000000001a9bb30 [at all 2 0 0] 1 Target(s), flag=c08a, guid: 0Environment|MobilePoC|1|md38|1|00|0||||||00|00{}{}{}[]STC_Toronto_AgentGroup_PoC@STC_RTG_STAT_SRV_51_PoC.GA
13:30:59.315_M_I_00750263bfbf4005 [13:01] current vq: 0000000001a35a90 id=5(), nVQ=1-1a06170, priority=0, time=1430415059.312
13:30:59.315_M_I_00750263bfbf4005 [13:03] call (vq 0000000001a35a90, id=5, priority 0, time 1430415059.312) waits for VQ 0000000001a9bb30 (name="MobilePoC") now (0)
    _T_I_00750263bfbf4005 [0E:19] check call routing states: s=0 d=0 t=0 h=(0 0) r=0 i=0 x=0 b=1 - false
    _I_I_00750263bfbf4005 [07:0a] HERE IS WAIT (-1 sec)
    _I_I_00750263bfbf4005 [07:0a] HERE IS WAIT (300 sec)
13:30:59.315_B_I_00750263bfbf4005 [07:0a] delay treatments for 0 msec
13:30:59.315_I_I_00750263bfbf4005 [09:04] <<<<<<<<<<<<suspend interp(WAIT), func:Wait timers:11000
13:30:59.315_B_I_00750263bfbf4005 [07:64] start chain of treatments
received from 65201(STC_ESRV_IXN_SRV_51_PoC)stcdevxmevo51.x.dev.can:16130(fd=) message EventACK(refid=-1)
13:30:59.386_R_I_ [19:10] routing interface request received: urs/call/00750263bfbf4005/func?name=FindConfigObject&params=%5B2,%20%22name:MobilePoC%22%5D, client=656(HTTPINTERFACE), ref=21
13:30:59.386_I_I_00750263bfbf4005 [09:06] >>>>>>>>>>>>start interp()
    _I_I_00750263bfbf4005 [07:46] no error mode for this call
    _I_I_00750263bfbf4005 [09:04] ASSIGN: v:0:this(LOCAL) <- OBJECT:000000000115EF70[Global]={"Class":"Global"}
    _I_I_00750263bfbf4005 [09:04] ASSIGN: v:0:_urs(LOCAL) <- OBJECT:0000000001A35BB0[FM]={}
    _I_I_00750263bfbf4005 [09:04] ASSIGN: v:0:_parms(LOCAL) <- OBJECT:0000000001A35A30[Array]=[2,"name:MobilePoC"]
    _I_I_00750263bfbf4005 [09:04] ASSIGN: v:0:_func(LOCAL) <- STRING: "FindConfigObject"
    _I_I_00750263bfbf4005 [09:04] ASSIGN: v:0:_r(LOCAL) <- LIST: dbid:134|type:5|number:MobilePoC|name:MobilePoC|switchdbid:102|switch:STC_ESRV_MM_SWITCH_51_PoC|tenantdbid:1|tenant:Environment|annex.:
    _I_I_00750263bfbf4005 [01:1f] <<<<<<<<<<<<stop interp
13:30:59.386_R_I_ [19:11] routing interface response 'Info' sent to client=656(HTTPINTERFACE), ref=21
Message: {"dbid":134,"type":5,"number":"MobilePoC","name":"MobilePoC","switchdbid":102,"switch":"STC_ESRV_MM_SWITCH_51_PoC","tenantdbid":1,"tenant":"Environment","annex":{}}
13:30:59.452_R_I_ [19:10] routing interface request received: urs/call/00750263bfbf4005/udata?RouterData70, client=656(HTTPINTERFACE), ref=22
13:30:59.452_R_I_ [19:11] routing interface response 'Info' sent to client=656(HTTPINTERFACE), ref=22
Message: {"RouterData70":"(\"t\"=\"1430415059 312\")"}
13:30:59.514_R_I_ [19:10] routing interface request received: urs/call/00750263bfbf4005/lvq?name=MobilePoC&aqt=stat&filter=ewt%2Ctime%2Cwt%2Ccalls%2Cpos%2Cwpos%2Chit, client=656(HTTPINTERFACE), ref=23
13:30:59.514_R_I_ [19:11] routing interface response 'Info' sent to client=656(HTTPINTERFACE), ref=23
Message: {"time":1430415059,"wt":0,"calls":1,"pos":1,"wpos":1,"ewt":10000,"hit":0}
13:31:00.000_M_I_ [10:1d] PULSE (calls: 2(2)=1+1-0, targets=1, firsters=0, time=1430415060, mem=0,17254,102,21,181,0,2,0)
13:31:00.000_M_I_ [10:1d] vqs allocation pattern: static=(13 256 128) dynamic=(5 5/1024)
13:31:00.000_M_I_ [10:1d] PULSE no calls, skipped: 0 cpu 3 missed 2 next
13:31:02.001_M_I_ [10:1d] PULSE (calls: 2(2)=2+0-0, targets=1, firsters=0, time=1430415062, mem=0,17254,102,21,181,0,2,0)
13:31:02.001_M_I_ [10:1d] vqs allocation pattern: static=(13 256 128) dynamic=(5 5/1024)
13:31:02.001_M_I_ [10:1d] PULSE no calls, skipped: 0 cpu 4 missed 2 next
13:31:03.496_G_I_ [01:0b] look for hanged interactions: 2 at all now (1 cpu usage)
13:31:03.496_G_I_ [01:0b] there are 2 calls in progress now
_G_I_ Version: 8.1.400.06
root 0000000040bc7200 (4,4,8)
thread 0000000000001ce0
13:31:03.653 Trc 20011 current number of targets for tenant Environment: Agents 11, Places 0, AgentGroups 1, PlaceGroups 0, ACDQueues 0, Routing Points 0
13:31:03.653 Trc 20012 current number of interactions per second - 0.20
13:31:03.653 Trc 20013 current number of entries: for longest queue 0, for all queues 0
13:31:04.000_M_I_ [10:1d] PULSE (calls: 2(2)=2+0-0, targets=1, firsters=0, time=1430415064, mem=0,17254,102,21,181,0,2,0)
13:31:04.000_M_I_ [10:1d] vqs allocation pattern: static=(13 256 128) dynamic=(5 5/1024)
13:31:04.000_M_I_ [10:1d] check queue for SO <002> type <Agent> stat <##state> (2 trgs 0 cpu)
    _M_I_0000000000000000 [10:20] try to route to ag "002" (pl "STC_8002_Toronto_Poc", 1 ready DNs, 2 trgs): TRG 0000000001822d30 VQ 0000000001a9bc50(1 calls)
    _M_I_0000000000000000 [10:20] try to route to ag "002" (pl "STC_8002_Toronto_Poc", 1 ready DNs, 2 trgs): TRG 0000000001822910 VQ 0000000001a9bb30(1 calls)
    _M_I_ [10:0b] ag 002, pl STC_8002_Toronto_Poc, sw  dn  isn't blocked absolutely(0)
    _M_I_00750263bfbf4004 [10:21] try to route to ag "002" (pl "STC_8002_Toronto_Poc", 1 ready DNs, 2 trgs): TRG 0000000001822d30 VQ 0000000001a9bc50(1 calls)
    _T_I_00750263bfbf4004 [0E:19] check call routing states: s=4 d=0 t=0 h=(0 0) r=0 i=0 x=0 b=1 - false
    _M_I_00750263bfbf4004 [10:21] state of call isn't good for routing
    _M_I_00750263bfbf4005 [10:21] try to route to ag "002" (pl "STC_8002_Toronto_Poc", 1 ready DNs, 2 trgs): TRG 0000000001822910 VQ 0000000001a9bb30(1 calls)
    _T_I_00750263bfbf4005 [0E:19] check call routing states: s=4 d=0 t=0 h=(0 0) r=0 i=0 x=0 b=1 - false
    _M_I_00750263bfbf4005 [10:21] state of call isn't good for routing
13:31:04.000_M_I_ [10:1d] PULSE over: 0 cpu 1 objects 2 trgs
13:31:06.000_M_I_ [10:1d] PULSE (calls: 2(2)=2+0-0, targets=1, firsters=0, time=1430415066, mem=0,17254,102,21,181,0,2,0)
13:31:06.000_M_I_ [10:1d] vqs allocation pattern: static=(13 256 128) dynamic=(5 5/1024)
13:31:06.000_M_I_ [10:1d] PULSE no calls, skipped: 0 cpu 1 missed 2 next
13:31:08.000_M_I_ [10:1d] PULSE (calls: 2(2)=2+0-0, targets=1, firsters=0, time=1430415068, mem=0,17254,102,21,181,0,2,0)
13:31:08.000_M_I_ [10:1d] vqs allocation pattern: static=(13 256 128) dynamic=(5 5/1024)
13:31:08.000_M_I_ [10:1d] PULSE no calls, skipped: 0 cpu 2 missed 2 next
13:31:10.000_M_I_ [10:1d] PULSE (calls: 2(2)=2+0-0, targets=1, firsters=0, time=1430415070, mem=0,17254,102,21,181,0,2,0)
13:31:10.000_M_I_ [10:1d] vqs allocation pattern: static=(13 256 128) dynamic=(5 5/1024)
13:31:10.000_M_I_ [10:1d] PULSE no calls, skipped: 0 cpu 3 missed 2 next
13:31:12.000_M_I_ [10:1d] PULSE (calls: 2(2)=2+0-0, targets=1, firsters=0, time=1430415072, mem=0,17254,102,21,181,0,2,0)
13:31:12.000_M_I_ [10:1d] vqs allocation pattern: static=(13 256 128) dynamic=(5 5/1024)
13:31:12.000_M_I_ [10:1d] PULSE no calls, skipped: 0 cpu 4 missed 2 next
13:31:14.000_M_I_ [10:1d] PULSE (calls: 2(2)=2+0-0, targets=1, firsters=0, time=1430415074, mem=0,17254,102,21,181,0,2,0)
13:31:14.000_M_I_ [10:1d] vqs allocation pattern: static=(13 256 128) dynamic=(5 5/1024)
13:31:14.000_M_I_ [10:1d] check queue for SO <002> type <Agent> stat <##state> (2 trgs 0 cpu)
    _M_I_0000000000000000 [10:20] try to route to ag "002" (pl "STC_8002_Toronto_Poc", 1 ready DNs, 2 trgs): TRG 0000000001822d30 VQ 0000000001a9bc50(1 calls)
    _M_I_0000000000000000 [10:20] try to route to ag "002" (pl "STC_8002_Toronto_Poc", 1 ready DNs, 2 trgs): TRG 0000000001822910 VQ 0000000001a9bb30(1 calls)
    _M_I_ [10:0b] ag 002, pl STC_8002_Toronto_Poc, sw  dn  isn't blocked absolutely(0)
    _M_I_00750263bfbf4004 [10:21] try to route to ag "002" (pl "STC_8002_Toronto_Poc", 1 ready DNs, 2 trgs): TRG 0000000001822d30 VQ 0000000001a9bc50(1 calls)
    _T_I_00750263bfbf4004 [0E:19] check call routing states: s=4 d=0 t=0 h=(0 0) r=0 i=0 x=0 b=1 - false
    _M_I_00750263bfbf4004 [10:21] state of call isn't good for routing
    _M_I_00750263bfbf4005 [10:21] try to route to ag "002" (pl "STC_8002_Toronto_Poc", 1 ready DNs, 2 trgs): TRG 0000000001822910 VQ 0000000001a9bb30(1 calls)
    _T_I_00750263bfbf4005 [0E:19] check call routing states: s=4 d=0 t=0 h=(0 0) r=0 i=0 x=0 b=1 - false
    _M_I_00750263bfbf4005 [10:21] state of call isn't good for routing
13:31:14.000_M_I_ [10:1d] PULSE over: 0 cpu 1 objects 2 trgs
13:31:16.000_M_I_ [10:1d] PULSE (calls: 2(2)=2+0-0, targets=1, firsters=0, time=1430415076, mem=0,17254,102,21,181,0,2,0)
13:31:16.000_M_I_ [10:1d] vqs allocation pattern: static=(13 256 128) dynamic=(5 5/1024)
13:31:16.000_M_I_ [10:1d] PULSE no calls, skipped: 0 cpu 1 missed 2 next
13:31:18.000_M_I_ [10:1d] PULSE (calls: 2(2)=2+0-0, targets=1, firsters=0, time=1430415078, mem=0,17254,102,21,181,0,2,0)
13:31:18.000_M_I_ [10:1d] vqs allocation pattern: static=(13 256 128) dynamic=(5 5/1024)
13:31:18.000_M_I_ [10:1d] PULSE no calls, skipped: 0 cpu 2 missed 2 next
13:31:20.000_M_I_ [10:1d] PULSE (calls: 2(2)=2+0-0, targets=1, firsters=0, time=1430415080, mem=0,17254,102,21,181,0,2,0)
13:31:20.000_M_I_ [10:1d] vqs allocation pattern: static=(13 256 128) dynamic=(5 5/1024)
13:31:20.000_M_I_ [10:1d] PULSE no calls, skipped: 0 cpu 3 missed 2 next
13:31:22.001_M_I_ [10:1d] PULSE (calls: 2(2)=2+0-0, targets=1, firsters=0, time=1430415082, mem=0,17254,102,21,181,0,2,0)
13:31:22.001_M_I_ [10:1d] vqs allocation pattern: static=(13 256 128) dynamic=(5 5/1024)
13:31:22.001_M_I_ [10:1d] PULSE no calls, skipped: 0 cpu 4 missed 2 next
13:31:24.000_M_I_ [10:1d] PULSE (calls: 2(2)=2+0-0, targets=1, firsters=0, time=1430415084, mem=0,17254,102,21,181,0,2,0)
13:31:24.000_M_I_ [10:1d] vqs allocation pattern: static=(13 256 128) dynamic=(5 5/1024)
13:31:24.000_M_I_ [10:1d] check queue for SO <002> type <Agent> stat <##state> (2 trgs 0 cpu)
    _M_I_0000000000000000 [10:20] try to route to ag "002" (pl "STC_8002_Toronto_Poc", 1 ready DNs, 2 trgs): TRG 0000000001822d30 VQ 0000000001a9bc50(1 calls)
    _M_I_0000000000000000 [10:20] try to route to ag "002" (pl "STC_8002_Toronto_Poc", 1 ready DNs, 2 trgs): TRG 0000000001822910 VQ 0000000001a9bb30(1 calls)
    _M_I_ [10:0b] ag 002, pl STC_8002_Toronto_Poc, sw  dn  isn't blocked absolutely(0)
    _M_I_00750263bfbf4004 [10:21] try to route to ag "002" (pl "STC_8002_Toronto_Poc", 1 ready DNs, 2 trgs): TRG 0000000001822d30 VQ 0000000001a9bc50(1 calls)
    _T_I_00750263bfbf4004 [0E:19] check call routing states: s=4 d=0 t=0 h=(0 0) r=0 i=0 x=0 b=1 - false
    _M_I_00750263bfbf4004 [10:21] state of call isn't good for routing
    _M_I_00750263bfbf4005 [10:21] try to route to ag "002" (pl "STC_8002_Toronto_Poc", 1 ready DNs, 2 trgs): TRG 0000000001822910 VQ 0000000001a9bb30(1 calls)
    _T_I_00750263bfbf4005 [0E:19] check call routing states: s=4 d=0 t=0 h=(0 0) r=0 i=0 x=0 b=1 - false
    _M_I_00750263bfbf4005 [10:21] state of call isn't good for routing
13:31:24.000_M_I_ [10:1d] PULSE over: 0 cpu 1 objects 2 trgs
13:31:26.000_M_I_ [10:1d] PULSE (calls: 2(2)=2+0-0, targets=1, firsters=0, time=1430415086, mem=0,17254,102,21,181,0,2,0)
13:31:26.000_M_I_ [10:1d] vqs allocation pattern: static=(13 256 128) dynamic=(5 5/1024)
13:31:26.000_M_I_ [10:1d] PULSE no calls, skipped: 0 cpu 1 missed 2 next
13:31:28.001_M_I_ [10:1d] PULSE (calls: 2(2)=2+0-0, targets=1, firsters=0, time=1430415088, mem=0,17254,102,21,181,0,2,0)
13:31:28.001_M_I_ [10:1d] vqs allocation pattern: static=(13 256 128) dynamic=(5 5/1024)
13:31:28.001_M_I_ [10:1d] PULSE no calls, skipped: 0 cpu 2 missed 2 next
13:31:30.000_M_I_ [10:1d] PULSE (calls: 2(2)=2+0-0, targets=1, firsters=0, time=1430415090, mem=0,17254,102,21,181,0,2,0)
13:31:30.000_M_I_ [10:1d] vqs allocation pattern: static=(13 256 128) dynamic=(5 5/1024)
13:31:30.000_M_I_ [10:1d] PULSE no calls, skipped: 0 cpu 3 missed 2 next
13:31:32.001_M_I_ [10:1d] PULSE (calls: 2(2)=2+0-0, targets=1, firsters=0, time=1430415092, mem=0,17254,102,21,181,0,2,0)
13:31:32.001_M_I_ [10:1d] vqs allocation pattern: static=(13 256 128) dynamic=(5 5/1024)
13:31:32.001_M_I_ [10:1d] PULSE no calls, skipped: 0 cpu 4 missed 2 next
13:31:34.000_M_I_ [10:1d] PULSE (calls: 2(2)=2+0-0, targets=1, firsters=0, time=1430415094, mem=0,17254,102,21,181,0,2,0)
13:31:34.000_M_I_ [10:1d] vqs allocation pattern: static=(13 256 128) dynamic=(5 5/1024)
13:31:34.000_M_I_ [10:1d] check queue for SO <002> type <Agent> stat <##state> (2 trgs 0 cpu)
    _M_I_0000000000000000 [10:20] try to route to ag "002" (pl "STC_8002_Toronto_Poc", 1 ready DNs, 2 trgs): TRG 0000000001822d30 VQ 0000000001a9bc50(1 calls)
    _M_I_0000000000000000 [10:20] try to route to ag "002" (pl "STC_8002_Toronto_Poc", 1 ready DNs, 2 trgs): TRG 0000000001822910 VQ 0000000001a9bb30(1 calls)
    _M_I_ [10:0b] ag 002, pl STC_8002_Toronto_Poc, sw  dn  isn't blocked absolutely(0)
    _M_I_00750263bfbf4004 [10:21] try to route to ag "002" (pl "STC_8002_Toronto_Poc", 1 ready DNs, 2 trgs): TRG 0000000001822d30 VQ 0000000001a9bc50(1 calls)
    _T_I_00750263bfbf4004 [0E:19] check call routing states: s=4 d=0 t=0 h=(0 0) r=0 i=0 x=0 b=1 - false
    _M_I_00750263bfbf4004 [10:21] state of call isn't good for routing
    _M_I_00750263bfbf4005 [10:21] try to route to ag "002" (pl "STC_8002_Toronto_Poc", 1 ready DNs, 2 trgs): TRG 0000000001822910 VQ 0000000001a9bb30(1 calls)
    _T_I_00750263bfbf4005 [0E:19] check call routing states: s=4 d=0 t=0 h=(0 0) r=0 i=0 x=0 b=1 - false
    _M_I_00750263bfbf4005 [10:21] state of call isn't good for routing
13:31:34.000_M_I_ [10:1d] PULSE over: 0 cpu 1 objects 2 trgs
13:31:36.001_M_I_ [10:1d] PULSE (calls: 2(2)=2+0-0, targets=1, firsters=0, time=1430415096, mem=0,17254,102,21,181,0,2,0)
13:31:36.001_M_I_ [10:1d] vqs allocation pattern: static=(13 256 128) dynamic=(5 5/1024)
13:31:36.001_M_I_ [10:1d] PULSE no calls, skipped: 0 cpu 1 missed 2 next
13:31:38.000_M_I_ [10:1d] PULSE (calls: 2(2)=2+0-0, targets=1, firsters=0, time=1430415098, mem=0,17254,102,21,181,0,2,0)
13:31:38.000_M_I_ [10:1d] vqs allocation pattern: static=(13 256 128) dynamic=(5 5/1024)
13:31:38.000_M_I_ [10:1d] PULSE no calls, skipped: 0 cpu 2 missed 2 next
13:31:40.001_M_I_ [10:1d] PULSE (calls: 2(2)=2+0-0, targets=1, firsters=0, time=1430415100, mem=0,17254,102,21,181,0,2,0)
13:31:40.001_M_I_ [10:1d] vqs allocation pattern: static=(13 256 128) dynamic=(5 5/1024)
13:31:40.001_M_I_ [10:1d] PULSE no calls, skipped: 0 cpu 3 missed 2 next
13:31:42.000_M_I_ [10:1d] PULSE (calls: 2(2)=2+0-0, targets=1, firsters=0, time=1430415102, mem=0,17254,102,21,181,0,2,0)
13:31:42.000_M_I_ [10:1d] vqs allocation pattern: static=(13 256 128) dynamic=(5 5/1024)
13:31:42.000_M_I_ [10:1d] PULSE no calls, skipped: 0 cpu 4 missed 2 next
13:31:44.001_M_I_ [10:1d] PULSE (calls: 2(2)=2+0-0, targets=1, firsters=0, time=1430415104, mem=0,17254,102,21,181,0,2,0)
13:31:44.001_M_I_ [10:1d] vqs allocation pattern: static=(13 256 128) dynamic=(5 5/1024)
13:31:44.001_M_I_ [10:1d] check queue for SO <002> type <Agent> stat <##state> (2 trgs 0 cpu)
    _M_I_0000000000000000 [10:20] try to route to ag "002" (pl "STC_8002_Toronto_Poc", 1 ready DNs, 2 trgs): TRG 0000000001822d30 VQ 0000000001a9bc50(1 calls)
    _M_I_0000000000000000 [10:20] try to route to ag "002" (pl "STC_8002_Toronto_Poc", 1 ready DNs, 2 trgs): TRG 0000000001822910 VQ 0000000001a9bb30(1 calls)
    _M_I_ [10:0b] ag 002, pl STC_8002_Toronto_Poc, sw  dn  isn't blocked absolutely(0)
    _M_I_00750263bfbf4004 [10:21] try to route to ag "002" (pl "STC_8002_Toronto_Poc", 1 ready DNs, 2 trgs): TRG 0000000001822d30 VQ 0000000001a9bc50(1 calls)
    _T_I_00750263bfbf4004 [0E:19] check call routing states: s=4 d=0 t=0 h=(0 0) r=0 i=0 x=0 b=1 - false
    _M_I_00750263bfbf4004 [10:21] state of call isn't good for routing
    _M_I_00750263bfbf4005 [10:21] try to route to ag "002" (pl "STC_8002_Toronto_Poc", 1 ready DNs, 2 trgs): TRG 0000000001822910 VQ 0000000001a9bb30(1 calls)
    _T_I_00750263bfbf4005 [0E:19] check call routing states: s=4 d=0 t=0 h=(0 0) r=0 i=0 x=0 b=1 - false
    _M_I_00750263bfbf4005 [10:21] state of call isn't good for routing
13:31:44.001_M_I_ [10:1d] PULSE over: 0 cpu 1 objects 2 trgs
13:31:46.000_M_I_ [10:1d] PULSE (calls: 2(2)=2+0-0, targets=1, firsters=0, time=1430415106, mem=0,17254,102,21,181,0,2,0)
13:31:46.000_M_I_ [10:1d] vqs allocation pattern: static=(13 256 128) dynamic=(5 5/1024)
13:31:46.000_M_I_ [10:1d] PULSE no calls, skipped: 0 cpu 1 missed 2 next
13:31:48.001_M_I_ [10:1d] PULSE (calls: 2(2)=2+0-0, targets=1, firsters=0, time=1430415108, mem=0,17254,102,21,181,0,2,0)
13:31:48.001_M_I_ [10:1d] vqs allocation pattern: static=(13 256 128) dynamic=(5 5/1024)
13:31:48.001_M_I_ [10:1d] PULSE no calls, skipped: 0 cpu 2 missed 2 next
13:31:50.001_M_I_ [10:1d] PULSE (calls: 2(2)=2+0-0, targets=1, firsters=0, time=1430415110, mem=0,17254,102,21,181,0,2,0)
13:31:50.001_M_I_ [10:1d] vqs allocation pattern: static=(13 256 128) dynamic=(5 5/1024)
13:31:50.001_M_I_ [10:1d] PULSE no calls, skipped: 0 cpu 3 missed 2 next
13:31:52.001_M_I_ [10:1d] PULSE (calls: 2(2)=2+0-0, targets=1, firsters=0, time=1430415112, mem=0,17254,102,21,181,0,2,0)
13:31:52.001_M_I_ [10:1d] vqs allocation pattern: static=(13 256 128) dynamic=(5 5/1024)
13:31:52.001_M_I_ [10:1d] PULSE no calls, skipped: 0 cpu 4 missed 2 next
13:31:54.001_M_I_ [10:1d] PULSE (calls: 2(2)=2+0-0, targets=1, firsters=0, time=1430415114, mem=0,17254,102,21,181,0,2,0)
13:31:54.001_M_I_ [10:1d] vqs allocation pattern: static=(13 256 128) dynamic=(5 5/1024)
13:31:54.001_M_I_ [10:1d] check queue for SO <002> type <Agent> stat <##state> (2 trgs 0 cpu)
    _M_I_0000000000000000 [10:20] try to route to ag "002" (pl "STC_8002_Toronto_Poc", 1 ready DNs, 2 trgs): TRG 0000000001822d30 VQ 0000000001a9bc50(1 calls)
    _M_I_0000000000000000 [10:20] try to route to ag "002" (pl "STC_8002_Toronto_Poc", 1 ready DNs, 2 trgs): TRG 0000000001822910 VQ 0000000001a9bb30(1 calls)
    _M_I_ [10:0b] ag 002, pl STC_8002_Toronto_Poc, sw  dn  isn't blocked absolutely(0)
    _M_I_00750263bfbf4004 [10:21] try to route to ag "002" (pl "STC_8002_Toronto_Poc", 1 ready DNs, 2 trgs): TRG 0000000001822d30 VQ 0000000001a9bc50(1 calls)
    _T_I_00750263bfbf4004 [0E:19] check call routing states: s=4 d=0 t=0 h=(0 0) r=0 i=0 x=0 b=1 - false
    _M_I_00750263bfbf4004 [10:21] state of call isn't good for routing
    _M_I_00750263bfbf4005 [10:21] try to route to ag "002" (pl "STC_8002_Toronto_Poc", 1 ready DNs, 2 trgs): TRG 0000000001822910 VQ 0000000001a9bb30(1 calls)
    _T_I_00750263bfbf4005 [0E:19] check call routing states: s=4 d=0 t=0 h=(0 0) r=0 i=0 x=0 b=1 - false
    _M_I_00750263bfbf4005 [10:21] state of call isn't good for routing
13:31:54.001_M_I_ [10:1d] PULSE over: 0 cpu 1 objects 2 trgs
13:31:56.000_M_I_ [10:1d] PULSE (calls: 2(2)=2+0-0, targets=1, firsters=0, time=1430415116, mem=0,17254,102,21,181,0,2,0)
13:31:56.000_M_I_ [10:1d] vqs allocation pattern: static=(13 256 128) dynamic=(5 5/1024)
13:31:56.000_M_I_ [10:1d] PULSE no calls, skipped: 0 cpu 1 missed 2 next
13:31:58.001_M_I_ [10:1d] PULSE (calls: 2(2)=2+0-0, targets=1, firsters=0, time=1430415118, mem=0,17254,102,21,181,0,2,0)
13:31:58.001_M_I_ [10:1d] vqs allocation pattern: static=(13 256 128) dynamic=(5 5/1024)
13:31:58.001_M_I_ [10:1d] PULSE no calls, skipped: 0 cpu 2 missed 2 next
13:31:59.315_T_I_00750263bfbf4005 [14:02] sending event 154 for vq MobilePoC (0 0 0 0 0 0)

[terry]

Basically from this log:

_T_I_00750263bfbf4004 [0E:19] check call routing states: s=4 d=0 t=0 h=(0 0) r=0 i=0 x=0 b=1 - false
    _M_I_00750263bfbf4004 [10:21] state of call isn't good for routing

It means (from b=1)the strategy executed for this call function DoNotSelectCall [true].
Call will not be routed at least until the same function will be executed with argument false.

Why URS doesn.t get (web) command to execute this function - not sure.
Might be lvq Web request to URS returns information about Virtual queue saying
it is not good time to do this. In response on lvq method the only suspicious thing is 10000 as ewt.
It might be because of VQ (MobilePoC) is not associated with any agent group and statserver can not really
count ewt

[Maxwell]

Hi Terry,

Thank you very much, that gives at least some direction to what is going on . Now I'm just wondering what triggers call function DoNotSelectCall to be executed with argument false.
I have added VQ (Mobile PoC) as an origination DN in one of my Agent groups, but it is still giving 10000 as estimated wait time. Mind you I have sent multiple requests and some of them don't seem to be terminating.

Any insights would be a great help.

[terry]

I'm not too good in GMS so following is just speculation.
If lvq method for the call is invoked the only reason for that
to find out when call expected to be routed (= to be close to head of queue and have
"small" ewt then URS web method to be caked to ask it to make interaction routable.)

[Maxwell]

Hi Terry,

Anything helps at this point. Any ideas on what x and b stand for in the call states, noone seems to be familiar with these, and b having a value of 1 seems concerning. Thanks

s=4 d=0 t=0 h=(0 0) r=0 i=0 x=0 b=1 - false

[terry]

To allocate agent to interaction the interaction (and not only agent) should be in a proper state.
This line in log lists interaction flags definiing its state and short result (true/false) is combined call state Ok for call to be routed (true) or not (false).
s- strategy state - which function/startegy object is executed (4 means Selection object for example, other "good" function can be SuspendForDN for example (s=10).
              call will not be routed if current function accesing data base is executed (s=22))
d - delivery - is call currently/already in process of delivering to other DN.
t - treatment - does any treatment is in process of applying to this call (good staes is when either not (0) or it is already applied (3))
h - hold - physically interaction should not be on hold (should not be EventHold received ans alos interaction should not be in "the air")
r - agent reservation - should not be any active agent reserving requests on behalf of this interaction
i - ivr - is it about IVR target or not (rules for IVR targets are not aso strong as for regular targets, call can be sent on IVR iven if currently strategy for this call acessing database)
x - - call is in routing delay status (somebody (GMS for example) call function RouteDelay[timeout] and timeout is not excpired yet). While is in such delay interaction will not be routed
b - call is blocked - somebody call DoNotSelectCall[true] function.