10:04:21:470 DBUG PHONGLUE titan.la rcv mgr(::ASTMGR): "Event: PeerStatus\r\nPrivilege: system,all\r\nPeer: SIP/7035421885\r\nPeerStatus: Registered\r\n\r\n" 10:04:22:781 DBUG PHONGLUE titan.la rcv mgr(::ASTMGR): "Event: PeerStatus\r\nPrivilege: system,all\r\nPeer: SIP/17035421885\r\nPeerStatus: Registered\r\n\r\n" 10:04:23:564 DBUG PHONGLUE titan.la rcv mgr(::ASTMGR): "Event: PeerStatus\r\nPrivilege: system,all\r\nPeer: SIP/17035421885\r\nPeerStatus: Registered\r\n\r\n" 10:04:26:808 DBUG PHONGLUE titan.la rcv mgr(::ASTMGR): "Event: PeerStatus\r\nPrivilege: system,all\r\nPeer: SIP/sameer\r\nPeerStatus: Registered\r\n\r\n" 10:04:27:258 DBUG PHONGLUE titan.la rcv mgr(::ASTMGR): "Event: PeerStatus\r\nPrivilege: system,all\r\nPeer: SIP/sameer\r\nPeerStatus: Registered\r\n\r\n" 10:04:43:098 DBUG PHONGLUE titan.la rcv mgr(::ASTMGR): "Event: PeerStatus\r\nPrivilege: system,all\r\nPeer: SIP/17035421885\r\nPeerStatus: Registered\r\n\r\n" 10:04:43:980 DBUG PHONGLUE titan.la rcv mgr(::ASTMGR): "Event: PeerStatus\r\nPrivilege: system,all\r\nPeer: SIP/17035421885\r\nPeerStatus: Registered\r\n\r\n" 10:04:47:128 DBUG PHONGLUE titan.la rcv mgr(::ASTMGR): "Event: PeerStatus\r\nPrivilege: system,all\r\nPeer: SIP/sameer\r\nPeerStatus: Registered\r\n\r\n" 10:04:47:597 DBUG PHONGLUE titan.la rcv mgr(::ASTMGR): "Event: PeerStatus\r\nPrivilege: system,all\r\nPeer: SIP/sameer\r\nPeerStatus: Registered\r\n\r\n" 10:04:49:480 DBUG PHONGLUE titan.la rcv mgr(::ASTMGR): "Event: PeerStatus\r\nPrivilege: system,all\r\nPeer: SIP/7035421885\r\nPeerStatus: Registered\r\n\r\n" 10:04:54:843 DBUG PHONGLUE titan.la rcv mgr(::ASTMGR): "Event: Newchannel\r\nPrivilege: call,all\r\nChannel: SIP/sip_proxy-out-014735f0\r\nState: Down\r\nCallerIDNum: \r\nCallerIDName: \r\nUniqueid: asterisk-1245074694.10185\r\n\r\n" 10:04:54:843 DBUG PHONGLUE titan.la rcv mgr(::ASTMGR): "Event: Newcallerid\r\nPrivilege: call,all\r\nChannel: SIP/sip_proxy-out-014735f0\r\nCallerID: \r\nCallerIDName: \r\nUniqueid: asterisk-1245074694.10185\r\nCID-CallingPres: 0 (Presentation Allowed, Not Screened)\r\n\r\n" 10:04:59:054 DBUG PHONGLUE titan.la rcv mgr(::ASTMGR): "Event: PeerStatus\r\nPrivilege: system,all\r\nPeer: SIP/17035421886\r\nPeerStatus: Registered\r\n\r\n" 10:05:00:892 DBUG PHONGLUE titan.la rcv mgr(::ASTMGR): "Event: Newstate\r\nPrivilege: call,all\r\nChannel: SIP/sip_proxy-out-014735f0\r\nState: Up\r\nCallerID: \r\nCallerIDName: \r\nUniqueid: asterisk-1245074694.10185\r\n\r\n" 10:05:00:893 DBUG PHONGLUE titan.la rcv mgr(::ASTMGR): "Event: OriginateResponse\r\nPrivilege: call,all\r\nResponse: Success\r\nChannel: SIP/sip_proxy-out-014735f0\r\nContext: lastpass\r\nExten: 9501\r\nReason: 4\r\nUniqueid: asterisk-1245074694.10185\r\nCallerID: \r\nCallerIDNum: \r\nCallerIDName: \r\n\r\n" 10:05:00:893 DBUG PHONGLUE titan.la rcv mgr(::ASTMGR): "Event: Newexten\r\nPrivilege: call,all\r\nChannel: SIP/sip_proxy-out-014735f0\r\nContext: lastpass\r\nExtension: 9501\r\nPriority: 1\r\nApplication: AGI\r\nAppData: agi://localhost/url=http%3A%2F%2Flocalhost%2F%7Edrew%2Fcall%2Fvxml%2Fapi%2Fcall.php&callid=7f4160d2f2921b66c81dedb9fe3771c6\r\nUniqueid: asterisk-1245074694.10185\r\n\r\n" 10:05:00:893 INFO PHONGLUE titan.la New AGI connection on fh = ::FASTAGI6033 10:05:00:893 DBUG PHONGLUE titan.la rcv agi(::FASTAGI6033): "agi_network: yes\n" 10:05:00:893 DBUG PHONGLUE titan.la ::FASTAGI6033 initial data "agi_network" = "yes" 10:05:00:893 DBUG PHONGLUE titan.la rcv agi(::FASTAGI6033): "agi_network_script: url=http%3A%2F%2Flocalhost%2F%7Edrew%2Fcall%2Fvxml%2Fapi%2Fcall.php&callid=7f4160d2f2921b66c81dedb9fe3771c6\n" 10:05:00:893 DBUG PHONGLUE titan.la ::FASTAGI6033 initial data "agi_network_script" = "url=http%3A%2F%2Flocalhost%2F%7Edrew%2Fcall%2Fvxml%2Fapi%2Fcall.php&callid=7f4160d2f2921b66c81dedb9fe3771c6" 10:05:00:893 DBUG PHONGLUE titan.la rcv agi(::FASTAGI6033): "agi_request: agi://localhost/url=http%3A%2F%2Flocalhost%2F%7Edrew%2Fcall%2Fvxml%2Fapi%2Fcall.php&callid=7f4160d2f2921b66c81dedb9fe3771c6\n" 10:05:00:893 DBUG PHONGLUE titan.la ::FASTAGI6033 initial data "agi_request" = "agi://localhost/url=http%3A%2F%2Flocalhost%2F%7Edrew%2Fcall%2Fvxml%2Fapi%2Fcall.php&callid=7f4160d2f2921b66c81dedb9fe3771c6" 10:05:00:893 DBUG PHONGLUE titan.la rcv agi(::FASTAGI6033): "agi_channel: SIP/sip_proxy-out-014735f0\n" 10:05:00:893 DBUG PHONGLUE titan.la ::FASTAGI6033 initial data "agi_channel" = "SIP/sip_proxy-out-014735f0" 10:05:00:894 DBUG PHONGLUE titan.la rcv agi(::FASTAGI6033): "agi_language: en\n" 10:05:00:894 DBUG PHONGLUE titan.la ::FASTAGI6033 initial data "agi_language" = "en" 10:05:00:894 DBUG PHONGLUE titan.la rcv agi(::FASTAGI6033): "agi_type: SIP\n" 10:05:00:894 DBUG PHONGLUE titan.la ::FASTAGI6033 initial data "agi_type" = "SIP" 10:05:00:894 DBUG PHONGLUE titan.la rcv agi(::FASTAGI6033): "agi_uniqueid: asterisk-1245074694.10185\n" 10:05:00:894 DBUG PHONGLUE titan.la ::FASTAGI6033 initial data "agi_uniqueid" = "asterisk-1245074694.10185" 10:05:00:894 DBUG PHONGLUE titan.la rcv agi(::FASTAGI6033): "agi_callerid: unknown\n" 10:05:00:894 DBUG PHONGLUE titan.la ::FASTAGI6033 initial data "agi_callerid" = "unknown" 10:05:00:894 DBUG PHONGLUE titan.la rcv agi(::FASTAGI6033): "agi_calleridname: unknown\n" 10:05:00:894 DBUG PHONGLUE titan.la ::FASTAGI6033 initial data "agi_calleridname" = "unknown" 10:05:00:894 DBUG PHONGLUE titan.la rcv agi(::FASTAGI6033): "agi_callingpres: 0\n" 10:05:00:894 DBUG PHONGLUE titan.la ::FASTAGI6033 initial data "agi_callingpres" = "0" 10:05:00:895 DBUG PHONGLUE titan.la rcv agi(::FASTAGI6033): "agi_callingani2: 0\n" 10:05:00:895 DBUG PHONGLUE titan.la ::FASTAGI6033 initial data "agi_callingani2" = "0" 10:05:00:895 DBUG PHONGLUE titan.la rcv agi(::FASTAGI6033): "agi_callington: 0\n" 10:05:00:895 DBUG PHONGLUE titan.la ::FASTAGI6033 initial data "agi_callington" = "0" 10:05:00:895 DBUG PHONGLUE titan.la rcv agi(::FASTAGI6033): "agi_callingtns: 0\n" 10:05:00:895 DBUG PHONGLUE titan.la ::FASTAGI6033 initial data "agi_callingtns" = "0" 10:05:00:895 DBUG PHONGLUE titan.la rcv agi(::FASTAGI6033): "agi_dnid: unknown\n" 10:05:00:895 DBUG PHONGLUE titan.la ::FASTAGI6033 initial data "agi_dnid" = "unknown" 10:05:00:895 DBUG PHONGLUE titan.la rcv agi(::FASTAGI6033): "agi_rdnis: unknown\n" 10:05:00:895 DBUG PHONGLUE titan.la ::FASTAGI6033 initial data "agi_rdnis" = "unknown" 10:05:00:895 DBUG PHONGLUE titan.la rcv agi(::FASTAGI6033): "agi_context: lastpass\n" 10:05:00:895 DBUG PHONGLUE titan.la ::FASTAGI6033 initial data "agi_context" = "lastpass" 10:05:00:896 DBUG PHONGLUE titan.la rcv agi(::FASTAGI6033): "agi_extension: 9501\n" 10:05:00:896 DBUG PHONGLUE titan.la ::FASTAGI6033 initial data "agi_extension" = "9501" 10:05:00:896 DBUG PHONGLUE titan.la rcv agi(::FASTAGI6033): "agi_priority: 1\n" 10:05:00:896 DBUG PHONGLUE titan.la ::FASTAGI6033 initial data "agi_priority" = "1" 10:05:00:896 DBUG PHONGLUE titan.la rcv agi(::FASTAGI6033): "agi_enhanced: 0.0\n" 10:05:00:896 DBUG PHONGLUE titan.la ::FASTAGI6033 initial data "agi_enhanced" = "0.0" 10:05:00:896 DBUG PHONGLUE titan.la rcv agi(::FASTAGI6033): "agi_accountcode: 7f4160d2f2921b66c81\n" 10:05:00:896 DBUG PHONGLUE titan.la ::FASTAGI6033 initial data "agi_accountcode" = "7f4160d2f2921b66c81" 10:05:00:896 DBUG PHONGLUE titan.la rcv agi(::FASTAGI6033): "\n" 10:05:00:897 INFO PHONGLUE titan.la callid=[6033] new incoming assigned to index 0 of 1: CT client on fh="::CTCLIENT2" at host=127.0.0.1 proto=SATC 10:05:00:897 DBUG PHONGLUE titan.la callid=[6033] snd incoming callid=6033 ani="unknown" dnis="9501" arg="url=http%3A%2F%2Flocalhost%2F%7Edrew%2Fcall%2Fvxml%2Fapi%2Fcall.php&callid=7f4160d2f2921b66c81dedb9fe3771c6" to CT client on fh="::CTCLIENT2" at host=127.0.0.1 proto=SATC 10:05:00:897 DBUG PHONGLUE titan.la snd "incoming 6033 unknown 9501 \"url=http%3A%2F%2Flocalhost%2F%7Edrew%2Fcall%2Fvxml%2Fapi%2Fcall.php&callid=7f4160d2f2921b66c81dedb9fe3771c6\"\n" to ::CTCLIENT2 10:05:00:897 DBUG VOICEGLU titan.la rcv ctsrv: "incoming 6033 unknown 9501 \"url=http%3A%2F%2Flocalhost%2F%7Edrew%2Fcall%2Fvxml%2Fapi%2Fcall.php&callid=7f4160d2f2921b66c81dedb9fe3771c6\"\n" 10:05:00:897 DBUG VOICEGLU titan.la callid=[6033] parsed incoming callid=6033 ani="unknown" dnis="9501" arg="url=http%3A%2F%2Flocalhost%2F%7Edrew%2Fcall%2Fvxml%2Fapi%2Fcall.php&callid=7f4160d2f2921b66c81dedb9fe3771c6" 10:05:00:897 NOTI VOICEGLU titan.la callid=[6033] starting VXML interp on url="http://localhost/~drew/call/vxml/api/call.php?callid=7f4160d2f2921b66c81dedb9fe3771c6" for DNIS="9501" perlfd=10 vxmlfd=11 10:05:00:924 NOTI OPEN_VXI titan.la callid=[6033] Channel 6033: Waiting for Call 1 10:05:00:925 DBUG OPEN_VXI titan.la callid=[6033] |140347469982032|6033|60001|testClient::ChannelThread|About to call VXIplatformWaitForCall 10:05:00:925 DBUG OPEN_VXI titan.la callid=[6033] |140347469982032|6033|60001|testClient::ChannelThread|In a Call 10:05:00:926 NOTI OPEN_VXI titan.la callid=[6033] Channel 6033: In a Call 10:05:00:926 DBUG OPEN_VXI titan.la callid=[6033] JsiContext::Create() started 10:05:00:927 DBUG OPEN_VXI titan.la callid=[6033] JsiContext::Create(): JS_THREADSAFE set 10:05:00:927 DBUG OPEN_VXI titan.la callid=[6033] JSDOMDocument::JSInit() started 10:05:00:928 DBUG OPEN_VXI titan.la callid=[6033] JS_InitClass(JSDOMDocument) OK, do: InitNodeObject() 10:05:00:929 DBUG OPEN_VXI titan.la callid=[6033] JsiContext::Create() finished 10:05:00:953 DBUG VOICEGLU titan.la callid=[6033] rcv ovxi: "Queue \"\" \"\"\n" 10:05:00:955 DBUG VOICEGLU titan.la callid=[6033] made sound cache request screq=[6820] for play_queued index 0 as: 6820 t=7s - url http://www.zitnay.com/stuff/test2.wav 10:05:00:956 DBUG VOICEGLU titan.la snd "6820 t=7s - url http://www.zitnay.com/stuff/test2.wav\n" to ::MAIN_TO_SC 10:05:00:958 DBUG SNDCACHE titan.la rcv main: "6820 t=7s - url http://www.zitnay.com/stuff/test2.wav\n" 10:05:00:958 DBUG SNDCACHE titan.la screq=[6820] processing url request soundreq=http://www.zitnay.com/stuff/test2.wav path=url/www.zitnay.com/stuff/test2.wav hashname=test2_wav_stuff_www_zitnay_com__http_ host= maxage=300 cookies=HASH(0x29444e0) timeout=7 for http://www.zitnay.com/stuff/test2.wav 10:05:00:958 DBUG SNDCACHE titan.la screq=[6820] using cached value voiceglue/link/test2_wav_stuff_www_zitnay_com__http_ -> url/www.zitnay.com/stuff/test2.wav 10:05:00:958 DBUG SNDCACHE titan.la snd "6820 1 voiceglue/link/test2_wav_stuff_www_zitnay_com__http_\n" to ::SC_TO_MAIN 10:05:00:959 DBUG VOICEGLU titan.la callid=[6033] rcv ovxi: "Play\n" 10:05:00:960 DBUG VOICEGLU titan.la callid=[6033] snd Played to VXML interpreter on fh="::PERL_VXML_6033" at host=localhost callid=[6033] 10:05:00:961 DBUG VOICEGLU titan.la snd "Played\n" to ::PERL_VXML_6033 10:05:00:963 DBUG OPEN_VXI titan.la callid=[6033] VXIpromptQueue called with spec=, properties={" absoluteURI"="http://localhost/~drew/call/vxml/api/welcome.php" " base"="http://localhost/~drew/call/vxml/api/welcome.php" " encoding"="UTF-8" "audiofetchhint"="prefetch" "bargein"="false" "bargeintype"="speech" "confidencelevel"="0.5" "datafetchhint"="prefetch" "documentfetchhint"="safe" "fetchaudiodelay"="2s" "fetchaudiominimum"="5s" "fetchtimeout"="7s" "grammarfetchhint"="prefetch" "inet.urlBase"="http://localhost/~drew/call/vxml/api/welcome.php" "inputmodes"="dtmf voice" "maxnbest"="1" "objectfetchhint"="prefetch" "scriptfetchhint"="prefetch" "sensitivity"="0.5" "speedvsaccuracy"="0.5" "swirec_extra_nbest_keys"="" "termchar"="#" "termtimeout"="0s" "universals"="none"} 10:05:00:971 DBUG PHONGLUE titan.la rcv ct(::CTCLIENT2): "play 6033 \"voiceglue/link/test2_wav_stuff_www_zitnay_com__http_\" \"\"\n" 10:05:00:971 DBUG PHONGLUE titan.la callid=[6033] parsed play callid=6033 files=("voiceglue/link/test2_wav_stuff_www_zitnay_com__http_") stopkeys="" 10:05:00:971 DBUG PHONGLUE titan.la callid=[6033] snd PLAYFILE file="voiceglue/link/test2_wav_stuff_www_zitnay_com__http_" stopkeys="" to AGI client on fh="::FASTAGI6033" at host=127.0.0.1 callid=[6033] 10:05:00:971 DBUG PHONGLUE titan.la snd "STREAM FILE voiceglue/link/test2_wav_stuff_www_zitnay_com__http_ \"\"\n" to ::FASTAGI6033 10:05:01:003 DBUG OPEN_VXI titan.la callid=[6033] VXIpromptQueue (, ) 10:05:01:003 DBUG OPEN_VXI titan.la callid=[6033] snd vg: Queue "" "" 10:05:01:003 DBUG OPEN_VXI titan.la callid=[6033] VXIpromptPlay() 10:05:01:003 DBUG OPEN_VXI titan.la callid=[6033] snd vg: Play 10:05:01:003 DBUG VOICEGLU titan.la callid=[6033] rcv ovxi: "Play\n" 10:05:01:003 DBUG VOICEGLU titan.la callid=[6033] snd Played to VXML interpreter on fh="::PERL_VXML_6033" at host=localhost callid=[6033] 10:05:01:003 DBUG VOICEGLU titan.la snd "Played\n" to ::PERL_VXML_6033 10:05:01:004 DBUG OPEN_VXI titan.la callid=[6033] rcv vg: Played 10:05:01:004 DBUG OPEN_VXI titan.la callid=[6033] VXIpromptPlay() 10:05:01:004 DBUG OPEN_VXI titan.la callid=[6033] snd vg: Play 10:05:01:004 DBUG VOICEGLU titan.la rcv sc: "6820 1 voiceglue/link/test2_wav_stuff_www_zitnay_com__http_" 10:05:01:004 DBUG VOICEGLU titan.la callid=[6033] dpal(): do_prompt_and_listen() called 10:05:01:004 DBUG VOICEGLU titan.la callid=[6033] dpal(): Checking for prompts 10:05:01:004 DBUG VOICEGLU titan.la callid=[6033] dpal(): Found prompts to play, playing 10:05:01:004 DBUG VOICEGLU titan.la callid=[6033] snd play callid=6033 files=("voiceglue/link/test2_wav_stuff_www_zitnay_com__http_") stopkeys="" to CT server on fh="::CTSRV" at host=localhost 10:05:01:004 DBUG VOICEGLU titan.la snd "play 6033 \"voiceglue/link/test2_wav_stuff_www_zitnay_com__http_\" \"\"\n" to ::CTSRV 10:05:01:004 DBUG VOICEGLU titan.la callid=[6033] rcv ovxi: "Wait\n" 10:05:01:004 DBUG VOICEGLU titan.la callid=[6033] snd Waited to VXML interpreter on fh="::PERL_VXML_6033" at host=localhost callid=[6033] 10:05:01:004 DBUG VOICEGLU titan.la snd "Waited\n" to ::PERL_VXML_6033 10:05:01:005 DBUG OPEN_VXI titan.la callid=[6033] rcv vg: Played 10:05:01:005 DBUG OPEN_VXI titan.la callid=[6033] VXIpromptWait() 10:05:01:005 DBUG OPEN_VXI titan.la callid=[6033] snd vg: Wait 10:05:01:005 INFO VOICEGLU titan.la VXML interpreter filehandle "::PERL_VXML_6033" stopped: code = 0 10:05:01:005 INFO VOICEGLU titan.la callid=[6033] lost its VXML interpreter 10:05:01:005 DBUG VOICEGLU titan.la callid=[6033] deallocating VXML thread 10:05:01:005 NOTI VOICEGLU titan.la callid=[6033] hanging up because VXML interpreter exited 10:05:01:005 DBUG VOICEGLU titan.la callid=[6033] snd hangup to CT server on fh="::CTSRV" at host=localhost 10:05:01:005 DBUG VOICEGLU titan.la snd "hangup 6033\n" to ::CTSRV 10:05:01:005 DBUG OPEN_VXI titan.la callid=[6033] rcv vg: Waited 10:05:01:005 DBUG OPEN_VXI titan.la callid=[6033] |140347469982032|6033|60001|testClient::ChannelThread|NULL result 10:05:01:005 DBUG OPEN_VXI titan.la callid=[6033] |140347469982032|6033|60001|testClient::ChannelThread|Call Terminated 10:05:01:006 NOTI OPEN_VXI titan.la callid=[6033] Channel 6033: Call Terminated 10:05:01:006 DBUG OPEN_VXI titan.la callid=[6033] |140347469982032|-1|3000|SBinetDestroyResource|entering: 0x0x2ca2ec0 (0x0x33ef510) 10:05:01:006 DBUG OPEN_VXI titan.la callid=[6033] |140347469982032|-1|3000|SBinetDestroyResource|exiting, returned 0 10:05:01:015 DBUG PHONGLUE titan.la rcv ct(::CTCLIENT2): "hangup 6033\n" 10:05:01:015 DBUG PHONGLUE titan.la callid=[6033] parsed hangup 10:05:01:015 INFO PHONGLUE titan.la AGI client filehandle "::FASTAGI6033" stopped: hangup requested 10:05:01:019 DBUG PHONGLUE titan.la callid=[6033] snd hungup callid=6033 to CT client on fh="::CTCLIENT2" at host=127.0.0.1 proto=SATC 10:05:01:019 DBUG PHONGLUE titan.la snd "hungup 6033\n" to ::CTCLIENT2 10:05:01:019 DBUG PHONGLUE titan.la rcv mgr(::ASTMGR): "Event: Newexten\r\nPrivilege: call,all\r\nChannel: SIP/sip_proxy-out-014735f0\r\nContext: lastpass\r\nExtension: 9501\r\nPriority: 2\r\nApplication: Hangup\r\nAppData: \r\nUniqueid: asterisk-1245074694.10185\r\n\r\n" 10:05:01:019 DBUG PHONGLUE titan.la rcv mgr(::ASTMGR): "Event: Hangup\r\nPrivilege: call,all\r\nChannel: SIP/sip_proxy-out-014735f0\r\nUniqueid: asterisk-1245074694.10185\r\nCause: 16\r\nCause-txt: Normal Clearing\r\n\r\n" 10:05:01:043 DBUG VOICEGLU titan.la rcv ctsrv: "hungup 6033\n" 10:05:01:043 DBUG VOICEGLU titan.la callid=[6033] parsed hungup callid=6033 10:05:03:576 DBUG PHONGLUE titan.la rcv mgr(::ASTMGR): "Event: PeerStatus\r\nPrivilege: system,all\r\nPeer: SIP/17035421885\r\nPeerStatus: Registered\r\n\r\n"