With another routing strategy based on skills, in urs logfile i can see that my agents are not ready, but my agents are online.
received from 65200(TServer_SIP)videocc:7650(fd=) message EventQueued
AttributeOtherDN '1112'
AttributeOtherDNRole 1
AttributeCallState 0
AttributeCallType 1
AttributePropagatedCallType 1
AttributeCallID 15
AttributeConnID 006b01f32ae3400f
AttributeCallUUID 'KR8OMOG55T2H19IGOJDVUCQC1C00000F'
AttributeDNIS '1002'
AttributeThisDN '1002'
AttributeThisDNRole 2
AttributeThisQueue '1002'
AttributeExtensions [23] 00 01 01 00..
'BusinessCall' 1
AttributeTimeinSecs 1312364737 (11:45:37)
AttributeTimeinuSecs 312000
AttributeEventSequenceNumber 000000000000010b
11:45:37.312_T_I_0000000000000000 [14:0c] EventQueued is received for tserver TServer_SIP[SIPSwitch] (this dn=1002)
_T_I_006b01f32ae3400f [01:11] connid 006b01f32ae3400f is bound to the call 12-0195e420
11:45:37.312_I_I_006b01f32ae3400f [01:01] call (12-0195e420) for Resources created (del 0)
_T_I_006b01f32ae3400f [14:09] add DN TServer_SIP 1002 <1002@SIPSwitch> (CDN 57 006b01f32ae3400f) to the call 12-0195e420 truly:22
received from 65200(TServer_SIP)videocc:7650(fd=) message EventRouteRequest
AttributeCallState 0
AttributeCallType 1
AttributePropagatedCallType 1
AttributeCallID 15
AttributeConnID 006b01f32ae3400f
AttributeCallUUID 'KR8OMOG55T2H19IGOJDVUCQC1C00000F'
AttributeDNIS '1002'
AttributeThisDN '1002'
AttributeThisDNRole 2
AttributeThisQueue '1002'
AttributeOtherDN '1112'
AttributeOtherDNRole 1
AttributeExtensions [23] 00 01 01 00..
'BusinessCall' 1
AttributeTimeinSecs 1312364737 (11:45:37)
AttributeTimeinuSecs 312000
AttributeEventSequenceNumber 000000000000010c
11:45:37.312_T_I_006b01f32ae3400f [14:0c] EventRouteRequest is received for tserver TServer_SIP[SIPSwitch] (this dn=1002)
_T_I_006b01f32ae3400f [14:09] add DN TServer_SIP 1002 <1002@SIPSwitch> (CDN 71 006b01f32ae3400f) to the call 12-0195e420 truly:11
_T_I_006b01f32ae3400f [14:08] check delayed proc
_I_I_006b01f32ae3400f [14:33] strategy: *0x65*Skill (1019064432) is attached to the call
11:45:37.312 Int 20001 interaction 006b01f32ae3400f is started
_I_I_006b01f32ae3400f [01:14] current call classification: media=voice(100), service=default(200), segment=default(300)
11:45:37.312_I_I_006b01f32ae3400f [09:06] >>>>>>>>>>>>start interpretator()
_I_I_006b01f32ae3400f [09:04] ASSIGN: __Return(SCRIPT) <- STRING: ""
_I_I_006b01f32ae3400f [07:46] no error mode for this call
_I_I_006b01f32ae3400f [09:04] ASSIGN: __Return(SCRIPT) <- STRING: ""
_I_I_006b01f32ae3400f [09:04] ASSIGN: __DBReturn(SCRIPT) <- STRING: ""
_I_I_006b01f32ae3400f [09:04] ASSIGN: __DBStrReturn(SCRIPT) <- STRING: ""
_I_I_006b01f32ae3400f [09:04] ASSIGN: __TargetVar(SCRIPT) <- STRING: ""
_I_I_006b01f32ae3400f [07:14] expression translating: Angol < 4 -> Angol < 4
11:45:37.312_I_I_006b01f32ae3400f [07:07] HERE IS TARGETS
TARGETS: ?:Angol < 4@StatServer.GA
11:45:37.312_M_I_ [17:0c] VQ 01d74780 created: type=0, tenant=Resources
==========================================
_M_I_ [17:06] VQ 01d74780 Target 01d731a0 added: name=?:Angol < 4, location=StatServer, type=GA, state=##state, activity=unknown
11:45:37.312_M_I_ [10:06] STATOBJECT(01d74550 -1 2) tenant=Resources name=?:Angol < 4@StatServer.GA: statistic <##content> asked
_M_I_ [17:05] VQ 01d74780 target (name=?:Angol < 4, location=StatServer, type=GA) synchronizing
11:45:37.312_M_I_ [10:06] STATOBJECT(01d75e90 -1 0) tenant=Resources name=C1000@StatServer.A: statistic <##state> asked
_M_I_ [17:09] VQ 01d74780 Target "?:Angol < 4"(01d731a0): connected to state Agent C1000
11:45:37.312_M_I_ [10:06] STATOBJECT(01d759b0 -1 0) tenant=Resources name=B1000@StatServer.A: statistic <##state> asked
_M_I_ [17:09] VQ 01d74780 Target "?:Angol < 4"(01d731a0): connected to state Agent B1000
11:45:37.312_M_I_ [10:06] STATOBJECT(01d754c0 -1 0) tenant=Resources name=A1000@StatServer.A: statistic <##state> asked
_M_I_ [17:09] VQ 01d74780 Target "?:Angol < 4"(01d731a0): connected to state Agent A1000
_M_I_ [17:0f] VQ 01d74780 [at all 1 0 0] 1 Target(s), flag=808a, guid: 0Resources||1|d-1|1|00|||||00|00{}{}[]?:Angol < 4@StatServer.GA
11:45:37.312_M_I_006b01f32ae3400f [13:01] current virtual queue: 01d74820 id=12, nVQ=1-113a190, priority=0, time=1312364737.312
11:45:37.312_M_I_006b01f32ae3400f [17:11] VQ 01d74780 first available call: 006b01f32ae3400f, reason=binding
11:45:37.312_M_I_006b01f32ae3400f [13:03] call (virtual queue 01d74820, id=12, priority 0, time 1312364737.312) waits for VQ 01d74780 (name="") now
request to 65200(TServer_SIP) message RequestAttachUserData
AttributeReferenceID 58
AttributeUserData [35] 00 01 00 00..
'RTargetAgentGroup' '?:Angol < 4'
AttributeConnID 006b01f32ae3400f
AttributeThisDN '1002'
..sent to videocc:7650(fd=556)
11:45:37.312_M_I_006b01f32ae3400f [17:0e] VQ 01d74780 (virtual queue "", id=12), (1 Targets): SELECT ANY by statistic <NONE>(random )
_M_I_006b01f32ae3400f [17:0b] VQ 01d74780 Target "?:Angol < 4"(01d731a0) #1, (3-0 Components): SELECT ANY by statistic <NONE>(random ): MEM(0 2830) 0 0
_M_I_006b01f32ae3400f [17:0b] VQ 01d74780 Target "?:Angol < 4"(01d731a0) Component #1 C1000: not ready
_M_I_006b01f32ae3400f [17:0b] VQ 01d74780 Target "?:Angol < 4"(01d731a0) Component #2 B1000: not ready
_M_I_006b01f32ae3400f [17:0b] VQ 01d74780 Target "?:Angol < 4"(01d731a0) Component #3 A1000: not ready
_M_I_006b01f32ae3400f [17:0b] VQ 01d74780 Target "?:Angol < 4"(01d731a0): Component for routing was NOT SELECTED (0 3 0 0)
_M_I_006b01f32ae3400f [17:0e] VQ 01d74780 Target "?:Angol < 4"(01d731a0): not ready passed
_M_I_006b01f32ae3400f [17:0e] VQ 01d74780 (virtual queue "" id=12): Target for routing was NOT SELECTED (0 0 0 1 0)
_I_I_006b01f32ae3400f [07:0a] HERE IS WAIT (-1 sec)
_I_I_006b01f32ae3400f [07:0a] HERE IS WAIT (10 sec)
11:45:37.312_B_I_006b01f32ae3400f [07:0a] delay treatments for 0 msec
11:45:37.312_I_I_006b01f32ae3400f [09:04] <<<<<<<<<<<<suspend interpretator(WAIT), func:Wait timers:11000
11:45:37.312_B_I_006b01f32ae3400f [07:09] start chain of treatments
received from 65200(TServer_SIP)videocc:7650(fd=) message EventAttachedDataChanged(refid=58)
11:45:37.312_T_I_006b01f32ae3400f [14:32] EventAttachedDataChanged is received for tserver TServer_SIP[SIPSwitch] (this dn=1002, refid=58)
11:45:38.000_M_I_ [10:1d] PULSE (calls: 1(1)=1+1-1, targets=0, time=1312364738, mem=0,3343,55,42,142,1)
11:45:38.000_M_I_ [10:1d] virtual queues allocation pattern: static=(13 256 128) dynamic=(12 12/1024)
11:45:40.000_M_I_ [10:1d] PULSE (calls: 1(1)=1+0-0, targets=0, time=1312364740, mem=0,3343,55,42,142,1)
11:45:40.000_M_I_ [10:1d] virtual queues allocation pattern: static=(13 256 128) dynamic=(12 12/1024)
11:45:42.000_M_I_ [10:1d] PULSE (calls: 1(1)=1+0-0, targets=0, time=1312364742, mem=0,3343,55,42,142,1)
11:45:42.000_M_I_ [10:1d] virtual queues allocation pattern: static=(13 256 128) dynamic=(12 12/1024)
11:45:44.000_M_I_ [10:1d] PULSE (calls: 1(1)=1+0-0, targets=0, time=1312364744, mem=0,3343,55,42,142,1)
11:45:44.000_M_I_ [10:1d] virtual queues allocation pattern: static=(13 256 128) dynamic=(12 12/1024)
11:45:46.000_M_I_ [10:1d] PULSE (calls: 1(1)=1+0-0, targets=0, time=1312364746, mem=0,3343,55,42,142,1)
11:45:46.000_M_I_ [10:1d] virtual queues allocation pattern: static=(13 256 128) dynamic=(12 12/1024)
11:45:47.312_I_I_006b01f32ae3400f [07:08] wait time is over
11:45:47.312_I_I_006b01f32ae3400f [09:05] >>>>>>>>>>>>resume interpretator(0), func:Wait
11:45:47interaction 006b01f32ae3400f is routed to default
11:45:47.312_M_I_006b01f32ae3400f [17:11] VQ 01d74780 first available call: none, reason=binding
11:45:47.312_M_I_006b01f32ae3400f [13:03] call (virtual queue 01d74820, id=12, priority 0) doesn't wait for VQ 01d74780 (name="") now
request to 65200(TServer_SIP) message RequestDeletePair
AttributeReferenceID 59
AttributeDataKey 'RTargetAgentGroup'
AttributeConnID 006b01f32ae3400f
AttributeThisDN '1002'
..sent to videocc:7650(fd=556)
request to 65200(TServer_SIP) message RequestUpdateUserData
AttributeReferenceID 60
AttributeUserData [489] 00 15 00 00..
'RVQID' ''
'RVQDBID' ''
'RTargetTypeSelected' '100'
'RTargetRuleSelected' ''
'RTargetObjectSelected' ''
'RTargetObjSelDBID' ''
'RTargetAgentSelected' ''
'RTargetPlaceSelected' ''
'RTenant' 'Resources'
'RStrategyName' 'Skill'
'RStrategyDBID' '102'
'CBR-actual_volume' ''
'CBR-Interaction_cost' ''
'CBR-contract_DBIDs' ''
'CBR-IT-path_DBIDs' ''
'RRequestedSkillCombination' ''
'RRequestedSkills'(list)
'RTargetRequested' '?:Angol < 4'
'CustomerSegment' 'default'
'ServiceType' 'default'
'ServiceObjective' ''
AttributeConnID 006b01f32ae3400f
AttributeThisDN '1002'
..sent to videocc:7650(fd=556)
request to 65200(TServer_SIP) message RequestUpdateUserData
AttributeReferenceID 61
AttributeUserData [17] 00 01 01 00..
'PegDEF' 1
AttributeConnID 006b01f32ae3400f
AttributeThisDN '1002'
..sent to videocc:7650(fd=556)
11:45:47.312_T_I_006b01f32ae3400f [14:19] send to tserver TServer_SIP RequestRouteCall to dn 1012 on (dnis= )
request to 65200(TServer_SIP) message RequestRouteCall
AttributeReferenceID 62
AttributeReason [14] 00 01 01 00..
'RTR' 108
AttributeRouteType 1 (RouteTypeDefault)
AttributeExtensions [64] 00 03 00 00..
'DEFAULT#' '1012'
'CUSTOMER_ID' 'Resources'
'SWITCH' 'SIPSwitch'
AttributeOtherDN '1012'
AttributeConnID 006b01f32ae3400f
AttributeThisDN '1002'
..sent to videocc:7650(fd=556)
_A_I_006b01f32ae3400f [0E:04] ----------->DEPARTURE(processing time 10000)
11:45:47.312_I_I_006b01f32ae3400f [09:04] <<<<<<<<<<<<suspend interpretator(ROUTING), func:Default timers:00000
received from 65200(TServer_SIP)videocc:7650(fd=) message EventAttachedDataChanged(refid=59)
11:45:47.312_T_I_006b01f32ae3400f [14:32] EventAttachedDataChanged is received for tserver TServer_SIP[SIPSwitch] (this dn=1002, refid=59)
received from 65200(TServer_SIP)videocc:7650(fd=) message EventAttachedDataChanged(refid=60)
11:45:47.312_T_I_006b01f32ae3400f [14:32] EventAttachedDataChanged is received for tserver TServer_SIP[SIPSwitch] (this dn=1002, refid=60)
received from 65200(TServer_SIP)videocc:7650(fd=) message EventAttachedDataChanged(refid=61)
11:45:47.312_T_I_006b01f32ae3400f [14:32] EventAttachedDataChanged is received for tserver TServer_SIP[SIPSwitch] (this dn=1002, refid=61)
received from 65200(TServer_SIP)videocc:7650(fd=) message EventRouteUsed
AttributeCallState 0
AttributeCallType 1
AttributePropagatedCallType 1
AttributeCallID 15
AttributeConnID 006b01f32ae3400f
AttributeCallUUID 'KR8OMOG55T2H19IGOJDVUCQC1C00000F'
AttributeUserData [504] 00 16 00 00..
'RVQID' ''
'RVQDBID' ''
'RTargetTypeSelected' '100'
'RTargetRuleSelected' ''
'RTargetObjectSelected' ''
'RTargetObjSelDBID' ''
'RTargetAgentSelected' ''
'RTargetPlaceSelected' ''
'RTenant' 'Resources'
'RStrategyName' 'Skill'
'RStrategyDBID' '102'
'CBR-actual_volume' ''
'CBR-Interaction_cost' ''
'CBR-contract_DBIDs' ''
'CBR-IT-path_DBIDs' ''
'RRequestedSkillCombination' ''
'RRequestedSkills'(list)
'RTargetRequested' '?:Angol < 4'
'CustomerSegment' 'default'
'ServiceType' 'default'
'ServiceObjective' ''
'PegDEF' 1
AttributeDNIS '1002'
AttributeThisDN '1002'
AttributeThisDNRole 2
AttributeThisQueue '1002'
AttributeOtherDN '1112'
AttributeOtherDNRole 1
AttributeThirdPartyDN '1012'
AttributeThirdPartyDNRole 2
AttributeReferenceID 62
AttributeReason [14] 00 01 01 00..
'RTR' 108
AttributeExtensions [23] 00 01 01 00..
'BusinessCall' 1
AttributeTimeinSecs 1312364747 (11:45:47)
AttributeTimeinuSecs 312000
AttributeEventSequenceNumber 0000000000000115
11:45:47.312_T_I_006b01f32ae3400f [14:0c] EventRouteUsed(normal) is received for tserver TServer_SIP[SIPSwitch] (this dn=1002)
_T_I_006b01f32ae3400f [14:0a] del DN (TServer_SIP[SIPSwitch] 1002) truly(ref.id=62)
_T_I_006b01f32ae3400f [14:0a] there is no DNs for call, delivering in progress
11:45:47.312_A_I_006b01f32ae3400f [14:0d] <-----------DEPARTURE
_T_I_006b01f32ae3400f [0E:0d] routing done
Time to receive acknowledgement of call sending(msec) < 10000 >
11:45:47.312_I_I_006b01f32ae3400f [01:08] call (12-0195e420) deleting truly
CALL (006b01f32ae3400f) HISTORY ( Default ):
received from 65200(TServer_SIP)videocc:7650(fd=) message EventDiverted
AttributeCallState 0
AttributeCallType 1
AttributePropagatedCallType 1
AttributeCallID 15
AttributeConnID 006b01f32ae3400f
AttributeCallUUID 'KR8OMOG55T2H19IGOJDVUCQC1C00000F'
AttributeUserData [504] 00 16 00 00..
'RVQID' ''
'RVQDBID' ''
'RTargetTypeSelected' '100'
'RTargetRuleSelected' ''
'RTargetObjectSelected' ''
'RTargetObjSelDBID' ''
'RTargetAgentSelected' ''
'RTargetPlaceSelected' ''
'RTenant' 'Resources'
'RStrategyName' 'Skill'
'RStrategyDBID' '102'
'CBR-actual_volume' ''
'CBR-Interaction_cost' ''
'CBR-contract_DBIDs' ''
'CBR-IT-path_DBIDs' ''
'RRequestedSkillCombination' ''
'RRequestedSkills'(list)
'RTargetRequested' '?:Angol < 4'
'CustomerSegment' 'default'
'ServiceType' 'default'
'ServiceObjective' ''
'PegDEF' 1
AttributeDNIS '1002'
AttributeThisDN '1002'
AttributeThisDNRole 2
AttributeThisQueue '1002'
AttributeThirdPartyDN '1012'
AttributeThirdPartyDNRole 2
AttributeOtherDN '1112'
AttributeOtherDNRole 1
AttributeExtensions [23] 00 01 01 00..
'BusinessCall' 1
AttributeTimeinSecs 1312364747 (11:45:47)
AttributeTimeinuSecs 312000
AttributeEventSequenceNumber 0000000000000116
_G_I_ Version: 8.0.100.17
root 00b30760
11:45:48.359 Trc 20011 current number of targets for tenant Resources: Agents 3, Places 0, AgentGroups 2, PlaceGroups 0, ACDQueues 0, Routing Points 0
11:45:48.359 Trc 20012 current number of interactions per second - 0.20
11:45:48.359 Trc 20013 current number of entries: for longest queue 0, for all queues 0
11:46:00.312_M_I_ [17:0d] VQ 01d74780 deleted
11:46:00.312_M_I_ [17:08] VQ 01d74780 Target "?:Angol < 4"(01d731a0) deleted