Hi all,
ORS: 8.1.200.52
Composer: 8.1.101.88
ORS connection: SIP, URS
ORS seem to work fine with Cassandra and with other Genesys components.
I try to "develop" my first voice script (Composer generated scxml, Java project with Tomcat) with 3 simple blocks: Entry, play an annc with "Play Message", Exit
When the interaction enter the PlayMessage1 block the following issue occure:
---------------related ors log-----------------------
15:24:20.074 METRIC <state_enter sid='01KOS4NIB08GH25PG40AHG5AES00000J' name='PlayMessage1' type='state' />
15:24:20.074 METRIC <onentry sid='01KOS4NIB08GH25PG40AHG5AES00000J' name='PlayMessage1' line='110' />
15:24:20.075 METRIC <exec_error sid='01KOS4NIB08GH25PG40AHG5AES00000J' result='ReferenceError: InteractionID is not defined. Line 1 - in <play> at line: 111' />
15:24:20.075 METRIC <event_queued sid='01KOS4NIB08GH25PG40AHG5AES00000J' name='error.script.ReferenceError' type='platform' />
15:24:20.075 METRIC <event_processed sid='01KOS4NIB08GH25PG40AHG5AES00000J' name='error.script.ReferenceError' disposition='no transition' current_states='AppEntry,globalstate,PlayMessage1' />
15:24:20.075 METRIC <event_processed sid='01KOS4NIB08GH25PG40AHG5AES00000J' name='interaction.added' disposition='no transition' current_states='AppEntry,globalstate,PlayMessage1' />
---------------related ors log-----------------------
The InteractionID is defined in the Entry block by default:
InteractionID = _event.data.interactionid (System)
The InteractionID is defined in the PlayMessage1 block by default:
Interaction ID = Variable(InteractionID)
Nevertheless, I get the error message above, so the question is that:
How should I pass/define this variable or configure something else?
------------------full ors log of the call------------------
[b]15:24:20.049 Int 04543 Interaction message "EventCallCreated" received from 65202 ("SIPSrv")[/b]
: message EventCallCreated
AttributeDNIS '7100'
AttributeCallUUID 'IDMQB3O8UL1NTCP2E7D1KJCVVC00000M'
AttributeConnID 00de022028997016
AttributeCallID 22
AttributeCallType 1
AttributeMediaType 0
AttributeANI '6000'
AttributeTimeStamp 510937ed00095a88
AttributeEventSequenceNumber 0000000000000299
15:24:20.050 CALL(IDMQB3O8UL1NTCP2E7D1KJCVVC00000M,00de022028997016)
15:24:20.050 CTITM: transaction MatchCall created id='35', call=''
15:24:20.050 on_call_created(00de022028997016)
15:24:20.050 [ORSCallMonitor] OnCallCreated
15:24:20.050 ORSFmInteraction::HandleCallCreated: CallID=IDMQB3O8UL1NTCP2E7D1KJCVVC00000M
[b]15:24:20.050 Int 04543 Interaction message "EventCallPartyAdded" received from 65202 ("SIPSrv")[/b]
: message EventCallPartyAdded
AttributePartyUUID 'BB6KA8COT10J9388NL5IAR15TC00002P'
AttributeCallUUID 'IDMQB3O8UL1NTCP2E7D1KJCVVC00000M'
AttributeConnID 00de022028997016
AttributePartyType 0
AttributePartyState 0x1
AttributeMediaType 0
AttributeDN '6000'
AttributeDBID 112
AttributeTimeStamp 510937ed00095a88
AttributeEventSequenceNumber 000000000000029a
[b]15:24:20.052 Int 04543 Interaction message "EventDialing" received from 65202 ("SIPSrv@6000")[/b]
: message EventDialing
AttributeEventSequenceNumber 000000000000029c
AttributeTimeinuSecs 613000
AttributeTimeinSecs 1359558637 (16:10:37)
AttributeExtensions [23] 00 01 01 00..
'BusinessCall' 0
AttributeOtherDNRole 2
AttributeOtherDN '7100'
AttributeThisDNRole 1
AttributeThisDN '6000'
AttributeANI '6000'
AttributeDNIS '7100'
AttributeCallUUID 'IDMQB3O8UL1NTCP2E7D1KJCVVC00000M'
AttributeConnID 00de022028997016
AttributeCallID 22
AttributeLocalCallType 1
AttributeCallType 1
AttributeCallState 0
15:24:20.052 CGCTIParty created: callGUID:IDMQB3O8UL1NTCP2E7D1KJCVVC00000M, partyGUID:IDMQB3O8UL1NTCP2E7D1KJCVVC00000M-0, thisDN:6000[6000], localPartyId:37
15:24:20.052 [ORSCallMonitor] OnPartyCreated
15:24:20.052 on_party_added(00de022028997016,6000[0xb1d49e0])
15:24:20.052 CALL[IDMQB3O8UL1NTCP2E7D1KJCVVC00000M]: party 6000[b1d49e0] added
15:24:20.052 [ORSCallMonitor] OnPartyAdded
15:24:20.052 [IDX]: >> GET >> FMID=IDMQB3O8UL1NTCP2E7D1KJCVVC00000M NOT FOUND
15:24:20.052 on_party_state_changed(00de022028997016,6000[0xb1d49e0],2)
15:24:20.052 SRT[35]: transaction complete, call '' created
15:24:20.052 CALL[IDMQB3O8UL1NTCP2E7D1KJCVVC00000M]: new id 19 created
15:24:20.052 GCTIManager: add call '19'
15:24:20.052 GCTI: FSM: party '19:6000','0 - 1 -> 2',tr=1
15:24:20.052 [ORSCallMonitor] OnPartyStateChanged
15:24:20.052 ORSFmInteraction::HandlePartyStateChanged: 'null' --> 'connected'
15:24:20.052 [IDX]: >> GET >> FMID=IDMQB3O8UL1NTCP2E7D1KJCVVC00000M NOT FOUND
15:24:20.052 ors:+ORSFmInteractionData[0xc220580]:IxnGUID=IDMQB3O8UL1NTCP2E7D1KJCVVC00000M
[b]15:24:20.052 Int 04543 Interaction message "EventCallPartyAdded" received from 65202 ("SIPSrv")[/b]
: message EventCallPartyAdded
AttributePartyUUID 'BB6KA8COT10J9388NL5IAR15TC00002Q'
AttributeCallUUID 'IDMQB3O8UL1NTCP2E7D1KJCVVC00000M'
AttributeConnID 00de022028997016
AttributePartyType 0
AttributePartyState 0x9
AttributeMediaType 0
AttributeDN '7100'
AttributeDBID 141
AttributeTimeStamp 510937ed00095a88
AttributeEventSequenceNumber 000000000000029d
[b]15:24:20.053 Int 04543 Interaction message "EventQueued" received from 65202 ("SIPSrv@7100")[/b]
: message EventQueued
AttributeEventSequenceNumber 000000000000029e
AttributeTimeinuSecs 613000
AttributeTimeinSecs 1359558637 (16:10:37)
AttributeExtensions [23] 00 01 01 00..
'BusinessCall' 1
AttributeThisQueue '7100'
AttributeThisDNRole 2
AttributeThisDN '7100'
AttributeANI '6000'
AttributeDNIS '7100'
AttributeCallUUID 'IDMQB3O8UL1NTCP2E7D1KJCVVC00000M'
AttributeConnID 00de022028997016
AttributeCallID 22
AttributeLocalCallType 1
AttributeCallType 1
AttributeCallState 0
AttributeOtherDNRole 1
AttributeOtherDN '6000'
15:24:20.053 CGCTIParty created: callGUID:IDMQB3O8UL1NTCP2E7D1KJCVVC00000M, partyGUID:IDMQB3O8UL1NTCP2E7D1KJCVVC00000M-1, thisDN:7100[7100], localPartyId:38
15:24:20.053 [ORSCallMonitor] OnPartyCreated
15:24:20.053 on_party_added(00de022028997016,7100[0xb1d4948])
15:24:20.053 CALL[IDMQB3O8UL1NTCP2E7D1KJCVVC00000M]: party 7100[b1d4948] added
15:24:20.053 [ORSCallMonitor] OnPartyAdded
15:24:20.053 [IDX]: >> GET >> FMID=IDMQB3O8UL1NTCP2E7D1KJCVVC00000M NOT FOUND
15:24:20.053 on_party_state_changed(00de022028997016,7100[0xb1d4948],4)
15:24:20.053 GCTI: FSM: party '19:7100','0 - 4 -> 4',tr=8
15:24:20.053 [ORSCallMonitor] OnPartyStateChanged
15:24:20.053 ORSFmInteraction::HandlePartyStateChanged: 'null' --> 'queued'
15:24:20.053 [IDX]: >> GET >> FMID=IDMQB3O8UL1NTCP2E7D1KJCVVC00000M NOT FOUND
15:24:20.053 [IDX]: >> GET >> FMID=IDMQB3O8UL1NTCP2E7D1KJCVVC00000M NOT FOUND
15:24:20.053 [SessionManager]: Session does not exist for this call[IDMQB3O8UL1NTCP2E7D1KJCVVC00000M]... attempting to create the new one
15:24:20.053 [SessionManager]: CONFIGITEM[max-includes] = VALUE[500]
15:24:20.053 [SessionManager]: URL [http://vmgenesys:18080/ORSTest/src-gen/default.scxml] associated with script [ORSTest.default.defaultWorkflow]
15:24:20.053 [SessionManager]: http-version[1.1]
15:24:20.053 [SessionManager]: http-useragent[GOES/8.0]
15:24:20.053 [SessionManager]: CONFIGITEM[max-age] = VALUE[0]
15:24:20.053 [SessionManager]: CONFIGITEM[max-stale] = VALUE[0]
15:24:20.053 [SessionManager]: CONFIGITEM[max-loop-count] = VALUE[5000]
15:24:20.053 [SessionManager]: CONFIGITEM[scxmli.script_max_duration] = VALUE[2000]
15:24:20.053 [SessionManager]: Application parameter key [context_management_services_url] value [http://vmgenesys:7008]
15:24:20.053 [SessionManager]: Application parameter key [context_management_services_username] value []
15:24:20.053 [SessionManager]: Application parameter key [context_management_services_password] value []
15:24:20.053 [SessionManager]: Application parameter key [grat_server] value [<Business Rules Preferences not set><Business Rules Preferences not set>]
15:24:20.053 [SessionManager]: Application parameter key [grat_tenant] value [<Business Rules Preferences not set>]
15:24:20.053 [SessionManager]: Application parameter key [grat_username] value [<Business Rules Preferences not set>]
15:24:20.053 [SessionManager]: Application parameter key [grat_password] value []
15:24:20.053 [SessionManager]: URL[http://vmgenesys:18080/ORSTest/src-gen/default.scxml] AlternateURL[] associated with device[7100]
15:24:20.053 [SessionManager]: adding key[IDMQB3O8UL1NTCP2E7D1KJCVVC00000M]/value[01KOS4NIB08GH25PG40AHG5AES00000J]
15:24:20.053 [IDX]: >> GET ALL >> SESSID=01KOS4NIB08GH25PG40AHG5AES00000J / 0 FMIDS
15:24:20.053 [IDX]: << ADD << (FMID=IDMQB3O8UL1NTCP2E7D1KJCVVC00000M / SESSID=01KOS4NIB08GH25PG40AHG5AES00000J)
15:24:20.053 ors:+IORSSessionTask[0xc1f7318]
15:24:20.053 W-NODE[161](RUNNING): >>>> 75 bytes to S-NODE[161] >>>>
CLAIM 161 OCP/1.0
e:IDMQB3O8UL1NTCP2E7D1KJCVVC00000M
c:IXN:SIPSrv:670
15:24:20.053 W-NODE[161]: Pending claim (event ID: IDMQB3O8UL1NTCP2E7D1KJCVVC00000M, scope: IXN:SIPSrv:670, handlers: 1
15:24:20.053 [SessionManager]: CLAIM initiated for ID=IDMQB3O8UL1NTCP2E7D1KJCVVC00000M: SessionID=01KOS4NIB08GH25PG40AHG5AES00000J
15:24:20.053 ors:+OrsEvent[0xbf73cc0]:name=interaction.added
15:24:20.053 ors:+OrsEvent[0xbf74030]:name=interaction.present
15:24:20.053 ors:+OrsEvent[0xbf74088]:name=interaction.partystatechanged
[b]15:24:20.053 Int 04543 Interaction message "EventRouteRequest" received from 65202 ("SIPSrv@7100")[/b]
: message EventRouteRequest
AttributeEventSequenceNumber 000000000000029f
AttributeTimeinuSecs 613000
AttributeTimeinSecs 1359558637 (16:10:37)
AttributeExtensions [23] 00 01 01 00..
'BusinessCall' 1
AttributeOtherDNRole 1
AttributeOtherDN '6000'
AttributeThisQueue '7100'
AttributeThisDNRole 2
AttributeThisDN '7100'
AttributeANI '6000'
AttributeDNIS '7100'
AttributeCallUUID 'IDMQB3O8UL1NTCP2E7D1KJCVVC00000M'
AttributeConnID 00de022028997016
AttributeCallID 22
AttributeLocalCallType 1
AttributeCallType 1
AttributeCallState 0
15:24:20.053 on_party_state_changed(00de022028997016,7100[0xb1d4948],62)
15:24:20.053 [ORSCallMonitor] OnRouteRequest
15:24:20.053 ORSFmInteraction::HandleRouteRequest: gts_core library patch
15:24:20.054 S-NODE[161](MASTER): <<<< 93 bytes from NODE[161] <<<<
CLAIM 161 OCP/1.0
e:IDMQB3O8UL1NTCP2E7D1KJCVVC00000M
c:IXN:SIPSrv:670
PING 1 OCP/1.0
15:24:20.054 S-NODE[161]: ORSClusterSuperNode::AssignClaimToNode() Scope:Event [IXN:SIPSrv:670:IDMQB3O8UL1NTCP2E7D1KJCVVC00000M] from node [161]
15:24:20.054 S-NODE[161]: Processing claim request for (IDMQB3O8UL1NTCP2E7D1KJCVVC00000M:IXN:SIPSrv:670) from NODE[161]
15:24:20.054 S-NODE[161](MASTER): >>>> 96 bytes to NODE[161] >>>>
CLAIMRES 161 OCP/1.0
e:IDMQB3O8UL1NTCP2E7D1KJCVVC00000M
c:IXN:SIPSrv:670
r:primary
n:161
15:24:20.054 W-NODE[161](RUNNING): <<<< 96 bytes from S-NODE[161] <<<<
CLAIMRES 161 OCP/1.0
e:IDMQB3O8UL1NTCP2E7D1KJCVVC00000M
c:IXN:SIPSrv:670
r:primary
n:161
15:24:20.054 [ORSSessionQueueTaskCallback]: MSN Callback: [Node: 161, Event ID: IDMQB3O8UL1NTCP2E7D1KJCVVC00000M, Result: primary, Scope: IXN:SIPSrv:670] (Task: 0xc1f7318)
15:24:20.054 [SessionManager]: CLAIM RESULT: Session Granted: EventID=IDMQB3O8UL1NTCP2E7D1KJCVVC00000M, SessionID=01KOS4NIB08GH25PG40AHG5AES00000J
15:24:20.054 ors:+ORSScxmlSessionCtrlCtx[0xc1aee90]
[i][b]15:24:20.054 METRIC <doc_request sid='01KOS4NIB08GH25PG40AHG5AES00000J' url='http://vmgenesys:18080/ORSTest/src-gen/default.scxml' />[/b][/i]
15:24:20.054 scxml:(fm) UserResponse created. 0c5821a0
15:24:20.054 scxml:(fm) Processing GET request, URL: http://vmgenesys:18080/ORSTest/src-gen/default.scxml
15:24:20.054 scxml:(fm) found in cache, check if cache is valid
15:24:20.054 scxml:(fm) Re-using existing connection! (#0) with host vmgenesys
15:24:20.054 scxml:(fm) Connected to vmgenesys (192.168.0.93) port 18080 (#0)
15:24:20.055 scxml:(fm) GET /ORSTest/src-gen/default.scxml HTTP/1.1
15:24:20.055 scxml:(fm) User-Agent: GOES/8.0
15:24:20.055 scxml:(fm) Host: vmgenesys:18080
15:24:20.055 scxml:(fm) Accept: */*
15:24:20.055 scxml:(fm) If-Modified-Since: Wed, 30 Jan 2013 14:59:05 GMT
15:24:20.055 scxml:(fm) If-None-Match: W/"4215-1359557945675"
15:24:20.055 scxml:(fm) Cache-Control: max-age=0, max-stale=0
15:24:20.055 scxml:(fm) X-Genesys-FMSessionID: 01KOS4NIB08GH25PG40AHG5AES00000J-0000001A
15:24:20.055 [SessionManager]: SCXML Session Create Initiated (01KOS4NIB08GH25PG40AHG5AES00000J)
15:24:20.055 ors:~IORSSessionTask[0xc1f7318]
15:24:20.055 W-NODE[161]: Pending ACK (event ID: IDMQB3O8UL1NTCP2E7D1KJCVVC00000M, scope: IXN:SIPSrv:670
15:24:20.065 scxml:(fm) HTTP/1.1 200 OK
15:24:20.065 scxml:(fm) Server: Apache-Coyote/1.1
15:24:20.065 scxml:(fm) ETag: W/"7345-1359558482316"
15:24:20.065 scxml:(fm) Last-Modified: Wed, 30 Jan 2013 15:08:02 GMT
15:24:20.065 scxml:(fm) Content-Type: text/plain
15:24:20.065 scxml:(fm) Content-Length: 7345
15:24:20.065 scxml:(fm) Date: Wed, 30 Jan 2013 15:10:37 GMT
15:24:20.065 scxml:(fm) Connection #0 to host vmgenesys left intact
[i][b]15:24:20.065 scxml:(fm) Request successful: uri=http://vmgenesys:18080/ORSTest/src-gen/default.scxml[/b][/i]
15:24:20.065 scxml:(fm) HTTP request completed
15:24:20.065 scxml:(fm) Received fetch completed, response code=200
15:24:20.065 scxml:(fm) Added response to cache
15:24:20.065 scxml:(fm) Request completed, sending RequestCompleteNotify.
15:24:20.066 scxml:(fm) Processing ReleaseRequest
15:24:20.066 scxml:(fm) Deleting Request
15:24:20.068 ORSFmInteraction::Compile: Extension <terminate>
15:24:20.068 ors:+ActionDataBase[0xc4a3a88]
15:24:20.068 ORSFmDialog::Compile: Extension <prompt>
15:24:20.068 ORSFmDialog::Compile: Extension <prompts>
15:24:20.068 ORSFmDialog::Compile: Extension <play>
15:24:20.068 ors:+OrsActionData[0xc54f658]
15:24:20.069 ORSFmQueue::Compile: Extension <target>
15:24:20.069 ORSFmQueue::Compile: Extension <targets>
15:24:20.069 ORSFmQueue::Compile: Extension <submit>
15:24:20.069 ors:+OrsActionData[0xc555f80]
15:24:20.069 ORSFmInteraction::Validate: Extension <terminate>
15:24:20.069 ORSFmDialog::Validate: Extension <prompt>
15:24:20.069 ORSFmDialog::Validate: Extension <prompts>
15:24:20.069 ORSFmDialog::Validate: Extension <play>
15:24:20.070 ORSFmQueue::Validate: Extension <target>
15:24:20.070 ORSFmQueue::Validate: Extension <targets>
15:24:20.070 ORSFmQueue::Validate: Extension <submit>
[i][b]15:24:20.070 METRIC <doc_retrieved sid='01KOS4NIB08GH25PG40AHG5AES00000J' result='success' url='http://vmgenesys:18080/ORSTest/src-gen/default.scxml' cached='false' />[/b][/i]
15:24:20.070 CMLThreadSyncImpl:HandleThreadData: << 16 bytes <<
15:24:20.070 ORSSCXML:15:24:20.070: << SESSION CREATED(ID=01KOS4NIB08GH25PG40AHG5AES00000J)
15:24:20.070 [SessionManager]: SCXML Session Create Completed (01KOS4NIB08GH25PG40AHG5AES00000J)
15:24:20.070 ors:+IxnThreadData[0xbfd40dd0]
15:24:20.070 CTITM: R:127 UpdateUData(19,6000)
15:24:20.070 ors:~IxnThreadData[0xbfd40dd0]
15:24:20.070 PersistenceManager:PutSessionServerInfo tdelta cache processing[0]
15:24:20.070 PMCWorker[0xc1f73c0]::PerformWork(3011119984) tdelta to entry[0]
15:24:20.071 METRIC <event_queued sid='01KOS4NIB08GH25PG40AHG5AES00000J' name='interaction.added' type='external' />
15:24:20.071 METRIC <event_queued sid='01KOS4NIB08GH25PG40AHG5AES00000J' name='interaction.present' type='external' />
15:24:20.071 METRIC <event_queued sid='01KOS4NIB08GH25PG40AHG5AES00000J' name='interaction.partystatechanged' type='external' />
15:24:20.071 METRIC <appl_begin sid='01KOS4NIB08GH25PG40AHG5AES00000J' name='default' url='http://vmgenesys:18080/ORSTest/src-gen/default.scxml' docMd5='ff20fe1105197be3daad7c5e3176d2d' context_management_services_password='' context_management_services_url='http://vmgenesys:7008' context_management_services_username='' grat_password='' grat_server='<Business Rules Preferences not set><Business Rules Preferences not set>' grat_tenant='<Business Rules Preferences not set>' grat_username='<Business Rules Preferences not set>' provision_object_name='ORSTest.default.defaultWorkflow' tenant='Resources' tenant_id='101' />
15:24:20.071 CMLThreadSyncImpl:HandleThreadData: << 32 bytes <<
15:24:20.072 ORSURS::InvokeFunctionalModule: <<
refID 17
call '*01KOS4NIB08GH25PG40AHG5AES00000J'
module 'session'
method 'setTenant'
args '["Resources"]'
<<
15:24:20.073 ORSURS::HandleREvent: <<
event 1000000101
refID 17
call
message undefined
object 0
<<
15:24:20.074 METRIC <eval_expr sid='01KOS4NIB08GH25PG40AHG5AES00000J' expression='global._data = new Object();' result='[object Object]' />
15:24:20.074 METRIC <eval_expr sid='01KOS4NIB08GH25PG40AHG5AES00000J' expression='_data.context_management_services_password=''' result='' />
15:24:20.074 METRIC <eval_expr sid='01KOS4NIB08GH25PG40AHG5AES00000J' expression='_data.context_management_services_url='http://vmgenesys:7008'' result='http://vmgenesys:7008' />
15:24:20.074 METRIC <eval_expr sid='01KOS4NIB08GH25PG40AHG5AES00000J' expression='_data.context_management_services_username=''' result='' />
15:24:20.074 METRIC <eval_expr sid='01KOS4NIB08GH25PG40AHG5AES00000J' expression='_data.grat_password=''' result='' />
15:24:20.074 METRIC <eval_expr sid='01KOS4NIB08GH25PG40AHG5AES00000J' expression='_data.grat_server='<Business Rules Preferences not set><Business Rules Preferences not set>'' result='<Business Rules Preferences not set><Business Rules Preferences not set>' />
15:24:20.074 METRIC <eval_expr sid='01KOS4NIB08GH25PG40AHG5AES00000J' expression='_data.grat_tenant='<Business Rules Preferences not set>'' result='<Business Rules Preferences not set>' />
15:24:20.074 METRIC <eval_expr sid='01KOS4NIB08GH25PG40AHG5AES00000J' expression='_data.grat_username='<Business Rules Preferences not set>'' result='<Business Rules Preferences not set>' />
15:24:20.074 METRIC <eval_expr sid='01KOS4NIB08GH25PG40AHG5AES00000J' expression='_data.provision_object_name='ORSTest.default.defaultWorkflow'' result='ORSTest.default.defaultWorkflow' />
15:24:20.074 METRIC <eval_expr sid='01KOS4NIB08GH25PG40AHG5AES00000J' expression='_data.tenant='Resources'' result='Resources' />
15:24:20.074 METRIC <eval_expr sid='01KOS4NIB08GH25PG40AHG5AES00000J' expression='_data.tenant_id='101'' result='101' />
15:24:20.074 METRIC <state_enter sid='01KOS4NIB08GH25PG40AHG5AES00000J' name='AppEntry' type='state' />
15:24:20.074 METRIC <onentry sid='01KOS4NIB08GH25PG40AHG5AES00000J' name='AppEntry' line='17' />
15:24:20.074 METRIC <eval_expr sid='01KOS4NIB08GH25PG40AHG5AES00000J' expression='var App_Name = 'default';
var App_BaseURL;
var App_RelativePathURL;
var App_Language = 'en-US';
var App_Last_Error_Description = 'undefined';
var App_Last_Error_Event_Name = 'undefined';
var App_Last_Submit_Request_Id = 'undefine...' result='http://vmgenesys:18080/ORSTest/' />
15:24:20.074 METRIC <log sid='01KOS4NIB08GH25PG40AHG5AES00000J' expr='App_RelativePathURL = http://vmgenesys:18080/ORSTest/' label='' level='1' />
15:24:20.074 METRIC <transition sid='01KOS4NIB08GH25PG40AHG5AES00000J' target='globalstate' line='62' />
[i][b]15:24:20.074 METRIC <state_enter sid='01KOS4NIB08GH25PG40AHG5AES00000J' name='globalstate' type='state' />[/b][/i]
15:24:20.074 METRIC <transition sid='01KOS4NIB08GH25PG40AHG5AES00000J' target='Entry1' line='69' />
[i][b]15:24:20.074 METRIC <state_enter sid='01KOS4NIB08GH25PG40AHG5AES00000J' name='Entry1' type='state' />[/b][/i]
15:24:20.074 METRIC <onentry sid='01KOS4NIB08GH25PG40AHG5AES00000J' name='Entry1' line='77' />
15:24:20.074 METRIC <log sid='01KOS4NIB08GH25PG40AHG5AES00000J' expr='01KOS4NIB08GH25PG40AHG5AES00000J: Inside Entry Block: Entry1' label='' level='1' />
15:24:20.074 METRIC <state_exit sid='01KOS4NIB08GH25PG40AHG5AES00000J' name='Entry1' duration='0' />
15:24:20.074 METRIC <transition sid='01KOS4NIB08GH25PG40AHG5AES00000J' name='Entry1' target='PlayMessage1' line='80' />
[i][b]15:24:20.074 METRIC <state_enter sid='01KOS4NIB08GH25PG40AHG5AES00000J' name='PlayMessage1' type='state' />[/b][/i]
15:24:20.074 METRIC <onentry sid='01KOS4NIB08GH25PG40AHG5AES00000J' name='PlayMessage1' line='110' />
[i][b][color=red]15:24:20.075 METRIC <exec_error sid='01KOS4NIB08GH25PG40AHG5AES00000J' result='ReferenceError: InteractionID is not defined. Line 1 - in <play> at line: 111' />[/color][/b][/i]
15:24:20.075 METRIC <event_queued sid='01KOS4NIB08GH25PG40AHG5AES00000J' name='error.script.ReferenceError' type='platform' />
15:24:20.075 METRIC <event_processed sid='01KOS4NIB08GH25PG40AHG5AES00000J' name='error.script.ReferenceError' disposition='no transition' current_states='AppEntry,globalstate,PlayMessage1' />
15:24:20.075 METRIC <event_processed sid='01KOS4NIB08GH25PG40AHG5AES00000J' name='interaction.added' disposition='no transition' current_states='AppEntry,globalstate,PlayMessage1' />
15:24:20.075 ors:~OrsEvent[0xbf73cc0]:name=interaction.added
15:24:20.075 METRIC <event_processed sid='01KOS4NIB08GH25PG40AHG5AES00000J' name='interaction.present' disposition='no transition' current_states='AppEntry,globalstate,PlayMessage1' />
15:24:20.075 ors:~OrsEvent[0xbf74030]:name=interaction.present
15:24:20.075 METRIC <event_processed sid='01KOS4NIB08GH25PG40AHG5AES00000J' name='interaction.partystatechanged' disposition='no transition' current_states='AppEntry,globalstate,PlayMessage1' />
15:24:20.075 ors:~OrsEvent[0xbf74088]:name=interaction.partystatechanged
15:24:20.075 CMLThreadSyncImpl:HandleThreadData: << 16 bytes <<
15:24:20.075 ORSSCXML:15:24:20.075: << SESSION STARTED(ID=01KOS4NIB08GH25PG40AHG5AES00000J)
15:24:20.075 ors:~ORSScxmlSessionCtrlCtx[0xc1aee90]
15:24:20.076 PMCWorker[0xc1f73c0]::ProcessPutSessionIDServerInfo() before Thread_SendData sessionID(01KOS4NIB08GH25PG40AHG5AES00000J) result(0) strReason()..
15:24:20.077 PMCWorker[0xc1f73c0]::PerformWork(3011119984) tdelta cassandra[6]
15:24:20.077 CMLThreadSyncImpl:HandleThreadData: << 16 bytes <<
15:24:20.077 PersistenceManager::HandleDataFromThread: enter threadId(49), type(11002), pData(ca05a58) >>>>>>>>>>>>>>>>>>>>>>>
15:24:20.077 PersistenceManager::PERSISTENCE_PUT_SERVERINFO_BY_SESSIONID sessionID(01KOS4NIB08GH25PG40AHG5AES00000J) calling IOnPersistenceWriteCallback->OnWriteSuccess() - reason() tdelta to callback[7]
[b]15:24:20.084 Int 04543 Interaction message "EventCallDataChanged" received from 65202 ("SIPSrv")[/b]
: message EventCallDataChanged
AttributeUserData [62] 00 02 00 00..
'ORSession' '01KOS4NIB08GH25PG40AHG5AES00000J'
'ORDbid' '161'
AttributeCtrlParty '6000'
AttributeCallUUID 'IDMQB3O8UL1NTCP2E7D1KJCVVC00000M'
AttributeConnID 00de022028997016
AttributeTimeStamp 510937ed0009d3a0
AttributeEventSequenceNumber 00000000000002a3
15:24:20.084 call 00de022028997016 data changed
15:24:20.084 [ORSCallMonitor] OnCallInfoChanged
15:24:20.084 W-NODE[161](RUNNING): >>>> 78 bytes to S-NODE[161] >>>>
CLAIMACK 161 OCP/1.0
e:IDMQB3O8UL1NTCP2E7D1KJCVVC00000M
c:IXN:SIPSrv:670
15:24:20.084 ors:+OrsEvent[0xbf74088]:name=interaction.udata.changed
15:24:20.084 ors:+IORSSessionTask[0xc953170]
15:24:20.084 [SessionManager]: Queueing Task: [Session ID: 01KOS4NIB08GH25PG40AHG5AES00000J, Task: 0xc953170, Deferred ACK: 0]
15:24:20.084 PersistenceManager::GetSessionServerInfo calling SessionIDServerNodeResultHandler session id[01KOS4NIB08GH25PG40AHG5AES00000J] pHandler[0xca09700] with result(161) tdelta cache processing[0]
15:24:20.084 [ORSSessionQueueTaskCallback]: Persistence Callback: [Assigned Node: 161, Session ID: 01KOS4NIB08GH25PG40AHG5AES00000J] (Task: 0xc953170)
15:24:20.084 OrsScxmlIoProcessor::Send - sending event to session (01KOS4NIB08GH25PG40AHG5AES00000J) at this node
15:24:20.084 METRIC <event_queued sid='01KOS4NIB08GH25PG40AHG5AES00000J' name='interaction.udata.changed' type='external' />
15:24:20.084 ors:~IORSSessionTask[0xc953170]
[i][b]15:24:20.084 Trc 04541 Message EventACK received from 'SIPSrv'[/b][/i]
: message EventACK
AttributeEventSequenceNumber 00000000000002a4
AttributeTimeinuSecs 644000
AttributeTimeinSecs 1359558637 (16:10:37)
AttributeReferenceID 127
AttributeUserEvent RequestUpdateUserData
15:24:20.084 SRT[36]: uncommitted transaction destroyed
15:24:20.084 S-NODE[161](MASTER): <<<< 78 bytes from NODE[161] <<<<
CLAIMACK 161 OCP/1.0
e:IDMQB3O8UL1NTCP2E7D1KJCVVC00000M
c:IXN:SIPSrv:670
15:24:20.084 METRIC <event_processed sid='01KOS4NIB08GH25PG40AHG5AES00000J' name='interaction.udata.changed' disposition='no transition' current_states='AppEntry,globalstate,PlayMessage1' />
15:24:20.084 ors:~OrsEvent[0xbf74088]:name=interaction.udata.changed
-AP[3011]->-3 @15:24:20.2775
-Ap[3011]-<-3 @15:24:20.2785
-AP[3012]->-3 @15:24:25.2815
-Ap[3012]-<-3 @15:24:25.2823
[b]15:24:26.578 Int 04543 Interaction message "EventReleased" received from 65202 ("SIPSrv@6000")[/b]
: message EventReleased
AttributeCallState 0
AttributeCallType 1
AttributeLocalCallType 1
AttributeCallID 22
AttributeConnID 00de022028997016
AttributeCallUUID 'IDMQB3O8UL1NTCP2E7D1KJCVVC00000M'
AttributeDNIS '7100'
AttributeANI '6000'
AttributeThisDN '6000'
AttributeThisDNRole 1
AttributeOtherDN '7100'
AttributeOtherDNRole 2
AttributeExtensions [49] 00 02 01 00..
'BusinessCall' 0
'ReleasingParty' '1 Local'
AttributeTimeinSecs 1359558644 (16:10:44)
AttributeTimeinuSecs 144000
AttributeEventSequenceNumber 00000000000002a5
15:24:26.578 on_party_released(00de022028997016,6000[0xb1d49e0])
15:24:26.578 on_party_state_changed(00de022028997016,6000[0xb1d49e0],30)
15:24:26.578 GCTI: FSM: party '19:6000','2 - 20 -> 7',tr=4
15:24:26.578 [ORSCallMonitor] OnPartyStateChanged
15:24:26.578 ORSFmInteraction::HandlePartyStateChanged: 'connected' --> 'null'
15:24:26.578 ors:+OrsEvent[0xbf74088]:name=interaction.partystatechanged
15:24:26.579 ors:+IORSSessionTask[0xc954de0]
15:24:26.579 [SessionManager]: Queueing Task: [Session ID: 01KOS4NIB08GH25PG40AHG5AES00000J, Task: 0xc954de0, Deferred ACK: 0]
15:24:26.579 PersistenceManager::GetSessionServerInfo calling SessionIDServerNodeResultHandler session id[01KOS4NIB08GH25PG40AHG5AES00000J] pHandler[0xca09e80] with result(161) tdelta cache processing[0]
15:24:26.579 [ORSSessionQueueTaskCallback]: Persistence Callback: [Assigned Node: 161, Session ID: 01KOS4NIB08GH25PG40AHG5AES00000J] (Task: 0xc954de0)
15:24:26.579 OrsScxmlIoProcessor::Send - sending event to session (01KOS4NIB08GH25PG40AHG5AES00000J) at this node
15:24:26.579 METRIC <event_processed sid='01KOS4NIB08GH25PG40AHG5AES00000J' name='interaction.partystatechanged' disposition='no transition' current_states='AppEntry,globalstate,PlayMessage1' />
15:24:26.579 ors:~OrsEvent[0xbf74088]:name=interaction.partystatechanged
15:24:26.579 METRIC <event_queued sid='01KOS4NIB08GH25PG40AHG5AES00000J' name='interaction.partystatechanged' type='external' />
15:24:26.579 ors:~IORSSessionTask[0xc954de0]
15:24:26.579 CALL[IDMQB3O8UL1NTCP2E7D1KJCVVC00000M]: remove party 6000[b1d4a0c]
15:24:26.579 [ORSCallMonitor] OnPartyDeleted
15:24:26.579 ors:+OrsEvent[0xbf74088]:name=interaction.partydeleted
15:24:26.579 ors:+IORSSessionTask[0xc953400]
15:24:26.579 [SessionManager]: Queueing Task: [Session ID: 01KOS4NIB08GH25PG40AHG5AES00000J, Task: 0xc953400, Deferred ACK: 0]
15:24:26.579 PersistenceManager::GetSessionServerInfo calling SessionIDServerNodeResultHandler session id[01KOS4NIB08GH25PG40AHG5AES00000J] pHandler[0xca0a060] with result(161) tdelta cache processing[0]
15:24:26.579 [ORSSessionQueueTaskCallback]: Persistence Callback: [Assigned Node: 161, Session ID: 01KOS4NIB08GH25PG40AHG5AES00000J] (Task: 0xc953400)
15:24:26.579 OrsScxmlIoProcessor::Send - sending event to session (01KOS4NIB08GH25PG40AHG5AES00000J) at this node
15:24:26.579 METRIC <event_processed sid='01KOS4NIB08GH25PG40AHG5AES00000J' name='interaction.partydeleted' disposition='no transition' current_states='AppEntry,globalstate,PlayMessage1' />
15:24:26.579 ors:~OrsEvent[0xbf74088]:name=interaction.partydeleted
15:24:26.579 METRIC <event_queued sid='01KOS4NIB08GH25PG40AHG5AES00000J' name='interaction.partydeleted' type='external' />
15:24:26.579 ors:~IORSSessionTask[0xc953400]
15:24:26.579 on_party_deleted(00de022028997016,6000[0xb1d49e0])
15:24:26.579 ~PARTY(6000,b1d49e0)
[b]15:24:26.580 Int 04543 Interaction message "EventAbandoned" received from 65202 ("SIPSrv@7100")[/b]
: message EventAbandoned
AttributeCallState 0
AttributeCallType 1
AttributeLocalCallType 1
AttributeCallID 22
AttributeConnID 00de022028997016
AttributeCallUUID 'IDMQB3O8UL1NTCP2E7D1KJCVVC00000M'
AttributeDNIS '7100'
AttributeANI '6000'
AttributeThisDN '7100'
AttributeThisDNRole 2
AttributeThisQueue '7100'
AttributeOtherDN '6000'
AttributeOtherDNRole 1
AttributeExtensions [50] 00 02 01 00..
'BusinessCall' 1
'ReleasingParty' '2 Remote'
AttributeTimeinSecs 1359558644 (16:10:44)
AttributeTimeinuSecs 144000
AttributeEventSequenceNumber 00000000000002a8
15:24:26.580 [ORSCallMonitor] OnPrivateEvent
15:24:26.580 on_party_released(00de022028997016,7100[0xb1d4948])
15:24:26.580 on_party_state_changed(00de022028997016,7100[0xb1d4948],80)
15:24:26.580 [ORSCallMonitor] OnRouteEnd
15:24:26.580 [ORSCallMonitor] OnPartyStateChanged
15:24:26.580 ORSFmInteraction::HandlePartyStateChanged: 'queued' --> 'null'
15:24:26.580 ors:+OrsEvent[0xbf74088]:name=interaction.partystatechanged
15:24:26.580 ors:+IORSSessionTask[0xc953200]
15:24:26.580 [SessionManager]: Queueing Task: [Session ID: 01KOS4NIB08GH25PG40AHG5AES00000J, Task: 0xc953200, Deferred ACK: 0]
15:24:26.580 PersistenceManager::GetSessionServerInfo calling SessionIDServerNodeResultHandler session id[01KOS4NIB08GH25PG40AHG5AES00000J] pHandler[0xca0a5d0] with result(161) tdelta cache processing[0]
15:24:26.580 [ORSSessionQueueTaskCallback]: Persistence Callback: [Assigned Node: 161, Session ID: 01KOS4NIB08GH25PG40AHG5AES00000J] (Task: 0xc953200)
15:24:26.580 OrsScxmlIoProcessor::Send - sending event to session (01KOS4NIB08GH25PG40AHG5AES00000J) at this node
15:24:26.580 METRIC <event_processed sid='01KOS4NIB08GH25PG40AHG5AES00000J' name='interaction.partystatechanged' disposition='no transition' current_states='AppEntry,globalstate,PlayMessage1' />
15:24:26.580 ors:~OrsEvent[0xbf74088]:name=interaction.partystatechanged
15:24:26.580 METRIC <event_queued sid='01KOS4NIB08GH25PG40AHG5AES00000J' name='interaction.partystatechanged' type='external' />
15:24:26.580 ors:~IORSSessionTask[0xc953200]
15:24:26.580 on_party_deleted(00de022028997016,7100[0xb1d4948])
[b]15:24:26.599 Int 04543 Interaction message "EventCallDeleted" received from 65202 ("SIPSrv")[/b]
: message EventCallDeleted
AttributeTimeStamp 510937f400023280
AttributeConnID 00de022028997016
AttributeCallUUID 'IDMQB3O8UL1NTCP2E7D1KJCVVC00000M'
AttributeCtrlParty '6000'
AttributeEventSequenceNumber 00000000000002ad
15:24:26.599 on_switch_call_deleted(00de022028997016)
15:24:26.599 [ORSCallMonitor] OnSwitchCallDeleted
15:24:26.599 on_call_deleted(00de022028997016)
15:24:26.599 GCTI: Call cleanup enforced, 1 parties undeleted
15:24:26.599 CALL[IDMQB3O8UL1NTCP2E7D1KJCVVC00000M]: remove party 7100[b1d4974]
15:24:26.599 [ORSCallMonitor] OnPartyDeleted
15:24:26.599 ors:+OrsEvent[0xbf74088]:name=interaction.partydeleted
15:24:26.599 ors:+IORSSessionTask[0xc9531f0]
15:24:26.599 [SessionManager]: Queueing Task: [Session ID: 01KOS4NIB08GH25PG40AHG5AES00000J, Task: 0xc9531f0, Deferred ACK: 0]
15:24:26.599 PersistenceManager::GetSessionServerInfo calling SessionIDServerNodeResultHandler session id[01KOS4NIB08GH25PG40AHG5AES00000J] pHandler[0xca082c0] with result(161) tdelta cache processing[0]
15:24:26.599 [ORSSessionQueueTaskCallback]: Persistence Callback: [Assigned Node: 161, Session ID: 01KOS4NIB08GH25PG40AHG5AES00000J] (Task: 0xc9531f0)
15:24:26.599 OrsScxmlIoProcessor::Send - sending event to session (01KOS4NIB08GH25PG40AHG5AES00000J) at this node
15:24:26.599 METRIC <event_processed sid='01KOS4NIB08GH25PG40AHG5AES00000J' name='interaction.partydeleted' disposition='no transition' current_states='AppEntry,globalstate,PlayMessage1' />
15:24:26.599 ors:~OrsEvent[0xbf74088]:name=interaction.partydeleted
15:24:26.599 METRIC <event_queued sid='01KOS4NIB08GH25PG40AHG5AES00000J' name='interaction.partydeleted' type='external' />
15:24:26.599 ors:~IORSSessionTask[0xc9531f0]
15:24:26.599 ~PARTY(7100,b1d4948)
15:24:26.599 [ORSCallMonitor] OnCallDeleted
15:24:26.599 ors:+OrsEvent[0xbf74088]:name=interaction.notcontrolled
15:24:26.599 ors:+IORSSessionTask[0xc953220]
15:24:26.599 [SessionManager]: Queueing Task: [Session ID: 01KOS4NIB08GH25PG40AHG5AES00000J, Task: 0xc953220, Deferred ACK: 0]
15:24:26.599 PersistenceManager::GetSessionServerInfo calling SessionIDServerNodeResultHandler session id[01KOS4NIB08GH25PG40AHG5AES00000J] pHandler[0xca0a6d8] with result(161) tdelta cache processing[0]
15:24:26.599 [ORSSessionQueueTaskCallback]: Persistence Callback: [Assigned Node: 161, Session ID: 01KOS4NIB08GH25PG40AHG5AES00000J] (Task: 0xc953220)
15:24:26.599 OrsScxmlIoProcessor::Send - sending event to session (01KOS4NIB08GH25PG40AHG5AES00000J) at this node
15:24:26.600 METRIC <event_processed sid='01KOS4NIB08GH25PG40AHG5AES00000J' name='interaction.notcontrolled' disposition='no transition' current_states='AppEntry,globalstate,PlayMessage1' />
15:24:26.600 ors:~OrsEvent[0xbf74088]:name=interaction.notcontrolled
15:24:26.600 METRIC <event_queued sid='01KOS4NIB08GH25PG40AHG5AES00000J' name='interaction.notcontrolled' type='external' />
15:24:26.600 ors:~IORSSessionTask[0xc953220]
15:24:26.600 ors:+OrsEvent[0xbf74088]:name=interaction.deleted
15:24:26.600 ors:+IORSSessionTask[0xc9531e0]
15:24:26.600 [SessionManager]: Queueing Task: [Session ID: 01KOS4NIB08GH25PG40AHG5AES00000J, Task: 0xc9531e0, Deferred ACK: 0]
15:24:26.600 PersistenceManager::GetSessionServerInfo calling SessionIDServerNodeResultHandler session id[01KOS4NIB08GH25PG40AHG5AES00000J] pHandler[0xca0a810] with result(161) tdelta cache processing[0]
15:24:26.600 [ORSSessionQueueTaskCallback]: Persistence Callback: [Assigned Node: 161, Session ID: 01KOS4NIB08GH25PG40AHG5AES00000J] (Task: 0xc9531e0)
15:24:26.600 OrsScxmlIoProcessor::Send - sending event to session (01KOS4NIB08GH25PG40AHG5AES00000J) at this node
15:24:26.600 METRIC <event_processed sid='01KOS4NIB08GH25PG40AHG5AES00000J' name='interaction.deleted' disposition='no transition' current_states='AppEntry,globalstate,PlayMessage1' />
15:24:26.600 ors:~OrsEvent[0xbf74088]:name=interaction.deleted
15:24:26.600 METRIC <event_queued sid='01KOS4NIB08GH25PG40AHG5AES00000J' name='interaction.deleted' type='external' />
15:24:26.600 ors:~IORSSessionTask[0xc9531e0]
15:24:26.600 [SessionManager]: removing key[IDMQB3O8UL1NTCP2E7D1KJCVVC00000M]
15:24:26.600 [IDX]: >> REMOVE >> (FMID=IDMQB3O8UL1NTCP2E7D1KJCVVC00000M / SESSID=01KOS4NIB08GH25PG40AHG5AES00000J)
15:24:26.600 GCTIManager: remove call '19'
15:24:26.600 ~CALL(IDMQB3O8UL1NTCP2E7D1KJCVVC00000M,00de022028997016,19)
15:24:26.600 ors:~ORSFmInteractionData[0xc220580]:IxnGUID=IDMQB3O8UL1NTCP2E7D1KJCVVC00000M
-AP[3013]->-3 @15:24:30.2855
-Ap[3013]-<-3 @15:24:30.2862
------------------full ors log of the call------------------
Thanks,
Bandorka
