17:25:16.843|093c|.\3CXIvrService.cpp(859):IvrService, main(), Starting service, revision 3.1.2434.0
17:25:16.843|0934|.\3CXIvrService.cpp(572):IvrService, MainServiceProc::Run(), Loading configuration
17:25:16.859|0934|.\3CXIvrService.cpp(580):IvrService, MainServiceProc::Run(), Initializing VxmlCreator
17:25:16.859|0934|.\VoiceMenuCreator.cpp(616):VMCreator, VxmlCreatorSetLog(), External log has been set for VoiceMenuCreator dll
17:25:16.859|0934|.\3CXIvrService.cpp(583):IvrService, MainServiceProc::Run(), Setting VxmlCreator parameters
17:25:16.859|0934|.\VoiceMenuCreator.cpp(37):VMCreator, CVoiceMenuCreator::Init(), Initializing, revision 3.1.2434.0
17:25:16.906|0934|.\3CXIvrService.cpp(609):IvrService, MainServiceProc::Run(), Initializing php updater
17:25:16.906|0934|.\3CXIvrService.cpp(618):IvrService, MainServiceProc::Run(), Writing global parameters to php ...
17:25:16.906|0934|.\VoiceMenuCreator.cpp(235):VMCreator, CVoiceMenuCreator::DBConnect(), Connecting to database ...
17:25:16.906|0934|.\DBAccessor.cpp(552):VMCreator, CDBAccessor::Connect(), Database connect string [dbname=phonesystem port=5480 user=phonesystem password=EF11C93F-3E70-4D6E-A2C9-AC87944EC028]
17:25:17.093|0934|.\DBAccessor.cpp(591):VMCreator, CDBAccessor::Connect(), Database connection success
17:25:17.093|0934|.\VoiceMenuCreator.cpp(188):VMCreator, CVoiceMenuCreator::ProcessFiles(), Processing file C:\Program Files\3CX\PhoneSystem\Bin\..\Data\Ivr\Templates\Vxml\GlobSettings.php.php ...
17:25:17.109|0934|.\PhpWrapper.cpp(381):VMCreator, PHPWrapper::Execute(), Process 0x1EC (C:\Program Files\3CX\PhoneSystem\Bin\..\Bin\Php\php-win.exe) created
17:25:17.109|0934|.\PhpWrapper.cpp(382):VMCreator, PHPWrapper::Execute(), Writing 984 bytes of input data
17:25:17.109|0934|.\PhpWrapper.cpp(394):VMCreator, PHPWrapper::Execute(), Input data written for 0x1EC process
17:25:17.109|0934|.\PhpWrapper.cpp(406):VMCreator, PHPWrapper::Execute(), Waiting for finishing of 0x1EC process, timeout is 120000 ms
17:25:17.171|0934|.\PhpWrapper.cpp(412):VMCreator, PHPWrapper::Execute(), Process 0x1EC finished
17:25:17.171|0934|.\PhpWrapper.cpp(431):VMCreator, PHPWrapper::Execute(), Waiting for data from process
17:25:17.234|0934|.\PhpWrapper.cpp(433):VMCreator, PHPWrapper::Execute(), Data from process gotten
17:25:17.234|0934|.\VoiceMenuCreator.cpp(113):VMCreator, CVoiceMenuCreator::SaveOutput(), Saving file C:\Program Files\3CX\PhoneSystem\Bin\..\Data\Ivr\Vxml\GlobSettings.php ...
17:25:17.250|0934|.\VoiceMenuCreator.cpp(188):VMCreator, CVoiceMenuCreator::ProcessFiles(), Processing file C:\Program Files\3CX\PhoneSystem\Bin\..\Data\Ivr\Templates\Vxml\Prompts.php.php ...
17:25:17.265|0934|.\PhpWrapper.cpp(381):VMCreator, PHPWrapper::Execute(), Process 0x204 (C:\Program Files\3CX\PhoneSystem\Bin\..\Bin\Php\php-win.exe) created
17:25:17.265|0934|.\PhpWrapper.cpp(382):VMCreator, PHPWrapper::Execute(), Writing 30649 bytes of input data
17:25:17.312|0934|.\PhpWrapper.cpp(394):VMCreator, PHPWrapper::Execute(), Input data written for 0x204 process
17:25:17.312|0934|.\PhpWrapper.cpp(406):VMCreator, PHPWrapper::Execute(), Waiting for finishing of 0x204 process, timeout is 5000 ms
17:25:18.656|0934|.\PhpWrapper.cpp(412):VMCreator, PHPWrapper::Execute(), Process 0x204 finished
17:25:18.656|0934|.\PhpWrapper.cpp(431):VMCreator, PHPWrapper::Execute(), Waiting for data from process
17:25:18.843|0934|.\PhpWrapper.cpp(433):VMCreator, PHPWrapper::Execute(), Data from process gotten
17:25:18.843|0934|.\VoiceMenuCreator.cpp(113):VMCreator, CVoiceMenuCreator::SaveOutput(), Saving file C:\Program Files\3CX\PhoneSystem\Bin\..\Data\Ivr\Vxml\Prompts.php ...
17:25:18.843|0934|.\VoiceMenuCreator.cpp(188):VMCreator, CVoiceMenuCreator::ProcessFiles(), Processing file C:\Program Files\3CX\PhoneSystem\Bin\..\Data\Ivr\Templates\Vxml\language.php.php ...
17:25:18.859|0934|.\PhpWrapper.cpp(381):VMCreator, PHPWrapper::Execute(), Process 0x1F4 (C:\Program Files\3CX\PhoneSystem\Bin\..\Bin\Php\php-win.exe) created
17:25:18.859|0934|.\PhpWrapper.cpp(382):VMCreator, PHPWrapper::Execute(), Writing 30649 bytes of input data
17:25:19.109|0934|.\PhpWrapper.cpp(394):VMCreator, PHPWrapper::Execute(), Input data written for 0x1F4 process
17:25:19.109|0934|.\PhpWrapper.cpp(406):VMCreator, PHPWrapper::Execute(), Waiting for finishing of 0x1F4 process, timeout is 5000 ms
17:25:19.125|0934|.\PhpWrapper.cpp(412):VMCreator, PHPWrapper::Execute(), Process 0x1F4 finished
17:25:19.125|0934|.\PhpWrapper.cpp(431):VMCreator, PHPWrapper::Execute(), Waiting for data from process
17:25:19.234|0934|.\PhpWrapper.cpp(433):VMCreator, PHPWrapper::Execute(), Data from process gotten
17:25:19.234|0934|.\VoiceMenuCreator.cpp(113):VMCreator, CVoiceMenuCreator::SaveOutput(), Saving file C:\Program Files\3CX\PhoneSystem\Bin\..\Data\Ivr\Vxml\language.php ...
17:25:19.250|0934|.\IVR_Api.cpp(631):IvrDll, Ivr_SetLog(), External log has been set for IVR dll
17:25:19.250|0934|.\3CXIvrService.cpp(636):IvrService, MainServiceProc::Run(), Setting IVR parameters
17:25:19.250|0934|.\3CXIvrService.cpp(660):IvrService, MainServiceProc::Run(), Setting callback functions
17:25:19.250|0934|.\IVR_Api.cpp(311):IvrDll, IVRApi::Init(), Initializing, revision 3.1.2434.0
17:25:19.250|0934|.\IVR_Api.cpp(313):IvrDll, IVRApi::Init(), Initializing sockets
17:25:19.250|0934|.\IVR_Api.cpp(322):IvrDll, IVRApi::Init(), Initializing WWW library
17:25:19.265|0934|.\IVR_Api.cpp(361):IvrDll, IVRApi::Init(), Loading configuration
17:25:19.265|0934|.\IVR_Api.cpp(172):IvrDll, IVRApi::LoadConfiguration(), Using config file 'C:\Program Files\3CX\PhoneSystem\Bin\..\Data\Ivr\Cfg\3CXIvr.cfg'
17:25:19.265|0934|.\IVR_Api.cpp(206):IvrDll, IVRApi::LoadConfiguration(), Default vxml document: 'http://localhost:5481/Vxml/ivr_start.php'
17:25:19.265|0934|.\IVR_Api.cpp(232):IvrDll, IVRApi::LoadConfiguration(), Prompts URL: 'http://localhost:5481/Vxml/prompts'
17:25:19.265|0934|.\IVR_Api.cpp(258):IvrDll, IVRApi::LoadConfiguration(), Using beep URI 'file:///C%7c/Program%20Files/3CX/PhoneSystem/Bin/%2e%2e/Data/Ivr/Media/beep%2ewav'
17:25:19.265|0934|.\IVR_Api.cpp(280):IvrDll, IVRApi::LoadConfiguration(), Using error message URI 'file:///C%7c/Program%20Files/3CX/PhoneSystem/Bin/%2e%2e/Data/Ivr/Media/error%2emp3'
17:25:19.265|0934|.\IVR_Api.cpp(298):IvrDll, IVRApi::LoadConfiguration(), Using inet cache dir 'C:\Program Files\3CX\PhoneSystem\Bin\..\Data\Ivr\Cache'
17:25:19.265|0934|.\IVR_Api.cpp(367):IvrDll, IVRApi::Init(), Initializing VXI platform
17:25:19.265|0934|(0):Subtag VXIplatformInit. entering (log init complete): 0x00F23828
17:25:19.328|0934|(0):Subtag SBjsiInit. entering: 0x00F28FF8, 4000, 16384000, 131072, 100000
17:25:19.328|0934|(0):Subtag SBjsiInit. exiting: returned 0
17:25:19.328|0934|(0):Subtag VXIplatformInit. exiting: rc = 0
17:25:19.328|0934|.\IVR_Api.cpp(376):IvrDll, IVRApi::Init(), State machine initializing
17:25:19.328|0934|.\3CXIvrService.cpp(672):IvrService, MainServiceProc::Run(), Creating queues data manager
17:25:19.328|0934|.\3CXIvrService.cpp(676):IvrService, MainServiceProc::Run(), Creating SL Agent
17:25:19.328|0934|.\3CXIvrService.cpp(685):IvrService, MainServiceProc::Run(), Initializing PBX/Media communication part
17:25:19.343|0934|.\3CXIvrService.cpp(693):IvrService, MainServiceProc::Run(), Connecting to PBX (SL Agent)
17:25:19.343|0934|.\3CXIvrService.cpp(701):IvrService, MainServiceProc::Run(), Service started
17:25:19.375|1990|.\SLAgent.cpp(114):IvrService, SLAgent::Run(), Reconnected to 127.0.0.1:5482 SL server
17:25:19.484|1988|.\SLAgent.cpp(62):IvrService, SLAgent::OnGetQueuesData(), Sending queues information
17:25:19.859|198c|..\..\PbxServer\MSInterface.cpp(686):[CM112000] Media Server is connected
17:25:19.859|198c|..\..\PbxServer\MSInterface.cpp(689):New MS Factory created: 16017616
17:25:19.859|198c|..\..\PbxServer\MSInterface.cpp(695):Factory is added
17:25:22.812|1988|.\PbxMedia\IVRServer.cpp(66):IvrService, IVRCreateHandler(), Succees. IVRHandler:00000001 for EP:0000000B@
17:25:22.812|1988|.\PbxMedia\IPbxMediaImpl.cpp(45):IvrService, IPbxMediaImpl::startSession(), IPbxMediaImpl session started
17:25:22.812|1988|(0):Subtag VXIplatformCreateResources. entering: 0, 0x00F23828
17:25:22.812|1988|(0):Subtag SBjsiCreateResource. entering: 0x01AE02B0, 0x01AE024C
17:25:22.812|1988|(0):Subtag SBjsiCreateResource. exiting: returned 0
17:25:22.812|1988|(0):Subtag VXIplatformCreateResources. exiting: rc = 0, 0x01AE0230
17:25:22.812|1988|.\VXIMachine.cpp(495):IvrDll, CallSession::Start(), Using VXML document 'http://localhost:5481/Vxml/ivr_start.php?IVRHandlerFROM=100&IVRHandlerID=00000001&IVRHandlerMENU=999&IVRHandlerTO=999&ISID=From_100_To_999_Menu_999_%230%2F0'
17:25:22.812|1988|.\PbxMedia\IVRServer.cpp(106):IvrService, IVRStartSession(), Session started on IVRHandler:00000001
17:25:22.843|0f64|.\api\VXItelImpl.cpp(75):IvrDll, VXItelImpl::Impl::BeginSession(), BeginSession
17:25:22.843|0f64|(0):Event occurred (ID # 0). ANIIDNISVURLhttp://localhost:5481/Vxml/ivr_start.php?IVRHandlerFROM=100&IVRHandlerID=00000001&IVRHandlerMENU=999&IVRHandlerTO=999&ISID=From_100_To_999_Menu_999_%230%2F0
17:25:22.859|0f64|(0):Subtag SBjsiCreateContext. entering: 0x01AD3914
17:25:22.859|0f64|(0):Subtag SBjsiCreateContext. exiting: returned 0, 0x01AD0C60
17:25:22.859|0f64|(0):Subtag SBjsiPushScope. entering: 0x01AD0C60, 'session'
17:25:22.859|0f64|(0):Subtag SBjsiPushScope. exiting: returned 0
17:25:22.859|0f64|(0):Subtag SBjsiSetVarValue. entering: 0x01AD0C60, 'session.ani', 0x01AD0B20
17:25:22.859|0f64|(0):Subtag SBjsiSetVarValue. exiting: returned 0
17:25:22.859|0f64|(0):Subtag SBjsiSetVarValue. entering: 0x01AD0C60, 'session.callid', 0x01AE1D08
17:25:22.859|0f64|(0):Subtag SBjsiSetVarValue. exiting: returned 0
17:25:22.859|0f64|(0):Subtag SBjsiSetVarValue. entering: 0x01AD0C60, 'session.dnis', 0x01AE1D60
17:25:22.875|0f64|(0):Subtag SBjsiSetVarValue. exiting: returned 0
17:25:22.875|0f64|(0):Subtag SBjsiSetVarValue. entering: 0x01AD0C60, 'session.navigator', 0x00F28C40
17:25:22.875|0f64|(0):Subtag SBjsiSetVarValue. exiting: returned 0
17:25:22.875|0f64|(0):Subtag SBjsiPushScope. entering: 0x01AD0C60, 'application'
17:25:22.875|0f64|(0):Subtag SBjsiPushScope. exiting: returned 0
17:25:22.875|0f64|(0):Subtag SBjsiPushScope. entering: 0x01AD0C60, '$_platDefaults_'
17:25:22.875|0f64|(0):Subtag SBjsiPushScope. exiting: returned 0
17:25:22.937|0f64|(0):Error! Module 'pingtel.com.OSBinet'. Error ID 302. URLhttp://localhost:5481/Vxml/ivr_start.php?IVRHandlerFROM=100&IVRHandlerID=00000001&IVRHandlerMENU=999&IVRHandlerTO=999&ISID=From_100_To_999_Menu_999_%230%2F0
17:25:22.937|0f64|(0):DocumentParser::FetchDocument - Parse error in file "http://localhost:5481/Vxml/ivr_start.php?IVRHandlerFROM=100&IVRHandlerID=00000001&IVRHandlerMENU=999&IVRHandlerTO=999&ISID=From_100_To_999_Menu_999_%2F0", line 1, column 7 - unrecognized element - br
17:25:22.937|0f64|(0):Error! Module 'pingtel.com.vxi'. Error ID 999. messageXML parsing error in file http://localhost:5481/Vxml/ivr_start.php?IVRHandlerFROM=100&IVRHandlerID=00000001&IVRHandlerMENU=999&IVRHandlerTO=999&ISID=From_100_To_999_Menu_999_%230%2F0, line 1: unrecognized element - br
17:25:22.937|0f64|(0):Error! Module 'pingtel.com.vxi'. Error ID 205. urihttp://localhost:5481/Vxml/ivr_start.php?IVRHandlerFROM=100&IVRHandlerID=00000001&IVRHandlerMENU=999&IVRHandlerTO=999&ISID=From_100_To_999_Menu_999_%230%2F0
17:25:22.937|0f64|(0):Subtag SBjsiPushScope. entering: 0x01AD0C60, '$_execAnonymous_'
17:25:22.937|0f64|(0):Subtag SBjsiPushScope. exiting: returned 0
17:25:22.937|0f64|(0):Subtag SBjsiSetVarValue. entering: 0x01AD0C60, '_event', 0x01AD3C50
17:25:22.937|0f64|(0):Subtag SBjsiSetVarValue. exiting: returned 0
17:25:22.937|0f64|(0):Subtag SBjsiSetVarValue. entering: 0x01AD0C60, '_message', 0x01AE7728
17:25:22.937|0f64|(0):Subtag SBjsiSetVarValue. exiting: returned 0
17:25:22.937|0f64|(0):Subtag SBjsiEval. entering: 0x01AD0C60, '_event == _message', 0x022CEC6C
17:25:22.937|0f64|(0):Subtag SBjsiEval. exiting: returned 0, 0x022CEC6C (0x01AE3078)
17:25:22.937|0f64|(0):Subtag SBjsiEval. entering: 0x01AD0C60, '_event', 0x022CEB24
17:25:22.937|0f64|(0):Subtag SBjsiEval. exiting: returned 0, 0x022CEB24 (0x01AE3078)
17:25:22.937|0f64|(0):Event occurred (ID # 2). content <log> Error: error.badfetch
17:25:22.937|0f64|.\api\VXIpromptImpl.cpp(132):IvrDll, VXIpromptImpl::Impl::Queue(), baseUrl = '(null)'
17:25:22.953|0f64|.\api\VXIpromptImpl.cpp(212):IvrDll, VXIpromptImpl::Impl::Queue(), Error! A serious error within IVR session
17:25:22.953|0f64|.\PbxMedia\IPbxMediaImpl.cpp(89):IvrService, IPbxMediaImpl::PromptAddFile(), C:\Program Files\3CX\PhoneSystem\Bin\..\Data\Ivr\Media\error.mp3(1)
17:25:24.328|0f64|.\PbxMedia\IPbxMediaImpl.cpp(108):IvrService, IPbxMediaImpl::PromptPlayAll(), PlayAll
17:25:24.343|0f64|.\VXIMachine.cpp(385):IvrDll, CallSession::PromptsMap::WaitPlayed(), Waiting for prompts (start)
17:25:27.546|1988|..\..\PbxServer\MSInterface.cpp(621):FilePlayEndEventHandler
17:25:27.546|1988|c:\svn\src\ivr\vxmlivrservice\pbxmedia\IPbxMediaImpl.h(182):IvrService, IMSEndPointListenerIVR::endOfPlayedFile(), Fire '1' played
17:25:27.546|1988|.\VXIMachine.cpp(1178):IvrDll, CallSession::PromptPlayed(), Prompt 1 played
17:25:27.546|1988|.\VXIMachine.cpp(338):IvrDll, CallSession::PromptsMap::PromptPlayed(), All prompts played
17:25:27.546|0f64|.\VXIMachine.cpp(398):IvrDll, CallSession::PromptsMap::WaitPlayed(), Waiting for prompts (stop)
17:25:27.546|0f64|(0):Subtag SBjsiDestroyContext. entering: 0x01AD3914 (0x01AD0C60)
17:25:27.546|0f64|(0):Subtag SBjsiDestroyContext. exiting: returned 0
17:25:27.546|0f64|(0):Event occurred (ID # 1).
17:25:27.546|0f64|.\api\VXItelImpl.cpp(82):IvrDll, VXItelImpl::Impl::EndSession(), EndSession
17:25:27.546|0f64|(0):Subtag VXIplatformProcessDocument. exiting: rc = 0, 0x00000000
17:25:27.546|0f64|(0):Subtag ChannelThread. NULL result
17:25:28.546|0f64|.\PbxMedia\IPbxMediaImpl.cpp(69):IvrService, IPbxMediaImpl::SessionFinished(), Reason (0)
17:25:28.546|0f64|.\VXIMachine.cpp(1526):IvrDll, VXIMachine::EndSession(), Removing From_100_To_999_Menu_999_#0/0 call
17:25:28.546|0f64|.\QueueDataMgr.cpp(92):IvrService, QueueDataMgr::UnregisterCall(), Unregistering 'From_100_To_999_Menu_999_#0/0' call
17:25:28.546|0f64|(0):Subtag SBjsiDestroyResource. entering: 0x01AE024C (0x00F2A568)
17:25:28.546|0f64|(0):Subtag SBjsiDestroyResource. exiting: returned 0
17:25:28.562|092c|.\3CXIvrService.cpp(99):IvrService, PhpUpdater::TaskCheckMenus::Execute(), Checking IVR menus
17:25:28.593|1988|.\PbxMedia\IPbxMediaImpl.cpp(59):IvrService, IPbxMediaImpl::stopSession(), No Ivr session attached
17:25:28.593|1988|.\PbxMedia\IPbxMediaImpl.cpp(35):IvrService, IPbxMediaImpl::~IPbxMediaImpl(), IPbxMediaImpl destroyed
17:25:28.593|1988|.\PbxMedia\IVRServer.cpp(83):IvrService, IVRDeleteHandler(), IVRHandler:00000001 removed