Products Products
Support & Forums Support & Forums
Compliance Testing Compliance Testing
Labs & Sandboxes Labs & Sandboxes
Program Program
Co-Marketing Co-Marketing
Avaya Experience Platform Avaya Enterprise Cloud Avaya Client SDK Avaya Aura Platform See All Products Find Developer Resources

Avaya Experience Platform

Overview Interfaces Learning Support Options Compliance Testing

Avaya Enterprise Cloud

Overview Interfaces Learning Support Options Compliance Testing

Avaya Client SDK

Overview Programming Docs Developer Downloads Licensing Support & Testing

Avaya Aura Platform

Application Enablement Services Communication Manager Presence Services Snap-in Session Manager System Manager Session Border Controller
About DevConnect Support Submit Support Tickets Support Forums Other Support Options

About DevConnect Support

Support Options Support FAQs Supported Platforms and Interfaces

Submit Support Tickets

Procured System Support
For procurement issues or questions Remote Lab Support
For issues and questions related to DevConnect remote labs Membership & Program Support
For membership and program benefits questions General Support
For general issues not covered by the other support tickets

Support Forums

Avaya Client SDK AEServices DMCC APIs AEServices JTAPI AEServices Web Services Avaya Workspaces Avaya Experience Platform Orchestration Designer All Forums

Other Support Options

Product Notices Non-DevConnect Support Resources

Compliance Testing

About Compliance Testing Compliance Tested Solutions

About Compliance Testing

Compliance Testing Overview Avaya Interfaces Eligible For Testing Compliance Testing FAQs Compliance Testing Guide(s) (PDF)

Compliance Tested Solutions

DevConnect Marketplace Search Applications Notes

Labs & Sandboxes

Remote Labs Procurement Benefits

Remote Labs

Remote Labs Overview Avaya Aura Session Manager Avaya Aura AE Services & Communication Manager Self Services (Experience Portal) Avaya Proactive Outreach Manager Avaya Contact Center Elite

Procurement Benefits

About DevConnect Procurement Access/View Procurement Catalog Procurement FAQs DevConnect Procured System Support

Program

Program Overview Program Benefits Membership Options

Program Overview

DevConnect Membership Levels DevConnect Program Guide (PDF) DevConnect Program FAQs

Program Benefits

Membership Benefits DevConnect Procurement Co-marketing

Membership Options

Manage My Portfolio/Solutions Manage My Profile

Co-Marketing

Co-Marketing Benefits Relationship Benefits

Co-Marketing Benefits

Co-Marketing Overview Logos Marketplace Co-branded Collateral Select Product Program

Relationship Benefits

Social Media 8-and-Out Podcasts Sponsorships Advertising

Author Message
20/02/2014 03:26:53
Subject: AOD 6.0 Record Node - Record Issue
#1
YasinUNAL
Joined: Nov 12, 2013
Messages: 36
Offline
Hi,

We developed a Speech application on AOD 6.0 that uses Record node which users can create voice records.
We start to record after a beep voice and when we want to finish recording, we just press the button 0 (Zero).
We have in the properties of Record node that DTMF Terminate is enabled, Max Silence is 60000s and Max Length is 60000s.
We have also set those parameters like shown below;

PlayBeep = true
Modal = true
Audio Type = audio/x-wav
Record Mode = audio

But we're getting an exception after pressing zero for the records have long duration more than 3-5 mins.
We haven't observed that the exception occurs after a specific/exact recording time. But we can create short records.

We set Maximum Recording Duration and Fetch Timeout as 60000s on Experience Portal Manager.
We also set Session Timeout Duration as 90 minutes in web.xml file on Tomcat Application Server.

Do you have a recommend for this issue? Could it be another parameter to change on EP / application server?

Regards,
PM
20/02/2014 10:48:47
Subject: Re:AOD 6.0 Record Node - Record Issue
#2
WilsonYu
Joined: Nov 6, 2013
Messages: 3950
Offline
This is not likely anything in the application you can do. You would need to take a look into the logs on MPP (via EPMS), and find out what the error is first. If you have trouble doing that, please contact Avaya Support (via support.avaya.com).
PM
21/02/2014 08:24:40
Subject: Re:AOD 6.0 Record Node - Record Issue
#3
YasinUNAL
Joined: Nov 12, 2013
Messages: 36
Offline
Hi,

I made a test and started to record at 17:21:21 and finished to record by pressing DTMF "0" at 17:24.

We checked the MPP session logs and found some traces;

Please look at those lines;
@2014-02-21 17:24:15,671||FINEST|VB|14816|Session=2IVRSRV-2014052151916-5|VXML_APP_ERROR
{
dialogHandle: 5
info: "PAVB_00222"
}
.....
@2014-02-21 17:24:15,671||FINEST|VB|14816|Session=2IVRSRV-2014052151916-5|VXML_APP_ERROR
{
dialogHandle: 5
info: "PAVB_00226"
}
....
@2014-02-21 17:24:15,672||FINEST|VB|14816|Session=2IVRSRV-2014052151916-5|VXML_APP_ERROR
{
dialogHandle: 5
info: "PAVB_03061"
}


All trace file
@2014-02-21 17:21:21,858||FINE|CommonVXI.Rec|14816|Session=2IVRSRV-2014052151916-5|AVBrecRecord() Entered|2IVRSRV####
@2014-02-21 17:21:21,858||FINE|CommonVXI.Rec|14816|Session=2IVRSRV-2014052151916-5|osbrec.cpp:1474:AVBrecRecord() - maxRecordingTime: 60000000|2IVRSRV####
@2014-02-21 17:21:21,858||FINE|CommonVXI.Rec|14816|Session=2IVRSRV-2014052151916-5|osbrec.cpp:1485:AVBrecRecord() - DTMF will interrupt recording|2IVRSRV####
@2014-02-21 17:21:21,858||FINE|CommonVXI.Rec|14816|Session=2IVRSRV-2014052151916-5|osbrec.cpp:1506:AVBrecRecord() - timeout: 40000|2IVRSRV####
@2014-02-21 17:21:21,858||FINE|CommonVXI.Rec|14816|Session=2IVRSRV-2014052151916-5|osbrec.cpp:1516:AVBrecRecord() - timeoutComplete: 60000000|2IVRSRV####
@2014-02-21 17:21:21,858||FINE|CommonVXI.Rec|14816|Session=2IVRSRV-2014052151916-5|osbrec.cpp:1567:AVBrecRecord() recordingMimeType: audio/x-wav|2IVRSRV####
@2014-02-21 17:21:21,858||FINE|CommonVXI.Rec|14816|Session=2IVRSRV-2014052151916-5|AVBrecRecord() - Playing All queued prompts|2IVRSRV####
@2014-02-21 17:21:21,858||FINEST|CommonVXI.Rec|14816|Session=2IVRSRV-2014052151916-5|AVBrecRecord() Dumping recording properties...|2IVRSRV####
@2014-02-21 17:21:21,858||FINEST|CommonVXI.Rec|14816|Session=2IVRSRV-2014052151916-5||2IVRSRV####
@2014-02-21 17:21:21,858||FINEST|CommonVXI.Rec|14816|Session=2IVRSRV-2014052151916-5|osbrec.cpp:1658:Key = vxi.rec.maxRecordingTime Level = 0 value is INTEGER: 60000000|2IVRSRV####
@2014-02-21 17:21:21,859||FINEST|CommonVXI.Rec|14816|Session=2IVRSRV-2014052151916-5|osbrec.cpp:1658:Key = vxi.rec.recordingMimeType Level = 0 value is STRING: audio/x-wav|2IVRSRV####
@2014-02-21 17:21:21,859||FINEST|CommonVXI.Rec|14816|Session=2IVRSRV-2014052151916-5|osbrec.cpp:1658:Key = vxi.rec.recordmodes Level = 0 value is STRING: audio|2IVRSRV####
@2014-02-21 17:21:21,859||FINEST|CommonVXI.Rec|14816|Session=2IVRSRV-2014052151916-5|osbrec.cpp:1658:Key = vxi.rec.terminatedOnDTMF Level = 0 value is INTEGER: 1|2IVRSRV####
@2014-02-21 17:21:21,859||FINEST|CommonVXI.Rec|14816|Session=2IVRSRV-2014052151916-5|osbrec.cpp:1658:Key = vxi.rec.timeout Level = 0 value is INTEGER: 40000|2IVRSRV####
@2014-02-21 17:21:21,859||FINEST|CommonVXI.Rec|14816|Session=2IVRSRV-2014052151916-5|osbrec.cpp:1658:Key = vxi.rec.timeoutComplete Level = 0 value is INTEGER: 60000000|2IVRSRV####
@2014-02-21 17:21:30,533||FINEST|VB|14877|Session=2IVRSRV-2014052151916-5|******** Entering Dialog.cpp:337:QueueMessage|2IVRSRV####
@2014-02-21 17:21:30,533||FINEST|VB|14877|Session=2IVRSRV-2014052151916-5|******** Leaving Dialog.cpp:337:QueueMessage|2IVRSRV####
@2014-02-21 17:21:30,533||FINEST|VB|14310|Session=2IVRSRV-2014052151916-5|******** Entering Dialog.cpp:348:ProcessInboundMessage|2IVRSRV####
@2014-02-21 17:21:30,533||FINEST|VB|14310|Session=2IVRSRV-2014052151916-5|******** Entering vpchannel.cpp:5620:QueueMessage|2IVRSRV####
@2014-02-21 17:21:30,533||FINEST|VB|14310|Session=2IVRSRV-2014052151916-5|******** Leaving vpchannel.cpp:5620:QueueMessage|2IVRSRV####
@2014-02-21 17:21:30,533||FINEST|VB|14310|Session=2IVRSRV-2014052151916-5|successfully added msg to channel queue|2IVRSRV####
@2014-02-21 17:21:30,533||FINEST|VB|14310|Session=2IVRSRV-2014052151916-5|******** Leaving Dialog.cpp:348:ProcessInboundMessage|2IVRSRV####
@2014-02-21 17:21:30,533||FINE|VB|14311|Session=2IVRSRV-2014052151916-5|received a VXML_PROMPT_PLAY_COMPLETE|2IVRSRV####
@2014-02-21 17:21:30,533||FINEST|VB|14311|Session=2IVRSRV-2014052151916-5|******** Entering Utility.cpp:469:LogIPCMessage|2IVRSRV####
@2014-02-21 17:21:30,533||FINEST|VB|14311|Session=2IVRSRV-2014052151916-5|VXML_PROMPT_PLAY_COMPLETE
{
dialogHandle: 5
playCompleted: 1
promptID: 56
position: 200
markName: ""
markTimeElapsed: 8661
playCompleteStatus: COMPLETE_NORMAL
}
|2IVRSRV####
@2014-02-21 17:21:30,533||FINEST|VB|14311|Session=2IVRSRV-2014052151916-5|******** Leaving Utility.cpp:469:LogIPCMessage|2IVRSRV####
@2014-02-21 17:21:30,533||FINEST|VB|14311|Session=2IVRSRV-2014052151916-5|******** Leaving vpchannel.cpp:6525:WaitForMessage|2IVRSRV####
@2014-02-21 17:21:30,533||FINER|VB|14311|Session=2IVRSRV-2014052151916-5|Prompt playback completed. Stopping recognition.|2IVRSRV####
@2014-02-21 17:21:30,533||FINEST|VB|14311|Session=2IVRSRV-2014052151916-5|******** Entering vpchannel.cpp:6497:SendMessage|2IVRSRV####
@2014-02-21 17:21:30,533||FINE|VB|14311|Session=2IVRSRV-2014052151916-5|successfully sent message VXML_REC_CANCEL_RECOGNITION|2IVRSRV####
@2014-02-21 17:21:30,533||FINEST|VB|14311|Session=2IVRSRV-2014052151916-5|******** Entering Utility.cpp:469:LogIPCMessage|2IVRSRV####
@2014-02-21 17:21:30,534||FINEST|VB|14311|Session=2IVRSRV-2014052151916-5|VXML_REC_CANCEL_RECOGNITION
{
dialogHandle: 5
}
|2IVRSRV####
@2014-02-21 17:21:30,534||FINEST|VB|14311|Session=2IVRSRV-2014052151916-5|******** Leaving Utility.cpp:469:LogIPCMessage|2IVRSRV####
@2014-02-21 17:21:30,534||FINEST|VB|14311|Session=2IVRSRV-2014052151916-5|******** Leaving vpchannel.cpp:6497:SendMessage|2IVRSRV####
@2014-02-21 17:21:30,534||FINEST|VB|14311|Session=2IVRSRV-2014052151916-5|******** Entering vpchannel.cpp:6525:WaitForMessage|2IVRSRV####
@2014-02-21 17:21:30,534||FINE|VB|14311|Session=2IVRSRV-2014052151916-5|waiting for VXML_REC_RECOGNITION_CANCELLED|2IVRSRV####
@2014-02-21 17:21:30,536||FINEST|VB|14877|Session=2IVRSRV-2014052151916-5|******** Entering Dialog.cpp:337:QueueMessage|2IVRSRV####
@2014-02-21 17:21:30,536||FINEST|VB|14877|Session=2IVRSRV-2014052151916-5|******** Leaving Dialog.cpp:337:QueueMessage|2IVRSRV####
@2014-02-21 17:21:30,536||FINEST|VB|14310|Session=2IVRSRV-2014052151916-5|******** Entering Dialog.cpp:348:ProcessInboundMessage|2IVRSRV####
@2014-02-21 17:21:30,537||FINEST|VB|14310|Session=2IVRSRV-2014052151916-5|******** Entering vpchannel.cpp:5620:QueueMessage|2IVRSRV####
@2014-02-21 17:21:30,537||FINEST|VB|14310|Session=2IVRSRV-2014052151916-5|******** Leaving vpchannel.cpp:5620:QueueMessage|2IVRSRV####
@2014-02-21 17:21:30,537||FINEST|VB|14310|Session=2IVRSRV-2014052151916-5|successfully added msg to channel queue|2IVRSRV####
@2014-02-21 17:21:30,537||FINEST|VB|14310|Session=2IVRSRV-2014052151916-5|******** Leaving Dialog.cpp:348:ProcessInboundMessage|2IVRSRV####
@2014-02-21 17:21:30,537||FINE|VB|14311|Session=2IVRSRV-2014052151916-5|received the expected VXML_REC_RECOGNITION_CANCELLED|2IVRSRV####
@2014-02-21 17:21:30,537||FINEST|VB|14311|Session=2IVRSRV-2014052151916-5|******** Entering Utility.cpp:469:LogIPCMessage|2IVRSRV####
@2014-02-21 17:21:30,537||FINEST|VB|14311|Session=2IVRSRV-2014052151916-5|VXML_REC_RECOGNITION_CANCELLED
{
dialogHandle: 5
}
|2IVRSRV####
@2014-02-21 17:21:30,537||FINEST|VB|14311|Session=2IVRSRV-2014052151916-5|******** Leaving Utility.cpp:469:LogIPCMessage|2IVRSRV####
@2014-02-21 17:21:30,537||FINEST|VB|14311|Session=2IVRSRV-2014052151916-5|******** Leaving vpchannel.cpp:6525:WaitForMessage|2IVRSRV####
@2014-02-21 17:21:30,537||FINEST|VB|14311|Session=2IVRSRV-2014052151916-5|******** Entering vpchannel.cpp:1474:GetInputModes|2IVRSRV####
@2014-02-21 17:21:30,537||FINEST|VB|14311|Session=2IVRSRV-2014052151916-5|ASR: 0, DTMF: 0|2IVRSRV####
@2014-02-21 17:21:30,537||FINEST|VB|14311|Session=2IVRSRV-2014052151916-5|******** Leaving vpchannel.cpp:1474:GetInputModes|2IVRSRV####
@2014-02-21 17:21:30,537||FINEST|VB|14311|Session=2IVRSRV-2014052151916-5|will record to file: /opt/Avaya/ExperiencePortal/MPP/tmp/.557.wav|2IVRSRV####
@2014-02-21 17:21:30,537||FINEST|VB|14311|Session=2IVRSRV-2014052151916-5|******** Entering vpchannel.cpp:6497:SendMessage|2IVRSRV####
@2014-02-21 17:21:30,537||FINE|VB|14311|Session=2IVRSRV-2014052151916-5|successfully sent message VXML_TEL_RECORD|2IVRSRV####
@2014-02-21 17:21:30,537||FINEST|VB|14311|Session=2IVRSRV-2014052151916-5|******** Entering Utility.cpp:469:LogIPCMessage|2IVRSRV####
@2014-02-21 17:21:30,537||FINEST|VB|14311|Session=2IVRSRV-2014052151916-5|VXML_TEL_RECORD
{
dialogHandle: 5
withRecognition: 0
maxTime: 60000000
finalSilence: 60000000
preSilence: 40000
DTMFTerminate: 1
MIMEType: "audio/x-wav"
fileURI: "/opt/Avaya/ExperiencePortal/MPP/tmp/.557.wav"
}
|2IVRSRV####
@2014-02-21 17:21:30,537||FINEST|VB|14311|Session=2IVRSRV-2014052151916-5|******** Leaving Utility.cpp:469:LogIPCMessage|2IVRSRV####
@2014-02-21 17:21:30,537||FINEST|VB|14311|Session=2IVRSRV-2014052151916-5|******** Leaving vpchannel.cpp:6497:SendMessage|2IVRSRV####
@2014-02-21 17:21:30,537||FINEST|VB|14311|Session=2IVRSRV-2014052151916-5|******** Entering vpchannel.cpp:6525:WaitForMessage|2IVRSRV####
@2014-02-21 17:21:30,537||FINE|VB|14311|Session=2IVRSRV-2014052151916-5|waiting for DIALOG_SPECIFIC_MESSAGES|2IVRSRV####
@2014-02-21 17:24:15,662||FINEST|VB|14877|Session=2IVRSRV-2014052151916-5|******** Entering Dialog.cpp:337:QueueMessage|2IVRSRV####
@2014-02-21 17:24:15,662||FINEST|VB|14877|Session=2IVRSRV-2014052151916-5|******** Leaving Dialog.cpp:337:QueueMessage|2IVRSRV####
@2014-02-21 17:24:15,662||FINEST|VB|14310|Session=2IVRSRV-2014052151916-5|******** Entering Dialog.cpp:348:ProcessInboundMessage|2IVRSRV####
@2014-02-21 17:24:15,662||FINEST|VB|14310|Session=2IVRSRV-2014052151916-5|******** Entering vpchannel.cpp:5620:QueueMessage|2IVRSRV####
@2014-02-21 17:24:15,662||FINEST|VB|14310|Session=2IVRSRV-2014052151916-5|******** Leaving vpchannel.cpp:5620:QueueMessage|2IVRSRV####
@2014-02-21 17:24:15,662||FINEST|VB|14310|Session=2IVRSRV-2014052151916-5|successfully added msg to channel queue|2IVRSRV####
@2014-02-21 17:24:15,662||FINEST|VB|14310|Session=2IVRSRV-2014052151916-5|******** Leaving Dialog.cpp:348:ProcessInboundMessage|2IVRSRV####
@2014-02-21 17:24:15,662||FINE|VB|14311|Session=2IVRSRV-2014052151916-5|received a VXML_TEL_RECORD_RESULT|2IVRSRV####
@2014-02-21 17:24:15,662||FINEST|VB|14311|Session=2IVRSRV-2014052151916-5|******** Entering Utility.cpp:469:LogIPCMessage|2IVRSRV####
@2014-02-21 17:24:15,662||FINEST|VB|14311|Session=2IVRSRV-2014052151916-5|VXML_TEL_RECORD_RESULT
{
dialogHandle: 5
duration: 163080
DTMFChar: "0"
reason: RECORD_DTMF
}
|2IVRSRV####
@2014-02-21 17:24:15,662||FINEST|VB|14311|Session=2IVRSRV-2014052151916-5|******** Leaving Utility.cpp:469:LogIPCMessage|2IVRSRV####
@2014-02-21 17:24:15,662||FINEST|VB|14311|Session=2IVRSRV-2014052151916-5|******** Leaving vpchannel.cpp:6525:WaitForMessage|2IVRSRV####
@2014-02-21 17:24:15,662||FINEST|VB|14311|Session=2IVRSRV-2014052151916-5|VPChannel::DoRecord got a record result!|2IVRSRV####
@2014-02-21 17:24:15,662||FINER|VB|14311|Session=2IVRSRV-2014052151916-5|valid Recordresult, status: 4|2IVRSRV####
@2014-02-21 17:24:15,662||FINEST|VB|14311|Session=2IVRSRV-2014052151916-5|setting result->duration to 163080|2IVRSRV####
@2014-02-21 17:24:15,662||FINEST|VB|14311|Session=2IVRSRV-2014052151916-5|setting result->status to REC_STATUS_SUCCESS|2IVRSRV####
@2014-02-21 17:24:15,662||FINEST|VB|14311|Session=2IVRSRV-2014052151916-5|******** Leaving vpchannel.cpp:3057:DoRecord|2IVRSRV####
@2014-02-21 17:24:15,662||FINE|CommonVXI.Rec|14816|Session=2IVRSRV-2014052151916-5|osbrec.cpp:1673:AVBrecRecord() Platform call IChannel::DoRecord() returned: CHANNEL_RESULT_SUCCESS|2IVRSRV####
@2014-02-21 17:24:15,663||FINE|CommonVXI.Rec|14816|Session=2IVRSRV-2014052151916-5|AVBrecRecord.. Exit|2IVRSRV####
@2014-02-21 17:24:15,663||FINE|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|GrammarManager.cpp:2707:GrammarManager::Record() platform call returned: 0|2IVRSRV####
@2014-02-21 17:24:15,663||FINER|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|GrammarManager.cpp:2737:GrammarManager::Record() record result status: 0|2IVRSRV####
@2014-02-21 17:24:15,663||FINER|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|GrammarManager.cpp:2767:GrammarManager::Record() number of rec results: 0|2IVRSRV####
@2014-02-21 17:24:15,663||FINER|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|GrammarManager.cpp:3020:GrammarManager::Record() - no DTMF grammar.|2IVRSRV####
@2014-02-21 17:24:15,663||FINER|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|VXI.cpp:5490:file: file:///opt/Avaya/ExperiencePortal/MPP/tmp/.557.wav|2IVRSRV####
@2014-02-21 17:24:15,663||FINER|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|VXI.cpp:5495:uristr: file:///opt/Avaya/ExperiencePortal/MPP/tmp/.557.wav|2IVRSRV####
@2014-02-21 17:24:15,663||FINER|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|VXI.cpp:5505:Filename before stat: /opt/Avaya/ExperiencePortal/MPP/tmp/.557.wav|2IVRSRV####
@2014-02-21 17:24:15,663||FINER|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|VXI.cpp:5527:contentSize: 1304688|2IVRSRV####
@2014-02-21 17:24:15,663||FINER|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|VXI.cpp:5539:wFile: /opt/Avaya/ExperiencePortal/MPP/tmp/.557.wav|2IVRSRV####
@2014-02-21 17:24:15,663||FINER|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|VXI.cpp:5752:VXI::record_element() - no global grammar match found|2IVRSRV####
@2014-02-21 17:24:15,663||FINER|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|VXI.cpp:5765:VXI::record_element() - saving recording to <record> variable name: createRecord|2IVRSRV####
@2014-02-21 17:24:15,663||FINER|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|VXI.cpp:5784:wFile: /opt/Avaya/ExperiencePortal/MPP/tmp/.557.wav|2IVRSRV####
@2014-02-21 17:24:15,663||FINER|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|VXI.cpp:5817:VXI::record_element() - saving duration to shadow variable--> dialog.createRecord$.duration = 163080|2IVRSRV####
@2014-02-21 17:24:15,663||FINER|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|VXI.cpp:5843:VXI::record_element() - Saving the term char to shadow variable--> dialog.createRecord$.termchar = '0'|2IVRSRV####
@2014-02-21 17:24:15,663||FINER|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|VXI.cpp:5866:VXI::record_element() - Saving the maxtime to shadow variable--> dialog.createRecord$.maxtime = false|2IVRSRV####
@2014-02-21 17:24:15,663||FINER|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|VXI.cpp:5889:file: file:///opt/Avaya/ExperiencePortal/MPP/tmp/.557.wav|2IVRSRV####
@2014-02-21 17:24:15,663||FINER|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|VXI.cpp:5893:uristr: file:///opt/Avaya/ExperiencePortal/MPP/tmp/.557.wav|2IVRSRV####
@2014-02-21 17:24:15,663||FINER|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|VXI.cpp:5900:Filename before stat: /opt/Avaya/ExperiencePortal/MPP/tmp/.557.wav|2IVRSRV####
@2014-02-21 17:24:15,664||FINER|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|VXI.cpp:5926:VXI::record_element() - Saving the size to shadow variable--> dialog.createRecord$.size = 1304688|2IVRSRV####
@2014-02-21 17:24:15,664||FINER|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|VXI.cpp:3498:VXI::execute_content()|2IVRSRV####
@2014-02-21 17:24:15,664||FINER|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|VXI.cpp:3565:VXI::executable_element - ASSIGN|2IVRSRV####
@2014-02-21 17:24:15,664||FINER|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|VXI.cpp:3647:VXI::assign_element(name = createRecord___value exp = try {createRecord} catch (errMsg) {'undefined'})|2IVRSRV####
@2014-02-21 17:24:15,664||FINER|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|VXI.cpp:3565:VXI::executable_element - ASSIGN|2IVRSRV####
@2014-02-21 17:24:15,664||FINER|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|VXI.cpp:3647:VXI::assign_element(name = createRecord___confidence exp = try {createRecord$.confidence} catch (errMsg) {'undefined'})|2IVRSRV####
@2014-02-21 17:24:15,664||FINER|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|VXI.cpp:3565:VXI::executable_element - ASSIGN|2IVRSRV####
@2014-02-21 17:24:15,664||FINER|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|VXI.cpp:3647:VXI::assign_element(name = createRecord___utterance exp = try {createRecord$.utterance} catch (errMsg) {'undefined'})|2IVRSRV####
@2014-02-21 17:24:15,664||FINER|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|VXI.cpp:3565:VXI::executable_element - ASSIGN|2IVRSRV####
@2014-02-21 17:24:15,664||FINER|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|VXI.cpp:3647:VXI::assign_element(name = createRecord___duration exp = try {createRecord$.duration} catch (errMsg) {'undefined'})|2IVRSRV####
@2014-02-21 17:24:15,664||FINER|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|VXI.cpp:3565:VXI::executable_element - ASSIGN|2IVRSRV####
@2014-02-21 17:24:15,664||FINER|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|VXI.cpp:3647:VXI::assign_element(name = createRecord___size exp = try {createRecord$.size} catch (errMsg) {'undefined'})|2IVRSRV####
@2014-02-21 17:24:15,664||FINER|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|VXI.cpp:3565:VXI::executable_element - ASSIGN|2IVRSRV####
@2014-02-21 17:24:15,664||FINER|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|VXI.cpp:3647:VXI::assign_element(name = createRecord___termchar exp = try {createRecord$.termchar} catch (errMsg) {'undefined'})|2IVRSRV####
@2014-02-21 17:24:15,664||FINEST|VB|14311|Session=2IVRSRV-2014052151916-5|******** Entering vpchannel.cpp:1372:IsHangup|2IVRSRV####
@2014-02-21 17:24:15,664||FINEST|VB|14311|Session=2IVRSRV-2014052151916-5|VPChannel::IsHangup returning FALSE|2IVRSRV####
@2014-02-21 17:24:15,664||FINEST|VB|14311|Session=2IVRSRV-2014052151916-5|******** Leaving vpchannel.cpp:1372:IsHangup|2IVRSRV####
@2014-02-21 17:24:15,664||FINER|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|VXI.cpp:3565:VXI::executable_element - ASSIGN|2IVRSRV####
@2014-02-21 17:24:15,665||FINER|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|VXI.cpp:3647:VXI::assign_element(name = createRecord___maxtime exp = try {createRecord$.maxtime} catch (errMsg) {'undefined'})|2IVRSRV####
@2014-02-21 17:24:15,665||FINER|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|VXI.cpp:3089:VXI::DoInnerJump()|2IVRSRV####
@2014-02-21 17:24:15,665||FINE|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|VXI::DoInnerJump - Checking for hangup before executing form item - BLOCK|2IVRSRV####
@2014-02-21 17:24:15,665||FINER|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|GrammarManager.cpp:408:GrammarManager::DoDelayThrow() - forcing spec compliance|2IVRSRV####
@2014-02-21 17:24:15,665||FINER|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|GrammarManager.cpp:493:GrammarManager::DoDelayThrow() - no delayed throw scheduled|2IVRSRV####
@2014-02-21 17:24:15,665||FINER|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|DocumentParser::DoDelayThrow enter|2IVRSRV####
@2014-02-21 17:24:15,665||FINER|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|VXI.cpp:5039:VXI::CollectPhase - (block)|2IVRSRV####
@2014-02-21 17:24:15,665||FINER|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|VXI.cpp:5072:VXI::block_element()|2IVRSRV####
@2014-02-21 17:24:15,665||FINER|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|VXI.cpp:3498:VXI::execute_content()|2IVRSRV####
@2014-02-21 17:24:15,665||FINEST|VB|14311|Session=2IVRSRV-2014052151916-5|******** Entering vpchannel.cpp:1372:IsHangup|2IVRSRV####
@2014-02-21 17:24:15,665||FINEST|VB|14311|Session=2IVRSRV-2014052151916-5|VPChannel::IsHangup returning FALSE|2IVRSRV####
@2014-02-21 17:24:15,665||FINEST|VB|14311|Session=2IVRSRV-2014052151916-5|******** Leaving vpchannel.cpp:1372:IsHangup|2IVRSRV####
@2014-02-21 17:24:15,665||FINER|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|VXI.cpp:3565:VXI::executable_element - SUBMIT|2IVRSRV####
@2014-02-21 17:24:15,665||FINER|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|VXI.cpp:4568:VXI::submit_element()|2IVRSRV####
@2014-02-21 17:24:15,665||FINER|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|VXI.cpp:1583:VXI::DoOuterJump(recording-setURLOfFileName?___DDSESSIONID=907D9390BBCC8484119F24EB131C76B8%3A%2FFlorenceNightingaleIVR)|2IVRSRV####
@2014-02-21 17:24:15,665||FINER|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|PropertyList.cpp:501:PropertyList::GetFetchobjCacheAttrs - (documentmaxage) of (3600s)|2IVRSRV####
@2014-02-21 17:24:15,665||FINER|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|PropertyList.cpp:559:PropertyList::GetFetchobjCacheAttrs - (documentmaxstale) of (0s)|2IVRSRV####
@2014-02-21 17:24:15,665||FINEST|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|VXI.cpp:1655:Map has no properties. Level: 0|2IVRSRV####
@2014-02-21 17:24:15,666||FINEST|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|VXI.cpp:1683:VXI::DoOuterJump - fetchaudiominimum: 0|2IVRSRV####
@2014-02-21 17:24:15,666||FINEST|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|VXI.cpp:1696:VXI::DoOuterJump - fetchaudiodelay: 0|2IVRSRV####
@2014-02-21 17:24:15,666||FINE|CommonVXI.Cache|14816|Session=2IVRSRV-2014052151916-5|SBinetChannel::GetAbsoluteNamerecording-setURLOfFileName?___DDSESSIONID=907D9390BBCC8484119F24EB131C76B8%3A%2FFlorenceNightingaleIVR|2IVRSRV####
@2014-02-21 17:24:15,666||FINEST|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|VXI.cpp:1748:DoOuterJump(): Comparing m_preparedDocID to http://172.17.72.92:8080/FlorenceNightingaleIVR/recording-setURLOfFileName?___DDSESSIONID=907D9390BBCC8484119F24EB131C76B8%3A%2FFlorenceNightingaleIVR|2IVRSRV####
@2014-02-21 17:24:15,666||FINER|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|DocumentParser::FetchDocument(recording-setURLOfFileName?___DDSESSIONID=907D9390BBCC8484119F24EB131C76B8%3A%2FFlorenceNightingaleIVR)|2IVRSRV####
@2014-02-21 17:24:15,666||FINER|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|DocumentParser::FetchBuffer(recording-setURLOfFileName?___DDSESSIONID=907D9390BBCC8484119F24EB131C76B8%3A%2FFlorenceNightingaleIVR)|2IVRSRV####
@2014-02-21 17:24:15,666||FINEST|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|DocumentParser.cpp:370:Dumping INET Properties...|2IVRSRV####
@2014-02-21 17:24:15,666||FINEST|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|DocumentParser.cpp:371:Key = inet.authMode Level = 0 value is STRING: None|2IVRSRV####
@2014-02-21 17:24:15,666||FINEST|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|DocumentParser.cpp:371:Key = inet.basicAuthUserPwd Level = 0 value is STRING: :|2IVRSRV####
@2014-02-21 17:24:15,666||FINEST|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|DocumentParser.cpp:371:Key = inet.maxage Level = 0 value is INTEGER: 3600|2IVRSRV####
@2014-02-21 17:24:15,666||FINEST|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|DocumentParser.cpp:371:Key = inet.maxstale Level = 0 value is INTEGER: 0|2IVRSRV####
@2014-02-21 17:24:15,666||FINEST|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|DocumentParser.cpp:371:Key = inet.prefetchHint Level = 0 value is STRING: safe|2IVRSRV####
@2014-02-21 17:24:15,666||FINEST|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|DocumentParser.cpp:371:Key = inet.sslMutualAuth Level = 0 value is STRING: no|2IVRSRV####
@2014-02-21 17:24:15,666||FINEST|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|DocumentParser.cpp:371:Key = inet.submitMethod Level = 0 value is STRING: POST|2IVRSRV####
@2014-02-21 17:24:15,666||FINEST|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|DocumentParser.cpp:371:Key = inet.submitMimeType Level = 0 value is STRING: multipart/form-data|2IVRSRV####
@2014-02-21 17:24:15,666||FINEST|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|DocumentParser.cpp:371:Key = inet.timeoutDownload Level = 0 value is INTEGER: 60000000|2IVRSRV####
@2014-02-21 17:24:15,666||FINEST|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|DocumentParser.cpp:371:Key = inet.timeoutIO Level = 0 value is INTEGER: 60000000|2IVRSRV####
@2014-02-21 17:24:15,667||FINEST|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|DocumentParser.cpp:371:Key = inet.urlBase Level = 0 value is STRING: http://172.17.72.92:8080/FlorenceNightingaleIVR/recording-createRecord?___DDSESSIONID=907D9390BBCC8484119F24EB131C76B8%3A%2FFlorenceNightingaleIVR|2IVRSRV####
@2014-02-21 17:24:15,667||FINEST|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|DocumentParser.cpp:371:Key = inet.urlQueryArgs Level = 0 value is MAP: -->|2IVRSRV####
@2014-02-21 17:24:15,667||FINEST|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|DocumentParser.cpp:371:Key = createRecord___duration Level = 1 value is INTEGER: 163080|2IVRSRV####
@2014-02-21 17:24:15,667||FINEST|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|DocumentParser.cpp:371:Key = createRecord___maxtime Level = 1 Unknown type|2IVRSRV####
@2014-02-21 17:24:15,667||FINEST|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|DocumentParser.cpp:371:Key = createRecord___size Level = 1 value is INTEGER: 1304688|2IVRSRV####
@2014-02-21 17:24:15,667||FINEST|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|DocumentParser.cpp:371:Key = createRecord___termchar Level = 1 value is STRING: 0|2IVRSRV####
@2014-02-21 17:24:15,667||FINEST|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|DocumentParser.cpp:371:Key = createRecord___value Level = 1 VXIFile: name = /opt/Avaya/ExperiencePortal/MPP/tmp/.557.wav mime-type: audio/x-wav size = 1304688|2IVRSRV####
@2014-02-21 17:24:15,667||FINEST|CommonVXI.Cache|14816|Session=2IVRSRV-2014052151916-5|SBinetChannel.cpp:312:SBinetChannel::Open: about to open stream|2IVRSRV####
@2014-02-21 17:24:15,670||FINER|CommonVXI.Cache|14816|Session=2IVRSRV-2014052151916-5|SBinetHttpStream.cpp:387:SBinetChannel::doPost: Performing multipart POST, data size =1305311|2IVRSRV####
@2014-02-21 17:24:15,670||FINEST|CommonVXI.Cache|14816|Session=2IVRSRV-2014052151916-5|SBinetHttpStream::initSocket enter|2IVRSRV####
@2014-02-21 17:24:15,670||FINEST|CommonVXI.Cache|14816|Session=2IVRSRV-2014052151916-5|SBinetChannel::getHttpConnection Called url=http://172.17.72.92:8080/FlorenceNightingaleIVR/recording-setURLOfFileName?___DDSESSIONID=907D9390BBCC8484119F24EB131C76B8%3A%2FFlorenceNightingaleIVR|2IVRSRV####
@2014-02-21 17:24:15,670||FINEST|CommonVXI.Cache|14816|Session=2IVRSRV-2014052151916-5|SBinetChannel::getHttpConnection getNPath=/FlorenceNightingaleIVR/recording-setURLOfFileName?___DDSESSIONID=907D9390BBCC8484119F24EB131C76B8%3A%2FFlorenceNightingaleIVR|2IVRSRV####
@2014-02-21 17:24:15,670||FINEST|CommonVXI.Cache|14816|Session=2IVRSRV-2014052151916-5|SBinetHttpStream.cpp:156:SBinetHttpStream::initSocket, Connect to socket, time=0|2IVRSRV####
@2014-02-21 17:24:15,670||FINER|CommonVXI.Cache|14816|Session=2IVRSRV-2014052151916-5|SBinetStream::getCacheInfo: maxAge=3600maxStale=0|2IVRSRV####
@2014-02-21 17:24:15,670||FINEST|CommonVXI.Cache|14816|Session=2IVRSRV-2014052151916-5|SBinetCookie.cpp:73:SBinetCookie::matchDomain called cdomain=172.17.72.92domain=172.17.72.92true|2IVRSRV####
@2014-02-21 17:24:15,670||FINEST|CommonVXI.Cache|14816|Session=2IVRSRV-2014052151916-5|SBinetCookie.cpp:133:SBinetCookie::matchPath cpath=/FlorenceNightingaleIVR, path=/FlorenceNightingaleIVR/recording-setURLOfFileName?___DDSESSIONID=907D9390BBCC8484119F24EB131C76B8%3A%2FFlorenceNightingaleIVRtrue|2IVRSRV####
@2014-02-21 17:24:15,670||FINEST|CommonVXI.Cache|14816|Session=2IVRSRV-2014052151916-5|SBinetCookie.cpp:163:SBinetCookie::matchRequest match=true, domain=172.17.72.92, path=/FlorenceNightingaleIVR/recording-setURLOfFileName?___DDSESSIONID=907D9390BBCC8484119F24EB131C76B8%3A%2FFlorenceNightingaleIVR, _Name=JSESSIONID, _Value=907D9390BBCC8484119F24EB131C76B8, _Name=JSESSIONID, _Domain=172.17.72.92, _Path=/FlorenceNightingaleIVR, expires=Thu Jan 1 02:00:00 1970
, _nExpires=0false|2IVRSRV####
@2014-02-21 17:24:15,670||FINEST|CommonVXI.Cache|14816|Session=2IVRSRV-2014052151916-5|SBinetCookie.cpp:73:SBinetCookie::matchDomain called cdomain=172.17.72.92domain=172.17.72.92true|2IVRSRV####
@2014-02-21 17:24:15,670||FINEST|CommonVXI.Cache|14816|Session=2IVRSRV-2014052151916-5|SBinetCookie.cpp:133:SBinetCookie::matchPath cpath=/, path=/FlorenceNightingaleIVR/recording-setURLOfFileName?___DDSESSIONID=907D9390BBCC8484119F24EB131C76B8%3A%2FFlorenceNightingaleIVRtrue|2IVRSRV####
@2014-02-21 17:24:15,670||FINEST|CommonVXI.Cache|14816|Session=2IVRSRV-2014052151916-5|SBinetCookie.cpp:163:SBinetCookie::matchRequest match=true, domain=172.17.72.92, path=/FlorenceNightingaleIVR/recording-setURLOfFileName?___DDSESSIONID=907D9390BBCC8484119F24EB131C76B8%3A%2FFlorenceNightingaleIVR, _Name=___DDSESSIONID, _Value=907D9390BBCC8484119F24EB131C76B8%3A%2FFlorenceNightingaleIVR, _Name=___DDSESSIONID, _Domain=172.17.72.92, _Path=/, expires=Thu Jan 1 02:00:00 1970
, _nExpires=0false|2IVRSRV####
@2014-02-21 17:24:15,670||FINE|VB|14816|Session=2IVRSRV-2014052151916-5|an error was encountered running this application|2IVRSRV####
@2014-02-21 17:24:15,671||FINEST|VB|14816|Session=2IVRSRV-2014052151916-5|******** Entering Utility.cpp:469:LogIPCMessage|2IVRSRV####
@2014-02-21 17:24:15,671||FINEST|VB|14816|Session=2IVRSRV-2014052151916-5|VXML_APP_ERROR
{
dialogHandle: 5
info: "PAVB_00222"
}
|2IVRSRV####
@2014-02-21 17:24:15,671||FINEST|VB|14816|Session=2IVRSRV-2014052151916-5|******** Leaving Utility.cpp:469:LogIPCMessage|2IVRSRV####
@2014-02-21 17:24:15,671|PAVB_00222|ERROR|VB|14816|Session=2IVRSRV-2014052151916-5|Socket Error in function SWIsocket::send, errorName: Unable to send data due to connection broken at the other end, errorCode: 32|2IVRSRV####
@2014-02-21 17:24:15,671||FINE|VB|14816|Session=2IVRSRV-2014052151916-5|an error was encountered running this application|2IVRSRV####
@2014-02-21 17:24:15,671||FINEST|VB|14816|Session=2IVRSRV-2014052151916-5|******** Entering Utility.cpp:469:LogIPCMessage|2IVRSRV####
@2014-02-21 17:24:15,671||FINEST|VB|14816|Session=2IVRSRV-2014052151916-5|VXML_APP_ERROR
{
dialogHandle: 5
info: "PAVB_00226"
}
|2IVRSRV####
@2014-02-21 17:24:15,671||FINEST|VB|14816|Session=2IVRSRV-2014052151916-5|******** Leaving Utility.cpp:469:LogIPCMessage|2IVRSRV####
@2014-02-21 17:24:15,671|PAVB_00226|ERROR|VB|14816|Session=2IVRSRV-2014052151916-5|HTTP connection error|2IVRSRV####
@2014-02-21 17:24:15,671||FINE|CommonVXI.Cache|14816|Session=2IVRSRV-2014052151916-5|Failed in POST with return code = -8|2IVRSRV####
@2014-02-21 17:24:15,671||FINEST|VB|14311|Session=2IVRSRV-2014052151916-5|******** Entering vpchannel.cpp:1372:IsHangup|2IVRSRV####
@2014-02-21 17:24:15,671||FINEST|VB|14311|Session=2IVRSRV-2014052151916-5|VPChannel::IsHangup returning FALSE|2IVRSRV####
@2014-02-21 17:24:15,671||FINEST|VB|14311|Session=2IVRSRV-2014052151916-5|******** Leaving vpchannel.cpp:1372:IsHangup|2IVRSRV####
@2014-02-21 17:24:15,671||FINE|CommonVXI.Cache|14816|Session=2IVRSRV-2014052151916-5|Undefined HTTP status -1234 for url = http://172.17.72.92:8080/FlorenceNightingaleIVR/recording-setURLOfFileName?___DDSESSIONID=907D9390BBCC8484119F24EB131C76B8%3A%2FFlorenceNightingaleIVR|2IVRSRV####
@2014-02-21 17:24:15,671||FINEST|CommonVXI.Cache|14816|Session=2IVRSRV-2014052151916-5|SBinetChannel.cpp:315:stream open returns -8|2IVRSRV####
@2014-02-21 17:24:15,672||FINE|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|DocumentParser::FetchBuffer - could not open URL: recording-setURLOfFileName?___DDSESSIONID=907D9390BBCC8484119F24EB131C76B8%3A%2FFlorenceNightingaleIVR|2IVRSRV####
@2014-02-21 17:24:15,672||FINE|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|DocumentParser.cpp:684:Failed to load document from URL recording-setURLOfFileName?___DDSESSIONID=907D9390BBCC8484119F24EB131C76B8%3A%2FFlorenceNightingaleIVR|2IVRSRV####
@2014-02-21 17:24:15,672||FINE|VB|14816|Session=2IVRSRV-2014052151916-5|an error was encountered running this application|2IVRSRV####
@2014-02-21 17:24:15,672||FINEST|VB|14816|Session=2IVRSRV-2014052151916-5|******** Entering Utility.cpp:469:LogIPCMessage|2IVRSRV####
@2014-02-21 17:24:15,672||FINEST|VB|14816|Session=2IVRSRV-2014052151916-5|VXML_APP_ERROR
{
dialogHandle: 5
info: "PAVB_03061"
}
|2IVRSRV####
@2014-02-21 17:24:15,672||FINEST|VB|14816|Session=2IVRSRV-2014052151916-5|******** Leaving Utility.cpp:469:LogIPCMessage|2IVRSRV####
@2014-02-21 17:24:15,672|PAVB_03061|ERROR|VB|14816|Session=2IVRSRV-2014052151916-5|error.badfetch - unable to open URI: recording-setURLOfFileName?___DDSESSIONID=907D9390BBCC8484119F24EB131C76B8%3A%2FFlorenceNightingaleIVR|2IVRSRV####
@2014-02-21 17:24:15,672|PAVB_03335|INFO|VB|14816|Session=2IVRSRV-2014052151916-5|Got VoiceXML exception: error.badfetch in http://172.17.72.92:8080/FlorenceNightingaleIVR/recording-createRecord?___DDSESSIONID=907D9390BBCC8484119F24EB131C76B8%3A%2FFlorenceNightingaleIVR|2IVRSRV####
@2014-02-21 17:24:15,672||FINE|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|VXI::DoEvent - Enter|2IVRSRV####
@2014-02-21 17:24:15,672||FINER|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|VXI.cpp:3214:VXI::playingPrompts = false|2IVRSRV####
@2014-02-21 17:24:15,672||FINER|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|VXI.cpp:3498:VXI::execute_content()|2IVRSRV####
@2014-02-21 17:24:15,672||FINER|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|VXI.cpp:3565:VXI::executable_element - GOTO|2IVRSRV####
@2014-02-21 17:24:15,672||FINER|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|VXI.cpp:4514:VXI::goto_element()|2IVRSRV####
@2014-02-21 17:24:15,672||FINER|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|VXI.cpp:1583:VXI::DoOuterJump(traceLastException?___DDSESSIONID=907D9390BBCC8484119F24EB131C76B8%3A%2FFlorenceNightingaleIVR)|2IVRSRV####
@2014-02-21 17:24:15,672||FINER|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|PropertyList.cpp:501:PropertyList::GetFetchobjCacheAttrs - (documentmaxage) of (3600s)|2IVRSRV####
@2014-02-21 17:24:15,673||FINER|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|PropertyList.cpp:559:PropertyList::GetFetchobjCacheAttrs - (documentmaxstale) of (0s)|2IVRSRV####
@2014-02-21 17:24:15,673||FINEST|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|VXI.cpp:1683:VXI::DoOuterJump - fetchaudiominimum: 0|2IVRSRV####
@2014-02-21 17:24:15,673||FINEST|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|VXI.cpp:1696:VXI::DoOuterJump - fetchaudiodelay: 0|2IVRSRV####
@2014-02-21 17:24:15,673||FINE|CommonVXI.Cache|14816|Session=2IVRSRV-2014052151916-5|SBinetChannel::GetAbsoluteNametraceLastException?___DDSESSIONID=907D9390BBCC8484119F24EB131C76B8%3A%2FFlorenceNightingaleIVR|2IVRSRV####
@2014-02-21 17:24:15,673||FINEST|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|VXI.cpp:1748:DoOuterJump(): Comparing m_preparedDocID to http://172.17.72.92:8080/FlorenceNightingaleIVR/traceLastException?___DDSESSIONID=907D9390BBCC8484119F24EB131C76B8%3A%2FFlorenceNightingaleIVR|2IVRSRV####
@2014-02-21 17:24:15,673||FINER|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|DocumentParser::FetchDocument(traceLastException?___DDSESSIONID=907D9390BBCC8484119F24EB131C76B8%3A%2FFlorenceNightingaleIVR)|2IVRSRV####
@2014-02-21 17:24:15,673||FINER|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|DocumentParser::FetchBuffer(traceLastException?___DDSESSIONID=907D9390BBCC8484119F24EB131C76B8%3A%2FFlorenceNightingaleIVR)|2IVRSRV####
@2014-02-21 17:24:15,673||FINEST|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|DocumentParser.cpp:370:Dumping INET Properties...|2IVRSRV####
@2014-02-21 17:24:15,673||FINEST|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|DocumentParser.cpp:371:Key = inet.authMode Level = 0 value is STRING: None|2IVRSRV####
@2014-02-21 17:24:15,673||FINEST|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|DocumentParser.cpp:371:Key = inet.basicAuthUserPwd Level = 0 value is STRING: :|2IVRSRV####
@2014-02-21 17:24:15,673||FINEST|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|DocumentParser.cpp:371:Key = inet.maxage Level = 0 value is INTEGER: 3600|2IVRSRV####
@2014-02-21 17:24:15,673||FINEST|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|DocumentParser.cpp:371:Key = inet.maxstale Level = 0 value is INTEGER: 0|2IVRSRV####
@2014-02-21 17:24:15,673||FINEST|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|DocumentParser.cpp:371:Key = inet.prefetchHint Level = 0 value is STRING: safe|2IVRSRV####
@2014-02-21 17:24:15,673||FINEST|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|DocumentParser.cpp:371:Key = inet.sslMutualAuth Level = 0 value is STRING: no|2IVRSRV####
@2014-02-21 17:24:15,673||FINEST|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|DocumentParser.cpp:371:Key = inet.timeoutDownload Level = 0 value is INTEGER: 60000000|2IVRSRV####
@2014-02-21 17:24:15,673||FINEST|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|DocumentParser.cpp:371:Key = inet.timeoutIO Level = 0 value is INTEGER: 60000000|2IVRSRV####
@2014-02-21 17:24:15,673||FINEST|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|DocumentParser.cpp:371:Key = inet.urlBase Level = 0 value is STRING: http://172.17.72.92:8080/FlorenceNightingaleIVR/recording-createRecord?___DDSESSIONID=907D9390BBCC8484119F24EB131C76B8%3A%2FFlorenceNightingaleIVR|2IVRSRV####
@2014-02-21 17:24:15,673||FINEST|CommonVXI.Cache|14816|Session=2IVRSRV-2014052151916-5|SBinetChannel.cpp:312:SBinetChannel::Open: about to open stream|2IVRSRV####
@2014-02-21 17:24:15,674||FINER|CommonVXI.Cache|14816|Session=2IVRSRV-2014052151916-5|SBinetStream::getCacheInfo: maxAge=3600maxStale=0|2IVRSRV####
@2014-02-21 17:24:15,674||FINEST|CommonVXI.Cache|14816|Session=2IVRSRV-2014052151916-5|SBinetHttpStream::initSocket enter|2IVRSRV####
@2014-02-21 17:24:15,674||FINEST|CommonVXI.Cache|14816|Session=2IVRSRV-2014052151916-5|SBinetChannel::getHttpConnection Called url=http://172.17.72.92:8080/FlorenceNightingaleIVR/traceLastException?___DDSESSIONID=907D9390BBCC8484119F24EB131C76B8%3A%2FFlorenceNightingaleIVR|2IVRSRV####
@2014-02-21 17:24:15,674||FINEST|CommonVXI.Cache|14816|Session=2IVRSRV-2014052151916-5|SBinetChannel::getHttpConnection getNPath=/FlorenceNightingaleIVR/traceLastException?___DDSESSIONID=907D9390BBCC8484119F24EB131C76B8%3A%2FFlorenceNightingaleIVR|2IVRSRV####
@2014-02-21 17:24:15,674||FINER|CommonVXI.Cache|14816|Session=2IVRSRV-2014052151916-5|SBinetHttpConnection.cpp:63:SBinetHttpConnection::connect timeout=60000000|2IVRSRV####
@2014-02-21 17:24:15,674||FINER|CommonVXI.Cache|14816|Session=2IVRSRV-2014052151916-5|SBinetHttpConnection.cpp:78:SBinetHttpConnection::Before connect timeout=60000000|2IVRSRV####
@2014-02-21 17:24:15,674||FINEST|CommonVXI.Cache|14816|Session=2IVRSRV-2014052151916-5|SWIsocket.cpp:172:SWIsocket::connect enter.|2IVRSRV####
@2014-02-21 17:24:15,674||FINEST|CommonVXI.Cache|14816|Session=2IVRSRV-2014052151916-5|SWIsocket.cpp:198:SWIsocket::connect ptv != NULL.|2IVRSRV####
@2014-02-21 17:24:15,674||FINE|CommonVXI.Cache|14816|Session=2IVRSRV-2014052151916-5|SWIsocket.cpp:222:connecting to 1548226988|2IVRSRV####
@2014-02-21 17:24:15,674||FINEST|CommonVXI.Cache|14816|Session=2IVRSRV-2014052151916-5|SWIsocket.cpp:261:SWIsocket::connect connected.|2IVRSRV####
@2014-02-21 17:24:15,674||FINE|CommonVXI.Cache|14816|Session=2IVRSRV-2014052151916-5|SWIsocket.cpp:328:Established Connection to 1548226988|2IVRSRV####
@2014-02-21 17:24:15,674||FINEST|CommonVXI.Cache|14816|Session=2IVRSRV-2014052151916-5|SWIsocket.cpp:427:SWIsocket::connect restore blocking mode.|2IVRSRV####
@2014-02-21 17:24:15,674||FINER|CommonVXI.Cache|14816|Session=2IVRSRV-2014052151916-5|SBinetHttpConnection.cpp:80:SBinetHttpConnection::After connect timeout=
PM
21/02/2014 10:47:22
Subject: Re:AOD 6.0 Record Node - Record Issue
#4
WilsonYu
Joined: Nov 6, 2013
Messages: 3950
Offline
Seems like the connection to app server is broken. Can you check the tomcat connection timeout property in the tomcat/conf/server.xml? It needs to be larger than the time of recording.
PM
17/03/2015 06:18:28
Subject: Re:AOD 6.0 Record Node - Record Issue
#5
YasinUNAL
Joined: Nov 12, 2013
Messages: 36
Offline
YasinUNAL wrote:Hi,

I made a test and started to record at 17:21:21 and finished to record by pressing DTMF "0" at 17:24.

We checked the MPP session logs and found some traces;

Please look at those lines; 
@2014-02-21 17:24:15,671||FINEST|VB|14816|Session=2IVRSRV-2014052151916-5|VXML_APP_ERROR

{

	dialogHandle: 5

	info: "PAVB_00222"

}

.....

@2014-02-21 17:24:15,671||FINEST|VB|14816|Session=2IVRSRV-2014052151916-5|VXML_APP_ERROR

{

	dialogHandle: 5

	info: "PAVB_00226"

}

....

@2014-02-21 17:24:15,672||FINEST|VB|14816|Session=2IVRSRV-2014052151916-5|VXML_APP_ERROR

{

	dialogHandle: 5

	info: "PAVB_03061"

}





[u][b]All trace file[/b][/u]

@2014-02-21 17:21:21,858||FINE|CommonVXI.Rec|14816|Session=2IVRSRV-2014052151916-5|AVBrecRecord() Entered|2IVRSRV####

@2014-02-21 17:21:21,858||FINE|CommonVXI.Rec|14816|Session=2IVRSRV-2014052151916-5|osbrec.cpp:1474:AVBrecRecord() - maxRecordingTime: 60000000|2IVRSRV####

@2014-02-21 17:21:21,858||FINE|CommonVXI.Rec|14816|Session=2IVRSRV-2014052151916-5|osbrec.cpp:1485:AVBrecRecord() - DTMF will interrupt recording|2IVRSRV####

@2014-02-21 17:21:21,858||FINE|CommonVXI.Rec|14816|Session=2IVRSRV-2014052151916-5|osbrec.cpp:1506:AVBrecRecord() - timeout: 40000|2IVRSRV####

@2014-02-21 17:21:21,858||FINE|CommonVXI.Rec|14816|Session=2IVRSRV-2014052151916-5|osbrec.cpp:1516:AVBrecRecord() - timeoutComplete: 60000000|2IVRSRV####

@2014-02-21 17:21:21,858||FINE|CommonVXI.Rec|14816|Session=2IVRSRV-2014052151916-5|osbrec.cpp:1567:AVBrecRecord() recordingMimeType: audio/x-wav|2IVRSRV####

@2014-02-21 17:21:21,858||FINE|CommonVXI.Rec|14816|Session=2IVRSRV-2014052151916-5|AVBrecRecord() - Playing All queued prompts|2IVRSRV####

@2014-02-21 17:21:21,858||FINEST|CommonVXI.Rec|14816|Session=2IVRSRV-2014052151916-5|AVBrecRecord() Dumping recording properties...|2IVRSRV####

@2014-02-21 17:21:21,858||FINEST|CommonVXI.Rec|14816|Session=2IVRSRV-2014052151916-5||2IVRSRV####

@2014-02-21 17:21:21,858||FINEST|CommonVXI.Rec|14816|Session=2IVRSRV-2014052151916-5|osbrec.cpp:1658:Key = vxi.rec.maxRecordingTime Level = 0 value is INTEGER: 60000000|2IVRSRV####

@2014-02-21 17:21:21,859||FINEST|CommonVXI.Rec|14816|Session=2IVRSRV-2014052151916-5|osbrec.cpp:1658:Key = vxi.rec.recordingMimeType Level = 0 value is STRING: audio/x-wav|2IVRSRV####

@2014-02-21 17:21:21,859||FINEST|CommonVXI.Rec|14816|Session=2IVRSRV-2014052151916-5|osbrec.cpp:1658:Key = vxi.rec.recordmodes Level = 0 value is STRING: audio|2IVRSRV####

@2014-02-21 17:21:21,859||FINEST|CommonVXI.Rec|14816|Session=2IVRSRV-2014052151916-5|osbrec.cpp:1658:Key = vxi.rec.terminatedOnDTMF Level = 0 value is INTEGER: 1|2IVRSRV####

@2014-02-21 17:21:21,859||FINEST|CommonVXI.Rec|14816|Session=2IVRSRV-2014052151916-5|osbrec.cpp:1658:Key = vxi.rec.timeout Level = 0 value is INTEGER: 40000|2IVRSRV####

@2014-02-21 17:21:21,859||FINEST|CommonVXI.Rec|14816|Session=2IVRSRV-2014052151916-5|osbrec.cpp:1658:Key = vxi.rec.timeoutComplete Level = 0 value is INTEGER: 60000000|2IVRSRV####

@2014-02-21 17:21:30,533||FINEST|VB|14877|Session=2IVRSRV-2014052151916-5|******** Entering Dialog.cpp:337:QueueMessage|2IVRSRV####

@2014-02-21 17:21:30,533||FINEST|VB|14877|Session=2IVRSRV-2014052151916-5|******** Leaving Dialog.cpp:337:QueueMessage|2IVRSRV####

@2014-02-21 17:21:30,533||FINEST|VB|14310|Session=2IVRSRV-2014052151916-5|******** Entering Dialog.cpp:348:ProcessInboundMessage|2IVRSRV####

@2014-02-21 17:21:30,533||FINEST|VB|14310|Session=2IVRSRV-2014052151916-5|******** Entering vpchannel.cpp:5620:QueueMessage|2IVRSRV####

@2014-02-21 17:21:30,533||FINEST|VB|14310|Session=2IVRSRV-2014052151916-5|******** Leaving vpchannel.cpp:5620:QueueMessage|2IVRSRV####

@2014-02-21 17:21:30,533||FINEST|VB|14310|Session=2IVRSRV-2014052151916-5|successfully added msg to channel queue|2IVRSRV####

@2014-02-21 17:21:30,533||FINEST|VB|14310|Session=2IVRSRV-2014052151916-5|******** Leaving Dialog.cpp:348:ProcessInboundMessage|2IVRSRV####

@2014-02-21 17:21:30,533||FINE|VB|14311|Session=2IVRSRV-2014052151916-5|received a VXML_PROMPT_PLAY_COMPLETE|2IVRSRV####

@2014-02-21 17:21:30,533||FINEST|VB|14311|Session=2IVRSRV-2014052151916-5|******** Entering Utility.cpp:469:LogIPCMessage|2IVRSRV####

@2014-02-21 17:21:30,533||FINEST|VB|14311|Session=2IVRSRV-2014052151916-5|VXML_PROMPT_PLAY_COMPLETE

{

	dialogHandle: 5

	playCompleted: 1

	promptID: 56

	position: 200

	markName: ""

	markTimeElapsed: 8661

	playCompleteStatus: COMPLETE_NORMAL

}

|2IVRSRV####

@2014-02-21 17:21:30,533||FINEST|VB|14311|Session=2IVRSRV-2014052151916-5|******** Leaving Utility.cpp:469:LogIPCMessage|2IVRSRV####

@2014-02-21 17:21:30,533||FINEST|VB|14311|Session=2IVRSRV-2014052151916-5|******** Leaving vpchannel.cpp:6525:WaitForMessage|2IVRSRV####

@2014-02-21 17:21:30,533||FINER|VB|14311|Session=2IVRSRV-2014052151916-5|Prompt playback completed. Stopping recognition.|2IVRSRV####

@2014-02-21 17:21:30,533||FINEST|VB|14311|Session=2IVRSRV-2014052151916-5|******** Entering vpchannel.cpp:6497:SendMessage|2IVRSRV####

@2014-02-21 17:21:30,533||FINE|VB|14311|Session=2IVRSRV-2014052151916-5|successfully sent message VXML_REC_CANCEL_RECOGNITION|2IVRSRV####

@2014-02-21 17:21:30,533||FINEST|VB|14311|Session=2IVRSRV-2014052151916-5|******** Entering Utility.cpp:469:LogIPCMessage|2IVRSRV####

@2014-02-21 17:21:30,534||FINEST|VB|14311|Session=2IVRSRV-2014052151916-5|VXML_REC_CANCEL_RECOGNITION

{

	dialogHandle: 5

}

|2IVRSRV####

@2014-02-21 17:21:30,534||FINEST|VB|14311|Session=2IVRSRV-2014052151916-5|******** Leaving Utility.cpp:469:LogIPCMessage|2IVRSRV####

@2014-02-21 17:21:30,534||FINEST|VB|14311|Session=2IVRSRV-2014052151916-5|******** Leaving vpchannel.cpp:6497:SendMessage|2IVRSRV####

@2014-02-21 17:21:30,534||FINEST|VB|14311|Session=2IVRSRV-2014052151916-5|******** Entering vpchannel.cpp:6525:WaitForMessage|2IVRSRV####

@2014-02-21 17:21:30,534||FINE|VB|14311|Session=2IVRSRV-2014052151916-5|waiting for VXML_REC_RECOGNITION_CANCELLED|2IVRSRV####

@2014-02-21 17:21:30,536||FINEST|VB|14877|Session=2IVRSRV-2014052151916-5|******** Entering Dialog.cpp:337:QueueMessage|2IVRSRV####

@2014-02-21 17:21:30,536||FINEST|VB|14877|Session=2IVRSRV-2014052151916-5|******** Leaving Dialog.cpp:337:QueueMessage|2IVRSRV####

@2014-02-21 17:21:30,536||FINEST|VB|14310|Session=2IVRSRV-2014052151916-5|******** Entering Dialog.cpp:348:ProcessInboundMessage|2IVRSRV####

@2014-02-21 17:21:30,537||FINEST|VB|14310|Session=2IVRSRV-2014052151916-5|******** Entering vpchannel.cpp:5620:QueueMessage|2IVRSRV####

@2014-02-21 17:21:30,537||FINEST|VB|14310|Session=2IVRSRV-2014052151916-5|******** Leaving vpchannel.cpp:5620:QueueMessage|2IVRSRV####

@2014-02-21 17:21:30,537||FINEST|VB|14310|Session=2IVRSRV-2014052151916-5|successfully added msg to channel queue|2IVRSRV####

@2014-02-21 17:21:30,537||FINEST|VB|14310|Session=2IVRSRV-2014052151916-5|******** Leaving Dialog.cpp:348:ProcessInboundMessage|2IVRSRV####

@2014-02-21 17:21:30,537||FINE|VB|14311|Session=2IVRSRV-2014052151916-5|received the expected VXML_REC_RECOGNITION_CANCELLED|2IVRSRV####

@2014-02-21 17:21:30,537||FINEST|VB|14311|Session=2IVRSRV-2014052151916-5|******** Entering Utility.cpp:469:LogIPCMessage|2IVRSRV####

@2014-02-21 17:21:30,537||FINEST|VB|14311|Session=2IVRSRV-2014052151916-5|VXML_REC_RECOGNITION_CANCELLED

{

	dialogHandle: 5

}

|2IVRSRV####

@2014-02-21 17:21:30,537||FINEST|VB|14311|Session=2IVRSRV-2014052151916-5|******** Leaving Utility.cpp:469:LogIPCMessage|2IVRSRV####

@2014-02-21 17:21:30,537||FINEST|VB|14311|Session=2IVRSRV-2014052151916-5|******** Leaving vpchannel.cpp:6525:WaitForMessage|2IVRSRV####

@2014-02-21 17:21:30,537||FINEST|VB|14311|Session=2IVRSRV-2014052151916-5|******** Entering vpchannel.cpp:1474:GetInputModes|2IVRSRV####

@2014-02-21 17:21:30,537||FINEST|VB|14311|Session=2IVRSRV-2014052151916-5|ASR: 0, DTMF: 0|2IVRSRV####

@2014-02-21 17:21:30,537||FINEST|VB|14311|Session=2IVRSRV-2014052151916-5|******** Leaving vpchannel.cpp:1474:GetInputModes|2IVRSRV####

@2014-02-21 17:21:30,537||FINEST|VB|14311|Session=2IVRSRV-2014052151916-5|will record to file: /opt/Avaya/ExperiencePortal/MPP/tmp/.557.wav|2IVRSRV####

@2014-02-21 17:21:30,537||FINEST|VB|14311|Session=2IVRSRV-2014052151916-5|******** Entering vpchannel.cpp:6497:SendMessage|2IVRSRV####

@2014-02-21 17:21:30,537||FINE|VB|14311|Session=2IVRSRV-2014052151916-5|successfully sent message VXML_TEL_RECORD|2IVRSRV####

@2014-02-21 17:21:30,537||FINEST|VB|14311|Session=2IVRSRV-2014052151916-5|******** Entering Utility.cpp:469:LogIPCMessage|2IVRSRV####

@2014-02-21 17:21:30,537||FINEST|VB|14311|Session=2IVRSRV-2014052151916-5|VXML_TEL_RECORD

{

	dialogHandle: 5

	withRecognition: 0

	maxTime: 60000000

	finalSilence: 60000000

	preSilence: 40000

	DTMFTerminate: 1

	MIMEType: "audio/x-wav"

	fileURI: "/opt/Avaya/ExperiencePortal/MPP/tmp/.557.wav"

}

|2IVRSRV####

@2014-02-21 17:21:30,537||FINEST|VB|14311|Session=2IVRSRV-2014052151916-5|******** Leaving Utility.cpp:469:LogIPCMessage|2IVRSRV####

@2014-02-21 17:21:30,537||FINEST|VB|14311|Session=2IVRSRV-2014052151916-5|******** Leaving vpchannel.cpp:6497:SendMessage|2IVRSRV####

@2014-02-21 17:21:30,537||FINEST|VB|14311|Session=2IVRSRV-2014052151916-5|******** Entering vpchannel.cpp:6525:WaitForMessage|2IVRSRV####

@2014-02-21 17:21:30,537||FINE|VB|14311|Session=2IVRSRV-2014052151916-5|waiting for DIALOG_SPECIFIC_MESSAGES|2IVRSRV####

@2014-02-21 17:24:15,662||FINEST|VB|14877|Session=2IVRSRV-2014052151916-5|******** Entering Dialog.cpp:337:QueueMessage|2IVRSRV####

@2014-02-21 17:24:15,662||FINEST|VB|14877|Session=2IVRSRV-2014052151916-5|******** Leaving Dialog.cpp:337:QueueMessage|2IVRSRV####

@2014-02-21 17:24:15,662||FINEST|VB|14310|Session=2IVRSRV-2014052151916-5|******** Entering Dialog.cpp:348:ProcessInboundMessage|2IVRSRV####

@2014-02-21 17:24:15,662||FINEST|VB|14310|Session=2IVRSRV-2014052151916-5|******** Entering vpchannel.cpp:5620:QueueMessage|2IVRSRV####

@2014-02-21 17:24:15,662||FINEST|VB|14310|Session=2IVRSRV-2014052151916-5|******** Leaving vpchannel.cpp:5620:QueueMessage|2IVRSRV####

@2014-02-21 17:24:15,662||FINEST|VB|14310|Session=2IVRSRV-2014052151916-5|successfully added msg to channel queue|2IVRSRV####

@2014-02-21 17:24:15,662||FINEST|VB|14310|Session=2IVRSRV-2014052151916-5|******** Leaving Dialog.cpp:348:ProcessInboundMessage|2IVRSRV####

@2014-02-21 17:24:15,662||FINE|VB|14311|Session=2IVRSRV-2014052151916-5|received a VXML_TEL_RECORD_RESULT|2IVRSRV####

@2014-02-21 17:24:15,662||FINEST|VB|14311|Session=2IVRSRV-2014052151916-5|******** Entering Utility.cpp:469:LogIPCMessage|2IVRSRV####

@2014-02-21 17:24:15,662||FINEST|VB|14311|Session=2IVRSRV-2014052151916-5|VXML_TEL_RECORD_RESULT

{

	dialogHandle: 5

	duration: 163080

	DTMFChar: "0"

	reason: RECORD_DTMF

}

|2IVRSRV####

@2014-02-21 17:24:15,662||FINEST|VB|14311|Session=2IVRSRV-2014052151916-5|******** Leaving Utility.cpp:469:LogIPCMessage|2IVRSRV####

@2014-02-21 17:24:15,662||FINEST|VB|14311|Session=2IVRSRV-2014052151916-5|******** Leaving vpchannel.cpp:6525:WaitForMessage|2IVRSRV####

@2014-02-21 17:24:15,662||FINEST|VB|14311|Session=2IVRSRV-2014052151916-5|VPChannel::DoRecord got a record result!|2IVRSRV####

@2014-02-21 17:24:15,662||FINER|VB|14311|Session=2IVRSRV-2014052151916-5|valid Recordresult, status: 4|2IVRSRV####

@2014-02-21 17:24:15,662||FINEST|VB|14311|Session=2IVRSRV-2014052151916-5|setting result->duration to 163080|2IVRSRV####

@2014-02-21 17:24:15,662||FINEST|VB|14311|Session=2IVRSRV-2014052151916-5|setting result->status to REC_STATUS_SUCCESS|2IVRSRV####

@2014-02-21 17:24:15,662||FINEST|VB|14311|Session=2IVRSRV-2014052151916-5|******** Leaving vpchannel.cpp:3057:DoRecord|2IVRSRV####

@2014-02-21 17:24:15,662||FINE|CommonVXI.Rec|14816|Session=2IVRSRV-2014052151916-5|osbrec.cpp:1673:AVBrecRecord() Platform call IChannel::DoRecord() returned: CHANNEL_RESULT_SUCCESS|2IVRSRV####

@2014-02-21 17:24:15,663||FINE|CommonVXI.Rec|14816|Session=2IVRSRV-2014052151916-5|AVBrecRecord.. Exit|2IVRSRV####

@2014-02-21 17:24:15,663||FINE|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|GrammarManager.cpp:2707:GrammarManager::Record()  platform call returned: 0|2IVRSRV####

@2014-02-21 17:24:15,663||FINER|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|GrammarManager.cpp:2737:GrammarManager::Record()  record result status: 0|2IVRSRV####

@2014-02-21 17:24:15,663||FINER|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|GrammarManager.cpp:2767:GrammarManager::Record()  number of rec results: 0|2IVRSRV####

@2014-02-21 17:24:15,663||FINER|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|GrammarManager.cpp:3020:GrammarManager::Record() - no DTMF grammar.|2IVRSRV####

@2014-02-21 17:24:15,663||FINER|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|VXI.cpp:5490:file: file:///opt/Avaya/ExperiencePortal/MPP/tmp/.557.wav|2IVRSRV####

@2014-02-21 17:24:15,663||FINER|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|VXI.cpp:5495:uristr: file:///opt/Avaya/ExperiencePortal/MPP/tmp/.557.wav|2IVRSRV####

@2014-02-21 17:24:15,663||FINER|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|VXI.cpp:5505:Filename before stat: /opt/Avaya/ExperiencePortal/MPP/tmp/.557.wav|2IVRSRV####

@2014-02-21 17:24:15,663||FINER|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|VXI.cpp:5527:contentSize: 1304688|2IVRSRV####

@2014-02-21 17:24:15,663||FINER|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|VXI.cpp:5539:wFile: /opt/Avaya/ExperiencePortal/MPP/tmp/.557.wav|2IVRSRV####

@2014-02-21 17:24:15,663||FINER|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|VXI.cpp:5752:VXI::record_element() - no global grammar match found|2IVRSRV####

@2014-02-21 17:24:15,663||FINER|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|VXI.cpp:5765:VXI::record_element() - saving recording to <record> variable name: createRecord|2IVRSRV####

@2014-02-21 17:24:15,663||FINER|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|VXI.cpp:5784:wFile: /opt/Avaya/ExperiencePortal/MPP/tmp/.557.wav|2IVRSRV####

@2014-02-21 17:24:15,663||FINER|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|VXI.cpp:5817:VXI::record_element() - saving duration to shadow variable--> dialog.createRecord$.duration = 163080|2IVRSRV####

@2014-02-21 17:24:15,663||FINER|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|VXI.cpp:5843:VXI::record_element() - Saving the term char to shadow variable--> dialog.createRecord$.termchar = '0'|2IVRSRV####

@2014-02-21 17:24:15,663||FINER|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|VXI.cpp:5866:VXI::record_element() - Saving the maxtime to shadow variable--> dialog.createRecord$.maxtime = false|2IVRSRV####

@2014-02-21 17:24:15,663||FINER|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|VXI.cpp:5889:file: file:///opt/Avaya/ExperiencePortal/MPP/tmp/.557.wav|2IVRSRV####

@2014-02-21 17:24:15,663||FINER|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|VXI.cpp:5893:uristr: file:///opt/Avaya/ExperiencePortal/MPP/tmp/.557.wav|2IVRSRV####

@2014-02-21 17:24:15,663||FINER|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|VXI.cpp:5900:Filename before stat: /opt/Avaya/ExperiencePortal/MPP/tmp/.557.wav|2IVRSRV####

@2014-02-21 17:24:15,664||FINER|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|VXI.cpp:5926:VXI::record_element() - Saving the size to shadow variable--> dialog.createRecord$.size = 1304688|2IVRSRV####

@2014-02-21 17:24:15,664||FINER|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|VXI.cpp:3498:VXI::execute_content()|2IVRSRV####

@2014-02-21 17:24:15,664||FINER|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|VXI.cpp:3565:VXI::executable_element - ASSIGN|2IVRSRV####

@2014-02-21 17:24:15,664||FINER|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|VXI.cpp:3647:VXI::assign_element(name = createRecord___value exp = try {createRecord} catch (errMsg) {'undefined'})|2IVRSRV####

@2014-02-21 17:24:15,664||FINER|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|VXI.cpp:3565:VXI::executable_element - ASSIGN|2IVRSRV####

@2014-02-21 17:24:15,664||FINER|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|VXI.cpp:3647:VXI::assign_element(name = createRecord___confidence exp = try {createRecord$.confidence} catch (errMsg) {'undefined'})|2IVRSRV####

@2014-02-21 17:24:15,664||FINER|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|VXI.cpp:3565:VXI::executable_element - ASSIGN|2IVRSRV####

@2014-02-21 17:24:15,664||FINER|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|VXI.cpp:3647:VXI::assign_element(name = createRecord___utterance exp = try {createRecord$.utterance} catch (errMsg) {'undefined'})|2IVRSRV####

@2014-02-21 17:24:15,664||FINER|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|VXI.cpp:3565:VXI::executable_element - ASSIGN|2IVRSRV####

@2014-02-21 17:24:15,664||FINER|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|VXI.cpp:3647:VXI::assign_element(name = createRecord___duration exp = try {createRecord$.duration} catch (errMsg) {'undefined'})|2IVRSRV####

@2014-02-21 17:24:15,664||FINER|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|VXI.cpp:3565:VXI::executable_element - ASSIGN|2IVRSRV####

@2014-02-21 17:24:15,664||FINER|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|VXI.cpp:3647:VXI::assign_element(name = createRecord___size exp = try {createRecord$.size} catch (errMsg) {'undefined'})|2IVRSRV####

@2014-02-21 17:24:15,664||FINER|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|VXI.cpp:3565:VXI::executable_element - ASSIGN|2IVRSRV####

@2014-02-21 17:24:15,664||FINER|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|VXI.cpp:3647:VXI::assign_element(name = createRecord___termchar exp = try {createRecord$.termchar} catch (errMsg) {'undefined'})|2IVRSRV####

@2014-02-21 17:24:15,664||FINEST|VB|14311|Session=2IVRSRV-2014052151916-5|******** Entering vpchannel.cpp:1372:IsHangup|2IVRSRV####

@2014-02-21 17:24:15,664||FINEST|VB|14311|Session=2IVRSRV-2014052151916-5|VPChannel::IsHangup returning FALSE|2IVRSRV####

@2014-02-21 17:24:15,664||FINEST|VB|14311|Session=2IVRSRV-2014052151916-5|******** Leaving vpchannel.cpp:1372:IsHangup|2IVRSRV####

@2014-02-21 17:24:15,664||FINER|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|VXI.cpp:3565:VXI::executable_element - ASSIGN|2IVRSRV####

@2014-02-21 17:24:15,665||FINER|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|VXI.cpp:3647:VXI::assign_element(name = createRecord___maxtime exp = try {createRecord$.maxtime} catch (errMsg) {'undefined'})|2IVRSRV####

@2014-02-21 17:24:15,665||FINER|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|VXI.cpp:3089:VXI::DoInnerJump()|2IVRSRV####

@2014-02-21 17:24:15,665||FINE|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|VXI::DoInnerJump - Checking for hangup before executing form item - BLOCK|2IVRSRV####

@2014-02-21 17:24:15,665||FINER|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|GrammarManager.cpp:408:GrammarManager::DoDelayThrow() - forcing spec compliance|2IVRSRV####

@2014-02-21 17:24:15,665||FINER|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|GrammarManager.cpp:493:GrammarManager::DoDelayThrow() - no delayed throw scheduled|2IVRSRV####

@2014-02-21 17:24:15,665||FINER|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|DocumentParser::DoDelayThrow enter|2IVRSRV####

@2014-02-21 17:24:15,665||FINER|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|VXI.cpp:5039:VXI::CollectPhase - (block)|2IVRSRV####

@2014-02-21 17:24:15,665||FINER|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|VXI.cpp:5072:VXI::block_element()|2IVRSRV####

@2014-02-21 17:24:15,665||FINER|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|VXI.cpp:3498:VXI::execute_content()|2IVRSRV####

@2014-02-21 17:24:15,665||FINEST|VB|14311|Session=2IVRSRV-2014052151916-5|******** Entering vpchannel.cpp:1372:IsHangup|2IVRSRV####

@2014-02-21 17:24:15,665||FINEST|VB|14311|Session=2IVRSRV-2014052151916-5|VPChannel::IsHangup returning FALSE|2IVRSRV####

@2014-02-21 17:24:15,665||FINEST|VB|14311|Session=2IVRSRV-2014052151916-5|******** Leaving vpchannel.cpp:1372:IsHangup|2IVRSRV####

@2014-02-21 17:24:15,665||FINER|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|VXI.cpp:3565:VXI::executable_element - SUBMIT|2IVRSRV####

@2014-02-21 17:24:15,665||FINER|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|VXI.cpp:4568:VXI::submit_element()|2IVRSRV####

@2014-02-21 17:24:15,665||FINER|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|VXI.cpp:1583:VXI::DoOuterJump(recording-setURLOfFileName?___DDSESSIONID=907D9390BBCC8484119F24EB131C76B8%3A%2FFlorenceNightingaleIVR)|2IVRSRV####

@2014-02-21 17:24:15,665||FINER|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|PropertyList.cpp:501:PropertyList::GetFetchobjCacheAttrs - (documentmaxage) of (3600s)|2IVRSRV####

@2014-02-21 17:24:15,665||FINER|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|PropertyList.cpp:559:PropertyList::GetFetchobjCacheAttrs - (documentmaxstale) of (0s)|2IVRSRV####

@2014-02-21 17:24:15,665||FINEST|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|VXI.cpp:1655:Map has no properties. Level: 0|2IVRSRV####

@2014-02-21 17:24:15,666||FINEST|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|VXI.cpp:1683:VXI::DoOuterJump - fetchaudiominimum: 0|2IVRSRV####

@2014-02-21 17:24:15,666||FINEST|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|VXI.cpp:1696:VXI::DoOuterJump - fetchaudiodelay: 0|2IVRSRV####

@2014-02-21 17:24:15,666||FINE|CommonVXI.Cache|14816|Session=2IVRSRV-2014052151916-5|SBinetChannel::GetAbsoluteNamerecording-setURLOfFileName?___DDSESSIONID=907D9390BBCC8484119F24EB131C76B8%3A%2FFlorenceNightingaleIVR|2IVRSRV####

@2014-02-21 17:24:15,666||FINEST|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|VXI.cpp:1748:DoOuterJump(): Comparing m_preparedDocID to http://172.17.72.92:8080/FlorenceNightingaleIVR/recording-setURLOfFileName?___DDSESSIONID=907D9390BBCC8484119F24EB131C76B8%3A%2FFlorenceNightingaleIVR|2IVRSRV####

@2014-02-21 17:24:15,666||FINER|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|DocumentParser::FetchDocument(recording-setURLOfFileName?___DDSESSIONID=907D9390BBCC8484119F24EB131C76B8%3A%2FFlorenceNightingaleIVR)|2IVRSRV####

@2014-02-21 17:24:15,666||FINER|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|DocumentParser::FetchBuffer(recording-setURLOfFileName?___DDSESSIONID=907D9390BBCC8484119F24EB131C76B8%3A%2FFlorenceNightingaleIVR)|2IVRSRV####

@2014-02-21 17:24:15,666||FINEST|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|DocumentParser.cpp:370:Dumping INET Properties...|2IVRSRV####

@2014-02-21 17:24:15,666||FINEST|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|DocumentParser.cpp:371:Key = inet.authMode Level = 0 value is STRING: None|2IVRSRV####

@2014-02-21 17:24:15,666||FINEST|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|DocumentParser.cpp:371:Key = inet.basicAuthUserPwd Level = 0 value is STRING: :|2IVRSRV####

@2014-02-21 17:24:15,666||FINEST|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|DocumentParser.cpp:371:Key = inet.maxage Level = 0 value is INTEGER: 3600|2IVRSRV####

@2014-02-21 17:24:15,666||FINEST|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|DocumentParser.cpp:371:Key = inet.maxstale Level = 0 value is INTEGER: 0|2IVRSRV####

@2014-02-21 17:24:15,666||FINEST|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|DocumentParser.cpp:371:Key = inet.prefetchHint Level = 0 value is STRING: safe|2IVRSRV####

@2014-02-21 17:24:15,666||FINEST|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|DocumentParser.cpp:371:Key = inet.sslMutualAuth Level = 0 value is STRING: no|2IVRSRV####

@2014-02-21 17:24:15,666||FINEST|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|DocumentParser.cpp:371:Key = inet.submitMethod Level = 0 value is STRING: POST|2IVRSRV####

@2014-02-21 17:24:15,666||FINEST|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|DocumentParser.cpp:371:Key = inet.submitMimeType Level = 0 value is STRING: multipart/form-data|2IVRSRV####

@2014-02-21 17:24:15,666||FINEST|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|DocumentParser.cpp:371:Key = inet.timeoutDownload Level = 0 value is INTEGER: 60000000|2IVRSRV####

@2014-02-21 17:24:15,666||FINEST|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|DocumentParser.cpp:371:Key = inet.timeoutIO Level = 0 value is INTEGER: 60000000|2IVRSRV####

@2014-02-21 17:24:15,667||FINEST|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|DocumentParser.cpp:371:Key = inet.urlBase Level = 0 value is STRING: http://172.17.72.92:8080/FlorenceNightingaleIVR/recording-createRecord?___DDSESSIONID=907D9390BBCC8484119F24EB131C76B8%3A%2FFlorenceNightingaleIVR|2IVRSRV####

@2014-02-21 17:24:15,667||FINEST|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|DocumentParser.cpp:371:Key = inet.urlQueryArgs Level = 0 value is MAP: -->|2IVRSRV####

@2014-02-21 17:24:15,667||FINEST|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|DocumentParser.cpp:371:Key = createRecord___duration Level = 1 value is INTEGER: 163080|2IVRSRV####

@2014-02-21 17:24:15,667||FINEST|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|DocumentParser.cpp:371:Key = createRecord___maxtime Level = 1 Unknown type|2IVRSRV####

@2014-02-21 17:24:15,667||FINEST|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|DocumentParser.cpp:371:Key = createRecord___size Level = 1 value is INTEGER: 1304688|2IVRSRV####

@2014-02-21 17:24:15,667||FINEST|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|DocumentParser.cpp:371:Key = createRecord___termchar Level = 1 value is STRING: 0|2IVRSRV####

@2014-02-21 17:24:15,667||FINEST|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|DocumentParser.cpp:371:Key = createRecord___value Level = 1 VXIFile: name = /opt/Avaya/ExperiencePortal/MPP/tmp/.557.wav mime-type: audio/x-wav size = 1304688|2IVRSRV####

@2014-02-21 17:24:15,667||FINEST|CommonVXI.Cache|14816|Session=2IVRSRV-2014052151916-5|SBinetChannel.cpp:312:SBinetChannel::Open: about to open stream|2IVRSRV####

@2014-02-21 17:24:15,670||FINER|CommonVXI.Cache|14816|Session=2IVRSRV-2014052151916-5|SBinetHttpStream.cpp:387:SBinetChannel::doPost: Performing multipart POST, data size =1305311|2IVRSRV####

@2014-02-21 17:24:15,670||FINEST|CommonVXI.Cache|14816|Session=2IVRSRV-2014052151916-5|SBinetHttpStream::initSocket enter|2IVRSRV####

@2014-02-21 17:24:15,670||FINEST|CommonVXI.Cache|14816|Session=2IVRSRV-2014052151916-5|SBinetChannel::getHttpConnection Called url=http://172.17.72.92:8080/FlorenceNightingaleIVR/recording-setURLOfFileName?___DDSESSIONID=907D9390BBCC8484119F24EB131C76B8%3A%2FFlorenceNightingaleIVR|2IVRSRV####

@2014-02-21 17:24:15,670||FINEST|CommonVXI.Cache|14816|Session=2IVRSRV-2014052151916-5|SBinetChannel::getHttpConnection getNPath=/FlorenceNightingaleIVR/recording-setURLOfFileName?___DDSESSIONID=907D9390BBCC8484119F24EB131C76B8%3A%2FFlorenceNightingaleIVR|2IVRSRV####

@2014-02-21 17:24:15,670||FINEST|CommonVXI.Cache|14816|Session=2IVRSRV-2014052151916-5|SBinetHttpStream.cpp:156:SBinetHttpStream::initSocket, Connect to socket, time=0|2IVRSRV####

@2014-02-21 17:24:15,670||FINER|CommonVXI.Cache|14816|Session=2IVRSRV-2014052151916-5|SBinetStream::getCacheInfo: maxAge=3600maxStale=0|2IVRSRV####

@2014-02-21 17:24:15,670||FINEST|CommonVXI.Cache|14816|Session=2IVRSRV-2014052151916-5|SBinetCookie.cpp:73:SBinetCookie::matchDomain called cdomain=172.17.72.92domain=172.17.72.92true|2IVRSRV####

@2014-02-21 17:24:15,670||FINEST|CommonVXI.Cache|14816|Session=2IVRSRV-2014052151916-5|SBinetCookie.cpp:133:SBinetCookie::matchPath cpath=/FlorenceNightingaleIVR, path=/FlorenceNightingaleIVR/recording-setURLOfFileName?___DDSESSIONID=907D9390BBCC8484119F24EB131C76B8%3A%2FFlorenceNightingaleIVRtrue|2IVRSRV####

@2014-02-21 17:24:15,670||FINEST|CommonVXI.Cache|14816|Session=2IVRSRV-2014052151916-5|SBinetCookie.cpp:163:SBinetCookie::matchRequest match=true, domain=172.17.72.92, path=/FlorenceNightingaleIVR/recording-setURLOfFileName?___DDSESSIONID=907D9390BBCC8484119F24EB131C76B8%3A%2FFlorenceNightingaleIVR, _Name=JSESSIONID, _Value=907D9390BBCC8484119F24EB131C76B8, _Name=JSESSIONID, _Domain=172.17.72.92, _Path=/FlorenceNightingaleIVR, expires=Thu Jan  1 02:00:00 1970

, _nExpires=0false|2IVRSRV####

@2014-02-21 17:24:15,670||FINEST|CommonVXI.Cache|14816|Session=2IVRSRV-2014052151916-5|SBinetCookie.cpp:73:SBinetCookie::matchDomain called cdomain=172.17.72.92domain=172.17.72.92true|2IVRSRV####

@2014-02-21 17:24:15,670||FINEST|CommonVXI.Cache|14816|Session=2IVRSRV-2014052151916-5|SBinetCookie.cpp:133:SBinetCookie::matchPath cpath=/, path=/FlorenceNightingaleIVR/recording-setURLOfFileName?___DDSESSIONID=907D9390BBCC8484119F24EB131C76B8%3A%2FFlorenceNightingaleIVRtrue|2IVRSRV####

@2014-02-21 17:24:15,670||FINEST|CommonVXI.Cache|14816|Session=2IVRSRV-2014052151916-5|SBinetCookie.cpp:163:SBinetCookie::matchRequest match=true, domain=172.17.72.92, path=/FlorenceNightingaleIVR/recording-setURLOfFileName?___DDSESSIONID=907D9390BBCC8484119F24EB131C76B8%3A%2FFlorenceNightingaleIVR, _Name=___DDSESSIONID, _Value=907D9390BBCC8484119F24EB131C76B8%3A%2FFlorenceNightingaleIVR, _Name=___DDSESSIONID, _Domain=172.17.72.92, _Path=/, expires=Thu Jan  1 02:00:00 1970

, _nExpires=0false|2IVRSRV####

@2014-02-21 17:24:15,670||FINE|VB|14816|Session=2IVRSRV-2014052151916-5|an error was encountered running this application|2IVRSRV####

@2014-02-21 17:24:15,671||FINEST|VB|14816|Session=2IVRSRV-2014052151916-5|******** Entering Utility.cpp:469:LogIPCMessage|2IVRSRV####

@2014-02-21 17:24:15,671||FINEST|VB|14816|Session=2IVRSRV-2014052151916-5|VXML_APP_ERROR

{

	dialogHandle: 5

	info: "PAVB_00222"

}

|2IVRSRV####

@2014-02-21 17:24:15,671||FINEST|VB|14816|Session=2IVRSRV-2014052151916-5|******** Leaving Utility.cpp:469:LogIPCMessage|2IVRSRV####

@2014-02-21 17:24:15,671|PAVB_00222|ERROR|VB|14816|Session=2IVRSRV-2014052151916-5|Socket Error in function SWIsocket::send, errorName: Unable to send data due to connection broken at the other end, errorCode: 32|2IVRSRV####

@2014-02-21 17:24:15,671||FINE|VB|14816|Session=2IVRSRV-2014052151916-5|an error was encountered running this application|2IVRSRV####

@2014-02-21 17:24:15,671||FINEST|VB|14816|Session=2IVRSRV-2014052151916-5|******** Entering Utility.cpp:469:LogIPCMessage|2IVRSRV####

@2014-02-21 17:24:15,671||FINEST|VB|14816|Session=2IVRSRV-2014052151916-5|VXML_APP_ERROR

{

	dialogHandle: 5

	info: "PAVB_00226"

}

|2IVRSRV####

@2014-02-21 17:24:15,671||FINEST|VB|14816|Session=2IVRSRV-2014052151916-5|******** Leaving Utility.cpp:469:LogIPCMessage|2IVRSRV####

@2014-02-21 17:24:15,671|PAVB_00226|ERROR|VB|14816|Session=2IVRSRV-2014052151916-5|HTTP connection error|2IVRSRV####

@2014-02-21 17:24:15,671||FINE|CommonVXI.Cache|14816|Session=2IVRSRV-2014052151916-5|Failed in POST with return code = -8|2IVRSRV####

@2014-02-21 17:24:15,671||FINEST|VB|14311|Session=2IVRSRV-2014052151916-5|******** Entering vpchannel.cpp:1372:IsHangup|2IVRSRV####

@2014-02-21 17:24:15,671||FINEST|VB|14311|Session=2IVRSRV-2014052151916-5|VPChannel::IsHangup returning FALSE|2IVRSRV####

@2014-02-21 17:24:15,671||FINEST|VB|14311|Session=2IVRSRV-2014052151916-5|******** Leaving vpchannel.cpp:1372:IsHangup|2IVRSRV####

@2014-02-21 17:24:15,671||FINE|CommonVXI.Cache|14816|Session=2IVRSRV-2014052151916-5|Undefined HTTP status -1234 for url = http://172.17.72.92:8080/FlorenceNightingaleIVR/recording-setURLOfFileName?___DDSESSIONID=907D9390BBCC8484119F24EB131C76B8%3A%2FFlorenceNightingaleIVR|2IVRSRV####

@2014-02-21 17:24:15,671||FINEST|CommonVXI.Cache|14816|Session=2IVRSRV-2014052151916-5|SBinetChannel.cpp:315:stream open returns -8|2IVRSRV####

@2014-02-21 17:24:15,672||FINE|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|DocumentParser::FetchBuffer - could not open URL: recording-setURLOfFileName?___DDSESSIONID=907D9390BBCC8484119F24EB131C76B8%3A%2FFlorenceNightingaleIVR|2IVRSRV####

@2014-02-21 17:24:15,672||FINE|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|DocumentParser.cpp:684:Failed to load document from URL recording-setURLOfFileName?___DDSESSIONID=907D9390BBCC8484119F24EB131C76B8%3A%2FFlorenceNightingaleIVR|2IVRSRV####

@2014-02-21 17:24:15,672||FINE|VB|14816|Session=2IVRSRV-2014052151916-5|an error was encountered running this application|2IVRSRV####

@2014-02-21 17:24:15,672||FINEST|VB|14816|Session=2IVRSRV-2014052151916-5|******** Entering Utility.cpp:469:LogIPCMessage|2IVRSRV####

@2014-02-21 17:24:15,672||FINEST|VB|14816|Session=2IVRSRV-2014052151916-5|VXML_APP_ERROR

{

	dialogHandle: 5

	info: "PAVB_03061"

}

|2IVRSRV####

@2014-02-21 17:24:15,672||FINEST|VB|14816|Session=2IVRSRV-2014052151916-5|******** Leaving Utility.cpp:469:LogIPCMessage|2IVRSRV####

@2014-02-21 17:24:15,672|PAVB_03061|ERROR|VB|14816|Session=2IVRSRV-2014052151916-5|error.badfetch - unable to open URI: recording-setURLOfFileName?___DDSESSIONID=907D9390BBCC8484119F24EB131C76B8%3A%2FFlorenceNightingaleIVR|2IVRSRV####

@2014-02-21 17:24:15,672|PAVB_03335|INFO|VB|14816|Session=2IVRSRV-2014052151916-5|Got VoiceXML exception: error.badfetch in http://172.17.72.92:8080/FlorenceNightingaleIVR/recording-createRecord?___DDSESSIONID=907D9390BBCC8484119F24EB131C76B8%3A%2FFlorenceNightingaleIVR|2IVRSRV####

@2014-02-21 17:24:15,672||FINE|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|VXI::DoEvent - Enter|2IVRSRV####

@2014-02-21 17:24:15,672||FINER|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|VXI.cpp:3214:VXI::playingPrompts = false|2IVRSRV####

@2014-02-21 17:24:15,672||FINER|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|VXI.cpp:3498:VXI::execute_content()|2IVRSRV####

@2014-02-21 17:24:15,672||FINER|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|VXI.cpp:3565:VXI::executable_element - GOTO|2IVRSRV####

@2014-02-21 17:24:15,672||FINER|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|VXI.cpp:4514:VXI::goto_element()|2IVRSRV####

@2014-02-21 17:24:15,672||FINER|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|VXI.cpp:1583:VXI::DoOuterJump(traceLastException?___DDSESSIONID=907D9390BBCC8484119F24EB131C76B8%3A%2FFlorenceNightingaleIVR)|2IVRSRV####

@2014-02-21 17:24:15,672||FINER|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|PropertyList.cpp:501:PropertyList::GetFetchobjCacheAttrs - (documentmaxage) of (3600s)|2IVRSRV####

@2014-02-21 17:24:15,673||FINER|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|PropertyList.cpp:559:PropertyList::GetFetchobjCacheAttrs - (documentmaxstale) of (0s)|2IVRSRV####

@2014-02-21 17:24:15,673||FINEST|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|VXI.cpp:1683:VXI::DoOuterJump - fetchaudiominimum: 0|2IVRSRV####

@2014-02-21 17:24:15,673||FINEST|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|VXI.cpp:1696:VXI::DoOuterJump - fetchaudiodelay: 0|2IVRSRV####

@2014-02-21 17:24:15,673||FINE|CommonVXI.Cache|14816|Session=2IVRSRV-2014052151916-5|SBinetChannel::GetAbsoluteNametraceLastException?___DDSESSIONID=907D9390BBCC8484119F24EB131C76B8%3A%2FFlorenceNightingaleIVR|2IVRSRV####

@2014-02-21 17:24:15,673||FINEST|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|VXI.cpp:1748:DoOuterJump(): Comparing m_preparedDocID to http://172.17.72.92:8080/FlorenceNightingaleIVR/traceLastException?___DDSESSIONID=907D9390BBCC8484119F24EB131C76B8%3A%2FFlorenceNightingaleIVR|2IVRSRV####

@2014-02-21 17:24:15,673||FINER|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|DocumentParser::FetchDocument(traceLastException?___DDSESSIONID=907D9390BBCC8484119F24EB131C76B8%3A%2FFlorenceNightingaleIVR)|2IVRSRV####

@2014-02-21 17:24:15,673||FINER|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|DocumentParser::FetchBuffer(traceLastException?___DDSESSIONID=907D9390BBCC8484119F24EB131C76B8%3A%2FFlorenceNightingaleIVR)|2IVRSRV####

@2014-02-21 17:24:15,673||FINEST|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|DocumentParser.cpp:370:Dumping INET Properties...|2IVRSRV####

@2014-02-21 17:24:15,673||FINEST|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|DocumentParser.cpp:371:Key = inet.authMode Level = 0 value is STRING: None|2IVRSRV####

@2014-02-21 17:24:15,673||FINEST|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|DocumentParser.cpp:371:Key = inet.basicAuthUserPwd Level = 0 value is STRING: :|2IVRSRV####

@2014-02-21 17:24:15,673||FINEST|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|DocumentParser.cpp:371:Key = inet.maxage Level = 0 value is INTEGER: 3600|2IVRSRV####

@2014-02-21 17:24:15,673||FINEST|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|DocumentParser.cpp:371:Key = inet.maxstale Level = 0 value is INTEGER: 0|2IVRSRV####

@2014-02-21 17:24:15,673||FINEST|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|DocumentParser.cpp:371:Key = inet.prefetchHint Level = 0 value is STRING: safe|2IVRSRV####

@2014-02-21 17:24:15,673||FINEST|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|DocumentParser.cpp:371:Key = inet.sslMutualAuth Level = 0 value is STRING: no|2IVRSRV####

@2014-02-21 17:24:15,673||FINEST|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|DocumentParser.cpp:371:Key = inet.timeoutDownload Level = 0 value is INTEGER: 60000000|2IVRSRV####

@2014-02-21 17:24:15,673||FINEST|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|DocumentParser.cpp:371:Key = inet.timeoutIO Level = 0 value is INTEGER: 60000000|2IVRSRV####

@2014-02-21 17:24:15,673||FINEST|CommonVXI.Interpreter|14816|Session=2IVRSRV-2014052151916-5|DocumentParser.cpp:371:Key = inet.urlBase Level = 0 value is STRING: http://172.17.72.92:8080/FlorenceNightingaleIVR/recording-createRecord?___DDSESSIONID=907D9390BBCC8484119F24EB131C76B8%3A%2FFlorenceNightingaleIVR|2IVRSRV####

@2014-02-21 17:24:15,673||FINEST|CommonVXI.Cache|14816|Session=2IVRSRV-2014052151916-5|SBinetChannel.cpp:312:SBinetChannel::Open: about to open stream|2IVRSRV####

@2014-02-21 17:24:15,674||FINER|CommonVXI.Cache|14816|Session=2IVRSRV-2014052151916-5|SBinetStream::getCacheInfo: maxAge=3600maxStale=0|2IVRSRV####

@2014-02-21 17:24:15,674||FINEST|CommonVXI.Cache|14816|Session=2IVRSRV-2014052151916-5|SBinetHttpStream::initSocket enter|2IVRSRV####

@2014-02-21 17:24:15,674||FINEST|CommonVXI.Cache|14816|Session=2IVRSRV-2014052151916-5|SBinetChannel::getHttpConnection Called url=http://172.17.72.92:8080/FlorenceNightingaleIVR/traceLastException?___DDSESSIONID=907D9390BBCC8484119F24EB131C76B8%3A%2FFlorenceNightingaleIVR|2IVRSRV####

@2014-02-21 17:24:15,674||FINEST|CommonVXI.Cache|14816|Session=2IVRSRV-2014052151916-5|SBinetChannel::getHttpConnection getNPath=/FlorenceNightingaleIVR/traceLastException?___DDSESSIONID=907D9390BBCC8484119F24EB131C76B8%3A%2FFlorenceNightingaleIVR|2IVRSRV####

@2014-02-21 17:24:15,674||FINER|CommonVXI.Cache|14816|Session=2IVRSRV-2014052151916-5|SBinetHttpConnection.cpp:63:SBinetHttpConnection::connect timeout=60000000|2IVRSRV####

@2014-02-21 17:24:15,674||FINER|CommonVXI.Cache|14816|Session=2IVRSRV-2014052151916-5|SBinetHttpConnection.cpp:78:SBinetHttpConnection::Before connect timeout=60000000|2IVRSRV####

@2014-02-21 17:24:15,674||FINEST|CommonVXI.Cache|14816|Session=2IVRSRV-2014052151916-5|SWIsocket.cpp:172:SWIsocket::connect enter.|2IVRSRV####

@2014-02-21 17:24:15,674||FINEST|CommonVXI.Cache|14816|Session=2IVRSRV-2014052151916-5|SWIsocket.cpp:198:SWIsocket::connect ptv != NULL.|2IVRSRV####

@2014-02-21 17:24:15,674||FINE|CommonVXI.Cache|14816|Session=2IVRSRV-2014052151916-5|SWIsocket.cpp:222:connecting to 1548226988|2IVRSRV####

@2014-02-21 17:24:15,674||FINEST|CommonVXI.Cache|14816|Session=2IVRSRV-2014052151916-5|SWIsocket.cpp:261:SWIsocket::connect connected.|2IVRSRV####

@2014-02-21 17:24:15,674||FINE|CommonVXI.Cache|14816|Session=2IVRSRV-2014052151916-5|SWIsocket.cpp:328:Established Connection to 1548226988|2IVRSRV####

@2014-02-21 17:24:15,674||FINEST|CommonVXI.Cache|14816|Session=2IVRSRV-2014052151916-5|SWIsocket.cpp:427:SWIsocket::connect restore blocking mode.|2IVRSRV####

@2014-02-21 17:24:15,674||FINER|CommonVXI.Cache|14816|Session=2IVRSRV-2014052151916-5|SBinetHttpConnection.cpp:80:SBinetHttpConnection::After connect timeout=[/quote]
PM
17/03/2015 10:49:03
Subject: Re:AOD 6.0 Record Node - Record Issue
#6
WilsonYu
Joined: Nov 6, 2013
Messages: 3950
Offline
Do you have the OD app trace.log to go with this?
PM
Go to:   
Mobile view