To follow up on why URS is routing priority 0 when there is priority 1 call waiting in another queue, here is a sample log:
there is Skill_A and Skill_B and operator has both of these skills.
There are two strategies, targeting skill A and skill B respectively.
Strategy is setup so that call's priority is increased every minutes by one count.
Call A - 0092017974d65052 - was in queue A for over a minute for Skill A and has priority 1
Call B - 0092017974d65053 - was in queue B for less than a minute and has priority 0
Once agent becomes ready, call B is sent to operator before call A (despite call A having a higher priority). Once agent finishes call B, call A is then routed to the same operator.
Why is it doing such a nasty thing?
[code]
18:11:58.727_M_I_ [10:06] tenant Resources GroupAgents <VAG_Skill_A_H>: statistic ##content with server StatServer <request=7, status=2> asked
_M_I_ [17:05] VQ(101b7ed20) target (name=VAG_Skill_A_H, location=StatServer, type=GA) synchronizing
request to 65202(--) message RequestDistributeEvent
AttributeExtensions [82] 00 04 00 00..
'SIGNATURE' 'router'
'NAME' 'URS1'
'VERSION' 'Version: 7.1.001.03'
'CLUSTER' 'URS1'
AttributeUserData [698] 00 1B 00 00..
'DNIS_NUM' '20005'
'CUSTID' 'CID:'
'RRequestedSkills'(list)
'CustomerSegment' 'default'
'ServiceType' 'default'
'ServiceObjective' ''
'DEBUG_GENESYS_DESTINATION' 'Switch_Tokyo'
'Site_ext' 'Tokyo'
'Skillname' 'Skill_A'
'SKILL_CODE' '21035'
'VAR_sTarget_val' '20842_Switch_Tokyo'
'VAR_sStatServer' 'StatServer'
'SubDN' '20779_Switch_Tokyo'
'RTargetRuleSelected' ''
'RTargetTypeSelected' '100'
'RTargetObjectSelected' ''
'RTargetAgentSelected' ''
'RTargetPlaceSelected' ''
'RTenant' 'Resources'
'RStrategyName' 'jump_to_routing'
'RTargetUsed'(list) 'TargetType' '100'
'TargetName' ''
'RRequestedSkillCombination' ''
'ReasonToCXU' 'noANI'
'VAR_nResult' '0'
'PegTD' 1
'ScriptName' ''
'VAR_sVQ' 'VQ_Skill_A_Tokyo'
AttributeDNIS '20005'
AttributeOtherDNRole 1
AttributeOtherDN '6003'
AttributeThisDNRole 2
AttributeThisQueue 'VQ_Skill_A'
AttributeThisDN 'VQ_Skill_A'
AttributeCallID 92
AttributeConnID 0092017974d65052
AttributeCustomerID 'Resources'
AttributeReferenceID 4294967295
AttributeUserEvent EventQueued
..sent to ctisssvp:5080(fd=16)
_M_I_0092017974d65052 [17:0e] virtual queue "VQ_Skill_A_Tokyo"(id=293), VQ 101b8b6e0: target for routing was NOT SELECTED (0 0 0 1)
18:11:59.738_I_I_0092017974d65052 [09:04] <<<<<<<<<<<<suspend interpretator(WAIT_FOR_DN), timers:11000
18:11:59.738_B_I_0092017974d65052 [07:09] start chain of treatments
received from 65202(--)ctisssvp:5080(fd=16) message EventACK
AttributeCustomerID 'Resources'
AttributeTimeinuSecs 754024
AttributeTimeinSecs 1184749919 (18:11:59)
AttributeReferenceID 4294967295
AttributeThisDN 'VQ_Skill_A'
AttributeUserEvent RequestDistributeEvent
18:12:00.006_M_I_ [10:1d] PULSE (calls: 1(1)=1+0-0, targets=2, time=1184749919, mem=0,90035,1399,16,123,0)
18:12:00.746_I_I_0092017974d65052 [07:08] wait time is over
18:12:00.746_I_I_0092017974d65052 [09:05] >>>>>>>>>>>>resume interpretator(0)
_I_I_0092017974d65052 [09:04] ASSIGN: sTarget_out(LOCAL) <- STRING: return:timeout
_I_I_0092017974d65052 [09:04] ASSIGN: nResult(LOCAL) <- INTEGER: 0
18:12:00.746_I_I_0092017974d65052 [07:48] suspend function
18:12:00.746_I_I_0092017974d65052 [09:04] <<<<<<<<<<<<suspend interpretator(JUMPING), timers:00001
18:12:00.746_I_I_0092017974d65052 [07:49] resume function
_I_I_0092017974d65052 [07:44] jump to strategy *0x65*main_routing
18:12:00.746_I_I_0092017974d65052 [09:05] >>>>>>>>>>>>resume interpretator(0)
_I_I_0092017974d65052 [09:04] ASSIGN: nResult(LOCAL) <- INTEGER: 0
_I_I_0092017974d65052 [09:04] ASSIGN: sTarget_val(LOCAL) <- STRING: return:timeout
_I_I_0092017974d65052 [09:04] ASSIGN: sPotentialTarget(LOCAL) <- STRING: VAG_Skill_A_L@StatServer.GA
18:12:00.746_I_I_0092017974d65052 [07:48] suspend function
18:12:00.746_I_I_0092017974d65052 [09:04] <<<<<<<<<<<<suspend interpretator(JUMPING), timers:00001
18:12:00.746_I_I_0092017974d65052 [07:49] resume function
_I_I_0092017974d65052 [07:43] call strategy *0x65*sub_queue_call
18:12:00.746_I_I_0092017974d65052 [09:06] >>>>>>>>>>>>start interpretator
_I_I_0092017974d65052 [07:46] no error mode for this call
_I_I_0092017974d65052 [09:04] ASSIGN: __Return(SCRIPT) <- STRING:
_I_I_0092017974d65052 [09:04] ASSIGN: __DBReturn(SCRIPT) <- STRING:
_I_I_0092017974d65052 [09:04] ASSIGN: __DBStrReturn(SCRIPT) <- STRING:
_I_I_0092017974d65052 [09:04] ASSIGN: __TargetVar(SCRIPT) <- STRING:
_I_I_0092017974d65052 [09:04] ASSIGN: nTimeout_in(LOCAL) <- INTEGER: 9999
_I_I_0092017974d65052 [09:04] ASSIGN: sTarget_in(LOCAL) <- STRING: VAG_Skill_A_L@StatServer.GA
_I_I_0092017974d65052 [09:04] ASSIGN: sVQ_in(LOCAL) <- STRING: VQ_Skill_A_Tokyo
18:12:00.747_I_I_0092017974d65052 [07:26] HERE IS TARGETS
TARGETS: VAG_Skill_A_L@StatServer.GA
18:12:00.747_M_I_0092017974d65052 [13:01] current virtual queue: 101b7ef30 id=294, nVQ=1|39868980-101731c80, priority=0, time=1184749920.747
18:12:00.747_M_I_ [17:0c] VQ(101b842e0) created: type=0, tenant=Resources
==========================================
_M_I_ [17:06] VQ(101b842e0) target 101ba4f70 added: name=VAG_Skill_A_L, location=StatServer, type=GA, state=##state, activity=unknown
18:12:00.747_M_I_ [10:06] tenant Resources GroupAgents <VAG_Skill_A_L>: statistic ##content with server StatServer <request=19, status=2> asked
_M_I_ [17:05] VQ(101b842e0) target (name=VAG_Skill_A_L, location=StatServer, type=GA) synchronizing
18:12:00.747_M_I_ [10:06] tenant Resources Agent <1602>: statistic ##state with server StatServer <request=13, status=2> asked
_M_I_ [17:09] VQ(101b842e0), target 101ba4f70: GroupAgents <VAG_Skill_A_L> connected to state Agent 1602
18:12:00.747_M_I_ [10:06] tenant Resources Agent <1604>: statistic ##state with server StatServer <request=15, status=2> asked
_M_I_ [17:09] VQ(101b842e0), target 101ba4f70: GroupAgents <VAG_Skill_A_L> connected to state Agent 1604
18:12:00.747_M_I_ [10:06] tenant Resources Agent <7999998>: statistic ##state with server StatServer <request=17, status=2> asked
_M_I_ [17:09] VQ(101b842e0), target 101ba4f70: GroupAgents <VAG_Skill_A_L> connected to state Agent 7999998
18:12:00.747_M_I_0092017974d65052 [13:02] entering virtual queue "VQ_Skill_A_Tokyo"
_M_I_ [17:0f] VQ(101b842e0) [at all 8 0] 1 target(s), flag=a, guid: 0Resources|VQ_Skill_A_Tokyo|1|-1|1|0|""|||01StatAgentLoading|00{}[]VAG_Skill_A_L@StatServer.GA
18:12:00.747_M_I_0092017974d65052 [13:03] call (virtual queue 101b7ef30, id=294, priority 0, time 1184749920.747) waits for VQ 101b842e0 (name="VQ_Skill_A_Tokyo") now
request to 65202(--) message RequestDeletePair
AttributeReferenceID 2692
AttributeDataKey 'RTargetAgentGroup'
AttributeConnID 0092017974d65052
AttributeThisDN '20719'
..sent to ctisssvp:5080(fd=16)
request to 65202(--) message RequestAttachUserData
AttributeReferenceID 2693
AttributeUserData [119] 00 03 00 00..
'RTargetAgentGroup' 'VAG_Skill_A_L'
'RTargetAgentGroup' 'VAG_Skill_A_M'
'RTargetAgentGroup' 'VAG_Skill_A_H'
AttributeConnID 0092017974d65052
AttributeThisDN '20719'
..sent to ctisssvp:5080(fd=16)
request to 65202(--) message RequestDistributeEvent
AttributeExtensions [82] 00 04 00 00..
'SIGNATURE' 'router'
'NAME' 'URS1'
'VERSION' 'Version: 7.1.001.03'
'CLUSTER' 'URS1'
AttributeUserData [815] 00 1E 00 00..
'DNIS_NUM' '20005'
'CUSTID' 'CID:'
'RRequestedSkills'(list)
'CustomerSegment' 'default'
'ServiceType' 'default'
'ServiceObjective' ''
'DEBUG_GENESYS_DESTINATION' 'Switch_Tokyo'
'Site_ext' 'Tokyo'
'Skillname' 'Skill_A'
'SKILL_CODE' '21035'
'VAR_sTarget_val' '20842_Switch_Tokyo'
'VAR_sStatServer' 'StatServer'
'SubDN' '20779_Switch_Tokyo'
'RTargetRuleSelected' ''
'RTargetTypeSelected' '100'
'RTargetObjectSelected' ''
'RTargetAgentSelected' ''
'RTargetPlaceSelected' ''
'RTenant' 'Resources'
'RStrategyName' 'jump_to_routing'
'RTargetUsed'(list) 'TargetType' '100'
'TargetName' ''
'RRequestedSkillCombination' ''
'ReasonToCXU' 'noANI'
'VAR_nResult' '0'
'PegTD' 1
'ScriptName' ''
'VAR_sVQ' 'VQ_Skill_A_Tokyo'
'RTargetAgentGroup' 'VAG_Skill_A_L'
'RTargetAgentGroup' 'VAG_Skill_A_M'
'RTargetAgentGroup' 'VAG_Skill_A_H'
AttributeDNIS '20005'
AttributeOtherDNRole 1
AttributeOtherDN '6003'
AttributeThisDNRole 2
AttributeThisQueue 'VQ_Skill_A'
AttributeThisDN 'VQ_Skill_A'
AttributeCallID 92
AttributeConnID 0092017974d65052
AttributeCustomerID 'Resources'
AttributeReferenceID 4294967295
AttributeUserEvent EventAttachedDataChanged
..sent to ctisssvp:5080(fd=16)
_M_I_0092017974d65052 [17:0e] virtual queue "VQ_Skill_A_Tokyo"(id=294), VQ 101b842e0 (1 targets): SELECT MAX by statistic StatAgentLoading(random )
_M_I_0092017974d65052 [17:0b] VQ 101b842e0 target "VAG_Skill_A_L" (#1, 3-2 components): SELECT MAX by statistic StatAgentLoading(random)
_M_I_0092017974d65052 [17:0b] component #1 1602: not ready
_M_I_0092017974d65052 [17:0b] component #2 1604: logged out
_M_I_0092017974d65052 [17:0b] component #3 7999998: logged out
_M_I_0092017974d65052 [17:0b] VQ 101b842e0 target "VAG_Skill_A_L": component was NOT SELECTED (2 1 0)
_M_I_0092017974d65052 [17:0e] target VAG_Skill_A_L: not ready passed
_M_I_0092017974d65052 [17:0e] virtual queue "VQ_Skill_A_Tokyo"(id=294), VQ 101b842e0: target for routing was NOT SELECTED (0 0 0 1)
result of SelectDN: STRING: return:timeout
_I_I_0092017974d65052 [09:04] ASSIGN: sTarget_out(LOCAL) <- STRING: return:timeout
_I_I_0092017974d65052 [07:27] HERE IS WAIT FOR DN (9999 sec)
18:12:00.748_B_I_0092017974d65052 [07:27] delay treatments for 0 msec
18:12:00.748_M_I_0092017974d65052 [10:1f] pulse for one call
_T_I_0092017974d65052 [0E:19] check call routing states: state=10 delivery=0 treatment=0 held=0 reserving=0 - true
_M_I_0092017974d65052 [17:0e] virtual queue "VQ_Skill_A_Tokyo"(id=292), VQ 101b7ed20 (1 targets): SELECT MAX by statistic StatAgentLoading(random )
_M_I_0092017974d65052 [17:0e] target VAG_Skill_A_H: empty(0-0 components) passed
_M_I_0092017974d65052 [17:0e] virtual queue "VQ_Skill_A_Tokyo"(id=292), VQ 101b7ed20: target for routing was NOT SELECTED (1 0 0 0)
_M_I_0092017974d65052 [17:0e] virtual queue "VQ_Skill_A_Tokyo"(id=293), VQ 101b8b6e0 (1 targets): SELECT MAX by statistic StatAgentLoading(random )
_M_I_0092017974d65052 [17:0b] VQ 101b8b6e0 target "VAG_Skill_A_M" (#1, 3-2 components): SELECT MAX by statistic StatAgentLoading(random)
_M_I_0092017974d65052 [17:0b] component #1 1602: not ready
_M_I_0092017974d65052 [17:0b] component #2 1604: logged out
_M_I_0092017974d65052 [17:0b] component #3 7999998: logged out
_M_I_0092017974d65052 [17:0b] VQ 101b8b6e0 target "VAG_Skill_A_M": component was NOT SELECTED (2 1 0)
_M_I_0092017974d65052 [17:0e] target VAG_Skill_A_M: not ready passed
_M_I_0092017974d65052 [17:0e] virtual queue "VQ_Skill_A_Tokyo"(id=293), VQ 101b8b6e0: target for routing was NOT SELECTED (0 0 0 1)
_M_I_0092017974d65052 [17:0e] virtual queue "VQ_Skill_A_Tokyo"(id=294), VQ 101b842e0 (1 targets): SELECT MAX by statistic StatAgentLoading(random )
_M_I_0092017974d65052 [17:0b] VQ 101b842e0 target "VAG_Skill_A_L" (#1, 3-2 components): SELECT MAX by statistic StatAgentLoading(random)
_M_I_0092017974d65052 [17:0b] component #1 1602: not ready
_M_I_0092017974d65052 [17:0b] component #2 1604: logged out
_M_I_0092017974d65052 [17:0b] component #3 7999998: logged out
_M_I_0092017974d65052 [17:0b] VQ 101b842e0 target "VAG_Skill_A_L": component was NOT SELECTED (2 1 0)
_M_I_0092017974d65052 [17:0e] target VAG_Skill_A_L: not ready passed
_M_I_0092017974d65052 [17:0e] virtual queue "VQ_Skill_A_Tokyo"(id=294), VQ 101b842e0: target for routing was NOT SELECTED (0 0 0 1)
18:12:00.748_I_I_0092017974d65052 [09:04] <<<<<<<<<<<<suspend interpretator(WAIT_FOR_DN), timers:11000
18:12:00.748_B_I_0092017974d65052 [07:09] start chain of treatments
received from 65202(--)ctisssvp:5080(fd=16) message EventACK
AttributeCustomerID 'Resources'
AttributeTimeinuSecs 764420
AttributeTimeinSecs 1184749920 (18:12:00)
AttributeReferenceID 4294967295
AttributeThisDN 'VQ_Skill_A'
AttributeUserEvent RequestDistributeEvent
18:12:02.006_M_I_ [10:1d] PULSE (calls: 1(1)=1+0-0, targets=2, time=1184749921, mem=0,90047,1401,17,123,0)
18:12:02.186 Trc 20011 current number of targets for tenant Resources: Agents 11, Places 0, AgentGroups 6, PlaceGroups 0, ACDQueues 0, Routing Points 6
18:12:02.186 Trc 20012 current number of interactions per second - 1.00
18:12:02.186 Trc 20013 current number of entries: for longest queue 0, for all queues 0
18:12:04.006_M_I_ [10:1d] PULSE (calls: 1(1)=1+0-0, targets=2, time=1184749923, mem=0,90047,1401,17,123,0)
18:12:04.376_M_I_ [17:0d] VQ(101b81480) deleted
18:12:04.376_M_I_ [17:08] VQ(101b81480) target 101ba4c40 deleted: name=VAG_Skill_B_L
18:12:04.376_M_I_ [17:0d] VQ(101b811e0) deleted
18:12:04.376_M_I_ [17:08] VQ(101b811e0) target 101b812f0 deleted: name=VAG_Skill_B_M
18:12:04.376_M_I_ [17:0d] VQ(101b906c0) deleted
18:12:04.376_M_I_ [17:08] VQ(101b906c0) target 1019c53d0 deleted: name=VAG_Skill_B_H
18:12:06.016_M_I_ [10:1d] PULSE (calls: 1(1)=1+0-0, targets=2, time=1184749925, mem=0,90014,1395,14,123,0)
18:12:08.006_M_I_ [10:1d] PULSE (calls: 1(1)=1+0-0, targets=2, time=1184749927, mem=0,90014,1395,14,123,0)
18:12:09.776_M_I_ [17:0d] VQ(101b905b0) deleted
18:12:09.776_M_I_ [17:08] VQ(101b905b0) target 101b9dc20 deleted: name=20717_Switch_Tokyo
18:12:10.006_M_I_ [10:1d] PULSE (calls: 1(1)=1+0-0, targets=1, time=1184749929, mem=0,90004,1393,13,123,0)
18:12:10.756_M_I_ [17:0d] VQ(101b9a7d0) deleted
18:12:10.756_M_I_ [17:08] VQ(101b9a7d0) target 101b9de60 deleted: name=20719_Switch_Tokyo
18:12:12.006_M_I_ [10:1d] PULSE (calls: 1(1)=1+0-0, targets=0, time=1184749931, mem=0,89994,1391,12,123,0)
18:12:14.006_M_I_ [10:1d] PULSE (calls: 1(1)=1+0-0, targets=0, time=1184749933, mem=0,89994,1391,12,123,0)
18:12:16.016_M_I_ [10:1d] PULSE (calls: 1(1)=1+0-0, targets=0, time=1184749935, mem=0,89994,1391,12,123,0)
18:12:18.006_M_I_ [10:1d] PULSE (calls: 1(1)=1+0-0, targets=0, time=1184749937, mem=0,89994,1391,12,123,0)
18:12:20.006_M_I_ [10:1d] PULSE (calls: 1(1)=1+0-0, targets=0, time=1184749939, mem=0,89994,1391,12,123,0)
18:12:22.006_M_I_ [10:1d] PULSE (calls: 1(1)=1+0-0, targets=0, time=1184749941, mem=0,89994,1391,12,123,0)
18:12:24.006_M_I_ [10:1d] PULSE (calls: 1(1)=1+0-0, targets=0, time=1184749943, mem=0,89994,1391,12,123,0)
18:12:26.016_M_I_ [10:1d] PULSE (calls: 1(1)=1+0-0, targets=0, time=1184749945, mem=0,89994,1391,12,123,0)
18:12:28.006_M_I_ [10:1d] PULSE (calls: 1(1)=1+0-0, targets=0, time=1184749947, mem=0,89994,1391,12,123,0)
18:12:30.006_M_I_ [10:1d] PULSE (calls: 1(1)=1+0-0, targets=0, time=1184749949, mem=0,89994,1391,12,123,0)
18:12:32.006_M_I_ [10:1d] PULSE (calls: 1(1)=1+0-0, targets=0, time=1184749951, mem=0,89994,1391,12,123,0)
18:12:34.006_M_I_ [10:1d] PULSE (calls: 1(1)=1+0-0, targets=0, time=1184749953, mem=0,89994,1391,12,123,0)
18:12:36.016_M_I_ [10:1d] PULSE (calls: 1(1)=1+0-0, targets=0, time=1184749955, mem=0,89994,1391,12,123,0)
18:12:38.006_M_I_ [10:1d] PULSE (calls: 1(1)=1+0-0, targets=0, time=1184749957, mem=0,89994,1391,12,123,0)
18:12:40.006_M_I_ [10:1d] PULSE (calls: 1(1)=1+0-0, targets=0, time=1184749959, mem=0,89994,1391,12,123,0)
18:12:42.006_M_I_ [10:1d] PULSE (calls: 1(1)=1+0-0, targets=0, time=1184749961, mem=0,89994,1391,12,123,0)
18:12:44.006_M_I_ [10:1d] PULSE (calls: 1(1)=1+0-0, targets=0, time=1184749963, mem=0,89994,1391,12,123,0)
18:12:46.015_M_I_ [10:1d] PULSE (calls: 1(1)=1+0-0, targets=0, time=1184749965, mem=0,89994,1391,12,123,0)
18:12:48.006_M_I_ [10:1d] PULSE (calls: 1(1)=1+0-0, targets=0, time=1184749967, mem=0,89994,1391,12,123,0)
18:12:50.006_M_I_ [10:1d] PULSE (calls: 1(1)=1+0-0, targets=0, time=1184749969, mem=0,89994,1391,12,123,0)
18:12:51.617_G_I_ [01:0b] look for hanged interactions: 1 at all now
18:12:51.617_G_I_ [01:0b] there are 1 calls in progress now
_G_I_ Version: 7.1.001.03
root 1009b7830
18:12:52.006_M_I_ [10:1d] PULSE (calls: 1(1)=1+0-0, targets=0, time=1184749971, mem=0,89994,1391,12,123,0)
18:12:54.006_M_I_ [10:1d] PULSE (calls: 1(1)=1+0-0, targets=0, time=1184749973, mem=0,89994,1391,12,123,0)
18:12:56.016_M_I_ [10:1d] PULSE (calls: 1(1)=1+0-0, targets=0, time=1184749975, mem=0,89994,1391,12,123,0)
18:12:58.006_M_I_ [10:1d] PULSE (calls: 1(1)=1+0-0, targets=0, time=1184749977, mem=0,89994,1391,12,123,0)
18:12:58.726_M_I_0092017974d65052 [07:0d] default priority 1
18:12:58.726_M_I_0092017974d65052 [13:05] current priority for all VQ(s) increased by 1
18:12:58.726_M_I_0092017974d65052 [13:03] call (virtual queue 101b7ef30, id=294, priority 0) doesn't wait for VQ 101b842e0 (name="VQ_Skill_A_Tokyo") now
18:12:58.727_M_I_0092017974d65052 [13:03] call (virtual queue 101b7ef30, id=294, priority 1, time 1184749920.747) waits for VQ 101b842e0 (name="VQ_Skill_A_Tokyo") now
18:12:58.727_M_I_0092017974d65052 [13:03] call (virtual queue 101b809f0, id=293, priority 0) doesn't wait for VQ 101b8b6e0 (name="VQ_Skill_A_Tokyo") now
18:12:58.727_M_I_0092017974d65052 [13:03] call (virtual queue 101b809f0, id=293, priority 1, time 1184749919.737) waits for VQ 101b8b6e0 (name="VQ_Skill_A_Tokyo") now
18:12:58.727_M_I_0092017974d65052 [13:03] call (virtual queue 101ba2f40, id=292, priority 0) doesn't wait for VQ 101b7ed20 (name="VQ_Skill_A_Tokyo") now
18:12:58.727_M_I_0092017974d65052 [13:03] call (virtual queue 101ba2f40, id=292, priority 1, time 1184749918.727) waits for VQ 101b7ed20 (name="VQ_Skill_A_Tokyo") now
18:13:00.006_M_I_ [10:1d] PULSE (calls: 1(1)=1+0-0, targets=0, time=1184749979, mem=0,89994,1391,12,123,0)
18:13:02.006_M_I_ [10:1d] PULSE (calls: 1(1)=1+0-0, targets=0, time=1184749981, mem=0,89994,1391,12,123,0)
18:13:04.006_M_I_ [10:1d] PULSE (calls: 1(1)=1+0-0, targets=0, time=1184749983, mem=0,89994,1391,12,123,0)
18:13:06.016_M_I_ [10:1d] PULSE (calls: 1(1)=1+0-0, targets=0, time=1184749985, mem=0,89994,1391,12,123,0)
18:13:08.006_M_I_ [10:1d] PULSE (calls: 1(1)=1+0-0, targets=0, time=1184749987, mem=0,89994,1391,12,123,0)
18:13:10.006_M_I_ [10:1d] PULSE (calls: 1(1)=1+0-0, targets=0, time=1184749989, mem=0,89994,1391,12,123,0)
18:13:12.006_M_I_ [10:1d] PULSE (calls: 1(1)=1+0-0, targets=0, time=1184749991, mem=0,89994,1391,12,123,0)
18:13:14.006_M_I_ [10:1d] PULSE (calls: 1(1)=1+0-0, targets=0, time=1184749993, mem=0,89994,1391,12,123,0)
18:13:16.015_M_I_ [10:1d] PULSE (calls: 1(1)=1+0-0, targets=0, time=1184749995, mem=0,89994,1391,12,123,0)
18:13:18.006_M_I_ [10:1d] PULSE (calls: 1(1)=1+0-0, targets=0, time=1184749997, mem=0,89994,1391,12,123,0)
18:13:20.006_M_I_ [10:1d] PULSE (calls: 1(1)=1+0-0, targets=0, time=1184749999, mem=0,89994,1391,12,123,0)
18:13:22.006_M_I_ [10:1d] PULSE (calls: 1(1)=1+0-0, targets=0, time=1184750001, mem=0,89994,1391,12,123,0)
18:13:24.006_M_I_ [10:1d] PULSE (calls: 2(2)=1+4-3, targets=2, time=1184750003, mem=0,90077,1396,16,124,0)
18:13:24.376_B_I_0092017974d65053 [14:apply_treatment_postproc] treatment timer is activated in APPLIED state
18:13:24.377_M_I_ [10:06] tenant Resources Agent <1704>: statistic ##state with server StatServer_Oak <request=3, status=2> asked
_M_I_ [17:09] VQ(101b90640), target 101b90750: GroupAgents <VAG_Skill_B_H> connected to state Agent 1704
request to 65202(--) message RequestDistributeEvent
AttributeExtensions [82] 00 04 00 00..
'SIGNATURE' 'router'
'NAME' 'URS1'
'VERSION' 'Version: 7.1.001.03'
'CLUSTER' 'URS1'
AttributeUserData [686] 00 1B 00 00..
'DNIS_NUM' '20003'
'CUSTID' 'CID:'
'RRequestedSkills'(list)
'CustomerSegment' 'default'
'ServiceType' 'default'
'ServiceObjective' ''
'DEBUG_GENESYS_DESTINATION' 'Switch_Tokyo'
'Site_ext' 'Tokyo'
'Skillname' 'Skill_B'
'SKILL_CODE' '21030'
'VAR_sTarget_val' '20841_Switch_Tokyo'
'VAR_sStatServer' 'StatServer'
'SubDN' '20777_Switch_Tokyo'
'RTargetRuleSelected' ''
'RTargetTypeSelected' '100'
'RTargetObjectSelected' ''
'RTargetAgentSelected' ''
'RTargetPlaceSelected' ''
'RTenant' 'Resources'
'RStrategyName' 'jump_to_routing'
'RTargetUsed'(list) 'TargetType' '100'
'TargetName' ''
'RRequestedSkillCombination' ''
'ReasonToCXU' 'noANI'
'VAR_nResult' '0'
'PegTD' 1
'ScriptName' ''
'VAR_sVQ' 'VQ_Skill_B_Tokyo'
AttributeDNIS '20003'
AttributeOtherDNRole 1
AttributeOtherDN '6001'
AttributeThisDNRole 2
AttributeThisQueue 'VQ_Skill_B'
AttributeThisDN 'VQ_Skill_B'
AttributeCallID 93
AttributeConnID 0092017974d65053
AttributeCustomerID 'Resources'
AttributeReferenceID 4294967295
AttributeUserEvent EventQueued
..sent to ctisssvp:5080(fd=16)
_M_I_0092017974d65053 [17:0e] virtual queue "VQ_Skill_B_Tokyo"(id=298), VQ 101b81270: target for routing was NOT SELECTED (0 1 0 0)
18:13:25.388_I_I_0092017974d65053 [09:04] <<<<<<<<<<<<suspend interpretator(WAIT_FOR_DN), timers:11000
18:13:25.388_B_I_0092017974d65053 [07:09] start chain of treatments
received from 65202(--)ctisssvp:5080(fd=16) message EventACK
AttributeCustomerID 'Resources'
AttributeTimeinuSecs 404057
AttributeTimeinSecs 1184750005 (18:13:25)
AttributeReferenceID 4294967295
AttributeThisDN 'VQ_Skill_B'
AttributeUserEvent RequestDistributeEvent
18:13:26.016_M_I_ [10:1d] PULSE (calls: 2(2)=2+0-0, targets=2, time=1184750005, mem=0,90105,1400,18,124,0)
18:13:26.396_I_I_0092017974d65053 [07:08] wait time is over
18:13:26.396_I_I_0092017974d65053 [09:05] >>>>>>>>>>>>resume interpretator(0)
_I_I_0092017974d65053 [09:04] ASSIGN: sTarget_out(LOCAL) <- STRING: return:timeout
_I_I_0092017974d65053 [09:04] ASSIGN: nResult(LOCAL) <- INTEGER: 0
18:13:26.396_I_I_0092017974d65053 [07:48] suspend function
18:13:26.396_I_I_0092017974d65053 [09:04] <<<<<<<<<<<<suspend interpretator(JUMPING), timers:00001
18:13:26.396_I_I_0092017974d65053 [07:49] resume function
_I_I_0092017974d65053 [07:44] jump to strategy *0x65*main_routing
18:13:26.396_I_I_0092017974d65053 [09:05] >>>>>>>>>>>>resume interpretator(0)
_I_I_0092017974d65053 [09:04] ASSIGN: nResult(LOCAL) <- INTEGER: 0
_I_I_0092017974d65053 [09:04] ASSIGN: sTarget_val(LOCAL) <- STRING: return:timeout
_I_I_0092017974d65053 [09:04] ASSIGN: sPotentialTarget(LOCAL) <- STRING: VAG_Skill_B_L@StatServer_Oak.GA
18:13:26.396_I_I_0092017974d65053 [07:48] suspend function
18:13:26.396_I_I_0092017974d65053 [09:04] <<<<<<<<<<<<suspend interpretator(JUMPING), timers:00001
18:13:26.396_I_I_0092017974d65053 [07:49] resume function
_I_I_0092017974d65053 [07:43] call strategy *0x65*sub_queue_call
18:13:26.396_I_I_0092017974d65053 [09:06] >>>>>>>>>>>>start interpretator
_I_I_0092017974d65053 [07:46] no error mode for this call
_I_I_0092017974d65053 [09:04] ASSIGN: __Return(SCRIPT) <- STRING:
_I_I_0092017974d65053 [09:04] ASSIGN: __DBReturn(SCRIPT) <- STRING:
_I_I_0092017974d65053 [09:04] ASSIGN: __DBStrReturn(SCRIPT) <- STRING:
_I_I_0092017974d65053 [09:04] ASSIGN: __TargetVar(SCRIPT) <- STRING:
_I_I_0092017974d65053 [09:04] ASSIGN: nTimeout_in(LOCAL) <- INTEGER: 9999
_I_I_0092017974d65053 [09:04] ASSIGN: sTarget_in(LOCAL) <- STRING: VAG_Skill_B_L@StatServer_Oak.GA
_I_I_0092017974d65053 [09:04] ASSIGN: sVQ_in(LOCAL) <- STRING: VQ_Skill_B_Tokyo
18:13:26.397_I_I_0092017974d65053 [07:26] HERE IS TARGETS
TARGETS: VAG_Skill_B_L@StatServer_Oak.GA
18:13:26.397_M_I_0092017974d65053 [13:01] current virtual queue: 101b79cd0 id=299, nVQ=1|39868980-101731c80, priority=0, time=1184750006.397
18:13:26.397_M_I_ [17:0c] VQ(101b96230) created: type=0, tenant=Resources
==========================================
_M_I_ [17:06] VQ(101b96230) target 101b96340 added: name=VAG_Skill_B_L, location=StatServer_Oak, type=GA, state=##state, activity=unknown
18:13:26.397_M_I_ [10:06] tenant Resources GroupAgents <VAG_Skill_B_L>: statistic ##content with server StatServer_Oak <request=15, status=2> asked
_M_I_ [17:05] VQ(101b96230) target (name=VAG_Skill_B_L, location=StatServer_Oak, type=GA) synchronizing
18:13:26.397_M_I_ [10:06] tenant Resources Agent <1701>: statistic ##state with server StatServer_Oak <request=17, status=2> asked
_M_I_ [17:09] VQ(101b96230), target 101b96340: GroupAgents <VAG_Skill_B_L> connected to state Agent 1701
18:13:26.397_M_I_ [10:06] tenant Resources Agent <1602>: statistic ##state with server StatServer_Oak <request=7, status=2> asked
_M_I_ [17:09] VQ(101b96230), target 101b96340: GroupAgents <VAG_Skill_B_L> connected to state Agent 1602
18:13:26.397_M_I_ [10:06] tenant Resources Agent <1702>: statistic ##state with server StatServer_Oak <request=19, status=2> asked
_M_I_ [17:09] VQ(101b96230), target 101b96340: GroupAgents <VAG_Skill_B_L> connected to state Agent 1702
18:13:26.397_M_I_ [10:06] tenant Resources Agent <1703>: statistic ##state with server StatServer_Oak <request=9, status=2> asked
_M_I_ [17:09] VQ(101b96230), target 101b96340: GroupAgents <VAG_Skill_B_L> connected to state Agent 1703
18:13:26.397_M_I_ [10:06] tenant Resources Agent <1802>: statistic ##state with server StatServer_Oak <request=21, status=2> asked
_M_I_ [17:09] VQ(101b96230), target 101b96340: GroupAgents <VAG_Skill_B_L> connected to state Agent 1802
18:13:26.397_M_I_ [10:06] tenant Resources Agent <1604>: statistic ##state with server StatServer_Oak <request=11, status=2> asked
_M_I_ [17:09] VQ(101b96230), target 101b96340: GroupAgents <VAG_Skill_B_L> connected to state Agent 1604
18:13:26.397_M_I_ [10:06] tenant Resources Agent <1704>: statistic ##state with server StatServer_Oak <request=3, status=2> asked
_M_I_ [17:09] VQ(101b96230), target 101b96340: GroupAgents <VAG_Skill_B_L> connected to state Agent 1704
18:13:26.397_M_I_ [10:06] tenant Resources Agent <7999998>: statistic ##state with server StatServer_Oak <request=13, status=2> asked
_M_I_ [17:09] VQ(101b96230), target 101b96340: GroupAgents <VAG_Skill_B_L> connected to state Agent 7999998
18:13:26.397_M_I_0092017974d65053 [13:02] entering virtual queue "VQ_Skill_B_Tokyo"
_M_I_ [17:0f] VQ(101b96230) [at all 8 0] 1 target(s), flag=a, guid: 0Resources|VQ_Skill_B_Tokyo|1|-1|1|0|""|||01StatAgentLoading|00{}[]VAG_Skill_B_L@StatServer_Oak.GA
18:13:26.397_M_I_0092017974d65053 [13:03] call (virtual queue 101b79cd0, id=299, priority 0, time 1184750006.397) waits for VQ 101b96230 (name="VQ_Skill_B_Tokyo") now
request to 65202(--) message RequestDeletePair
AttributeReferenceID 2726
AttributeDataKey 'RTargetAgentGroup'
AttributeConnID 0092017974d65053
AttributeThisDN '20719'
..sent to ctisssvp:5080(fd=16)
request to 65202(--) message RequestAttachUserData
AttributeReferenceID 2727
AttributeUserData [101] 00 03 00 00..
'RTargetAgentGroup' 'VAG_Skill_B_L'
'RTargetAgentGroup' 'VAG_Skill_B_M'
'RTargetAgentGroup' 'VAG_Skill_B_H'
AttributeConnID 0092017974d65053
AttributeThisDN '20719'
..sent to ctisssvp:5080(fd=16)
request to 65202(--) message RequestDistributeEvent
AttributeExtensions [82] 00 04 00 00..
'SIGNATURE' 'router'
'NAME' 'URS1'
'VERSION' 'Version: 7.1.001.03'
'CLUSTER' 'URS1'
AttributeUserData [785] 00 1E 00 00..
'DNIS_NUM' '20003'
'CUSTID' 'CID:'
'RRequestedSkills'(list)
'CustomerSegment' 'default'
'ServiceType' 'default'
'ServiceObjective' ''
'DEBUG_GENESYS_DESTINATION' 'Switch_Tokyo'
'Site_ext' 'Tokyo'
'Skillname' 'Skill_B'
'SKILL_CODE' '21030'
'VAR_sTarget_val' '20841_Switch_Tokyo'
'VAR_sStatServer' 'StatServer'
'SubDN' '20777_Switch_Tokyo'
'RTargetRuleSelected' ''
'RTargetTypeSelected' '100'
'RTargetObjectSelected' ''
'RTargetAgentSelected' ''
'RTargetPlaceSelected' ''
'RTenant' 'Resources'
'RStrategyName' 'jump_to_routing'
'RTargetUsed'(list) 'TargetType' '100'
'TargetName' ''
'RRequestedSkillCombination' ''
'ReasonToCXU' 'noANI'
'VAR_nResult' '0'
'PegTD' 1
'ScriptName' ''
'VAR_sVQ' 'VQ_Skill_B_Tokyo'
'RTargetAgentGroup' 'VAG_Skill_B_L'
'RTargetAgentGroup' 'VAG_Skill_B_M'
'RTargetAgentGroup' 'VAG_Skill_B_H'
AttributeDNIS '20003'
AttributeOtherDNRole 1
AttributeOtherDN '6001'
AttributeThisDNRole 2
AttributeThisQueue 'VQ_Skill_B'
AttributeThisDN 'VQ_Skill_B'
AttributeCallID 93
AttributeConnID 0092017974d65053
AttributeCustomerID 'Resources'
AttributeReferenceID 4294967295
AttributeUserEvent EventAttachedDataChanged
..sent to ctisssvp:5080(fd=16)
_M_I_0092017974d65053 [17:0e] virtual queue "VQ_Skill_B_Tokyo"(id=299), VQ 101b96230 (1 targets): SELECT MAX by statistic StatAgentLoading(random )
_M_I_0092017974d65053 [17:0b] VQ 101b96230 target "VAG_Skill_B_L" (#1, 8-7 components): SELECT MAX by statistic StatAgentLoading(random)
_M_I_0092017974d65053 [17:0b] component #1 1701: logged out
_M_I_0092017974d65053 [17:0b] component #2 1602: not ready
_M_I_0092017974d65053 [17:0b] component #3 1702: logged out
_M_I_0092017974d65053 [17:0b] component #4 1703: logged out
_M_I_0092017974d65053 [17:0b] component #5 1802: logged out
_M_I_0092017974d65053 [17:0b] component #6 1604: logged out
_M_I_0092017974d65053 [17:0b] component #7 1704: logged out
_M_I_0092017974d65053 [17:0b] component #8 7999998: logged out
_M_I_0092017974d65053 [17:0b] VQ 101b96230 target "VAG_Skill_B_L": component was NOT SELECTED (7 1 0)
_M_I_0092017974d65053 [17:0e] target VAG_Skill_B_L: not ready passed
_M_I_0092017974d65053 [17:0e] virtual queue "VQ_Skill_B_Tokyo"(id=299), VQ 101b96230: target for routing was NOT SELECTED (0 0 0 1)
result of SelectDN: STRING: return:timeout
_I_I_0092017974d65053 [09:04] ASSIGN: sTarget_out(LOCAL) <- STRING: return:timeout
_I_I_0092017974d65053 [07:27] HERE IS WAIT FOR DN (9999 sec)
18:13:26.398_B_I_0092017974d65053 [07:27] delay treatments for 0 msec
18:13:26.398_M_I_0092017974d65053 [10:1f] pulse for one call
_T_I_0092017974d65053 [0E:19] check call routing states: state=10 delivery=0 treatment=0 held=0 reserving=0 - true
_M_I_0092017974d65053 [17:0e] virtual queue "VQ_Skill_B_Tokyo"(id=297), VQ 101b90640 (1 targets): SELECT MAX by statistic StatAgentLoading(random )
_M_I_0092017974d65053 [17:0e] target VAG_Skill_B_H: empty(1-1 components) passed
_M_I_0092017974d65053 [17:0e] virtual queue "VQ_Skill_B_Tokyo"(id=297), VQ 101b90640: target for routing was NOT SELECTED (0 1 0 0)
_M_I_0092017974d65053 [17:0e] virtual queue "VQ_Skill_B_Tokyo"(id=298), VQ 101b81270 (1 targets): SELECT MAX by statistic StatAgentLoading(random )
_M_I_0092017974d65053 [17:0e] target VAG_Skill_B_M: empty(4-4 components) passed
_M_I_0092017974d65053 [17:0e] virtual queue "VQ_Skill_B_Tokyo"(id=298), VQ 101b81270: target for routing was NOT SELECTED (0 1 0 0)
_M_I_0092017974d65053 [17:0e] virtual queue "VQ_Skill_B_Tokyo"(id=299), VQ 101b96230 (1 targets): SELECT MAX by statistic StatAgentLoading(random )
_M_I_0092017974d65053 [17:0b] VQ 101b96230 target "VAG_Skill_B_L" (#1, 8-7 components): SELECT MAX by statistic StatAgentLoading(random)
_M_I_0092017974d65053 [17:0b] component #1 1701: logged out
_M_I_0092017974d65053 [17:0b] component #2 1602: not ready
_M_I_0092017974d65053 [17:0b] component #3 1702: logged out
_M_I_0092017974d65053 [17:0b] component #4 1703: logged out
_M_I_0092017974d65053 [17:0b] component #5 1802: logged out
_M_I_0092017974d65053 [17:0b] component #6 1604: logged out
_M_I_0092017974d65053 [17:0b] component #7 1704: logged out
_M_I_0092017974d65053 [17:0b] component #8 7999998: logged out
_M_I_0092017974d65053 [17:0b] VQ 101b96230 target "VAG_Skill_B_L": component was NOT SELECTED (7 1 0)
_M_I_0092017974d65053 [17:0e] target VAG_Skill_B_L: not ready passed
_M_I_0092017974d65053 [17:0e] virtual queue "VQ_Skill_B_Tokyo"(id=299), VQ 101b96230: target for routing was NOT SELECTED (0 0 0 1)
18:13:26.398_I_I_0092017974d65053 [09:04] <<<<<<<<<<<<suspend interpretator(WAIT_FOR_DN), timers:11000
18:13:26.398_B_I_0092017974d65053 [07:09] start chain of treatments
received from 65202(--)ctisssvp:5080(fd=16) message EventACK
AttributeCustomerID 'Resources'
AttributeTimeinuSecs 414564
AttributeTimeinSecs 1184750006 (18:13:26)
AttributeReferenceID 4294967295
AttributeThisDN 'VQ_Skill_B'
AttributeUserEvent RequestDistributeEvent
18:13:28.006_M_I_ [10:1d] PULSE (calls: 2(2)=2+0-0, targets=2, time=1184750007, mem=0,90117,1402,19,124,0)
18:13:29.267_M_I_ [10:17] CHANGE OF STATE (tenant Resources object 1602 statserver StatServer_Oak)
_M_I_ [10:17] ready DN(switch Switch_Tokyo, number 6002, type 2, time= 1184750009) for agent 1602, place Place_6002_Tokyo, status WaitForNextCall time= 1184750009
_M_I_ [10:17] tenant Resources object 1602 statserver StatServer_Oak: 1 ready DNs reported
18:13:29.267_M_I_ [10:15] check queue for statobject <1602> type <Agent>
WAITING CALLS: 0092017974d65053
_M_I_0000000000000000 [10:20] try to route to agent "1602" (place "Place_6002_Tokyo", 1 ready DNs reported)
18:13:29.267_M_I_ [10:0a] object Place_6002_Tokyo|Resources| is unblocked
_M_I_ [10:0b] agent 1602, place Place_6002_Tokyo, switch dn isn't blocked absolutely(0)
_M_I_0092017974d65053 [10:21] try to route to agent "1602" (place "Place_6002_Tokyo", 1 ready DNs reported)
_T_I_0092017974d65053 [0E:19] check call routing states: state=10 delivery=0 treatment=0 held=0 reserving=0 - true
18:13:29.267_M_I_ [10:0a] object 1602|Resources| is unblocked
18:13:29.267_M_I_ [10:0a] object 6002|Switch_Tokyo| is unblocked
_M_I_0092017974d65053 [01:0d] attempt to route (1)
_T_I_0092017974d65053 [0E:19] check call routing states: state=10 delivery=0 treatment=0 held=0 reserving=0 - true
========== DN Information ===========
DN Number 6002
Agent Name 1602
Place Name Place_6002_Tokyo
Agent Login 1602
Switch Name Switch_Tokyo
DN Type 2
=====================================
====================== Target Information =====================
Target Name < VAG_Skill_B_L >
Target Location < StatServer_Oak >
Target Type < GA >
STATE MAPPING INFO - STATISTIC < ##state >:
Stat Server DN info available
Modelling Statistic : NOT available
==============================================================
18:13:29.267_X_I_0092017974d65053 [06:04] send to tserver TServer_G3r_Tokyo_2 TReserveAgent (dn=6002@Switch_Tokyo, name=1602, place=Place_6002_Tokyo, priorities=0,3,602)
request to 65202(--) message RequestReserveAgent
AttributeReferenceID 2728
AttributeExtensions [71] 00 03 01 00..
'ar-priority-1' 3
'ar-priority-2' 602
'CUSTOMER_ID' 'Resources'
AttributePriority 0
AttributeTimeout 15000
AttributePlace 'Place_6002_Tokyo'
AttributeAgentID '1602'
AttributeThisDN '6002@Switch_Tokyo'
..sent to ctisssvp:5080(fd=16)
18:13:29.268_M_I_0092017974d65053 [10:1b] blocking: agent 1602, place Place_6002_Tokyo, switch Switch_Tokyo dn 6002 is blocked for 15 sec by 275 expired 1184750024
_M_I_0092017974d65053 [10:21] LAUNCHED (dn=6002)
18:13:29.268_M_I_ [10:17] CHANGE OF STATE (tenant Resources object 1602 statserver StatServer)
_M_I_ [10:17] ready DN(switch Switch_Tokyo, number 6002, type 2, time= 1184750009) for agent 1602, place Place_6002_Tokyo, status WaitForNextCall time= 1184750009
_M_I_ [10:17] tenant Resources object 1602 statserver StatServer: 1 ready DNs reported
18:13:29.268_M_I_ [10:15] check queue for statobject <1602> type <Agent>
WAITING CALLS: 0092017974d65052 0092017974d65052
_M_I_0000000000000000 [10:20] try to route to agent "1602" (place "Place_6002_Tokyo", 1 ready DNs reported)
_M_I_0000000000000000 [10:20] try to route to agent "1602" (place "Place_6002_Tokyo", 1 ready DNs reported)
_M_I_ [10:0b] agent 1602, place Place_6002_Tokyo, switch dn isn't blocked absolutely(275)
_M_I_0092017974d65052 [10:21] try to route to agent "1602" (place "Place_6002_Tokyo", 1 ready DNs reported)
_T_I_0092017974d65052 [0E:19] check call routing states: state=10 delivery=0 treatment=0 held=0 reserving=0 - true
_M_I_0092017974d65052 [10:21] agent 1602, place Place_6002_Tokyo, switch dn is blocked with call 275 (15 sec from 1184750009)
_M_I_0092017974d65052 [10:21] try to route to agent "1602" (place "Place_6002_Tokyo", 1 ready DNs reported)
_T_I_0092017974d65052 [0E:19] check call routing states: state=10 delivery=0 treatment=0 held=0 reserving=0 - true
_M_I_0092017974d65052 [10:21] agent 1602, place Place_6002_Tokyo, switch dn is blocked with call 275 (15 sec from 1184750009)
received from 65202(--)ctisssvp:5080(fd=16) message EventAgentReserved
AttributeCustomerID 'Resources'
AttributeTimeinuSecs 376601
AttributeTimeinSecs 1184750009 (18:13:29)
AttributeTimeout 15000
AttributePlace 'Place_6002_Tokyo'
AttributeAgentID '1602'
AttributeThisDN '6002@Switch_Tokyo'
AttributeReferenceID 2728
[/code]