[2011-02-04 14:10:59] VERBOSE[17753] chan_sip.c: <--- SIP read from UDP:208.94.157.10:5060 ---> INVITE sip:19164444444@XXX.XXX.XXX11:5060;transport=udp SIP/2.0 From: ;tag=a9d5ed0-13c4-4d4c4fb9-afe2be8-cb43ee3 To: Call-ID: CXC-224-75aa1390-a9d5ed0-13c4-4d4c4fb9-afe2be8-5559069d@208.94.157.10 CSeq: 1 INVITE Via: SIP/2.0/UDP 208.94.157.10:5060;branch=z9hG4bK-88b22-4d4c4fb9-afe2be8-1a7a20e0 Max-Forwards: 68 P-Asserted-Identity: Supported: 100rel Content-Disposition: session;handling=required Contact: Min-SE: 900 Session-Expires: 1800 Content-Type: application/sdp Content-Length: 238 v=0 o=Acme_UAS 0 1 IN IP4 208.94.157.10 s=SIP Media Capabilities c=IN IP4 208.94.157.10 t=0 0 m=audio 24572 RTP/AVP 0 18 101 a=rtpmap:0 PCMU/8000 a=rtpmap:18 G729/8000 a=rtpmap:101 telephone-event/8000 a=maxptime:20 a=sendrecv <-------------> [2011-02-04 14:10:59] VERBOSE[17753] chan_sip.c: --- (15 headers 11 lines) --- [2011-02-04 14:10:59] VERBOSE[17753] chan_sip.c: Sending to 208.94.157.10 : 5060 (NAT) [2011-02-04 14:10:59] VERBOSE[17753] chan_sip.c: Using INVITE request as basis request - CXC-224-75aa1390-a9d5ed0-13c4-4d4c4fb9-afe2be8-5559069d@208.94.157.10 [2011-02-04 14:10:59] VERBOSE[17753] chan_sip.c: Found peer '8-208.94.157.10' for '8165350000' from 208.94.157.10:5060 [2011-02-04 14:10:59] VERBOSE[17753] chan_sip.c: Found RTP audio format 0 [2011-02-04 14:10:59] VERBOSE[17753] chan_sip.c: Found RTP audio format 18 [2011-02-04 14:10:59] VERBOSE[17753] chan_sip.c: Found RTP audio format 101 [2011-02-04 14:10:59] VERBOSE[17753] chan_sip.c: Found audio description format PCMU for ID 0 [2011-02-04 14:10:59] VERBOSE[17753] chan_sip.c: Found audio description format G729 for ID 18 [2011-02-04 14:10:59] VERBOSE[17753] chan_sip.c: Found audio description format telephone-event for ID 101 [2011-02-04 14:10:59] VERBOSE[17753] chan_sip.c: Capabilities: us - 0x1004 (ulaw|g722), peer - audio=0x104 (ulaw|g729)/video=0x0 (nothing)/text=0x0 (nothing), combined - 0x4 (ulaw) [2011-02-04 14:10:59] VERBOSE[17753] chan_sip.c: Non-codec capabilities (dtmf): us - 0x1 (telephone-event), peer - 0x1 (telephone-event), combined - 0x1 (telephone-event) [2011-02-04 14:10:59] VERBOSE[17753] chan_sip.c: Peer audio RTP is at port 208.94.157.10:24572 [2011-02-04 14:10:59] VERBOSE[17753] chan_sip.c: Looking for 19164444444 in from-external (domain XXX.XXX.XXX11) [2011-02-04 14:10:59] VERBOSE[17753] chan_sip.c: list_route: hop: [2011-02-04 14:10:59] VERBOSE[17753] chan_sip.c: <--- Transmitting (no NAT) to 208.94.157.10:5060 ---> SIP/2.0 100 Trying Via: SIP/2.0/UDP 208.94.157.10:5060;branch=z9hG4bK-88b22-4d4c4fb9-afe2be8-1a7a20e0;received=208.94.157.10 From: ;tag=a9d5ed0-13c4-4d4c4fb9-afe2be8-cb43ee3 To: Call-ID: CXC-224-75aa1390-a9d5ed0-13c4-4d4c4fb9-afe2be8-5559069d@208.94.157.10 CSeq: 1 INVITE Server: i-Communicate Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO Supported: replaces, timer Contact: Content-Length: 0 <------------> [2011-02-04 14:10:59] VERBOSE[20457] res_agi.c: AGI Tx >> agi_network: yes [2011-02-04 14:10:59] VERBOSE[20457] res_agi.c: AGI Tx >> agi_network_script: enswitch?stype=external&cnumber=9164444444 [2011-02-04 14:10:59] VERBOSE[20457] res_agi.c: AGI Tx >> agi_request: agi://127.0.0.1/enswitch?stype=external&cnumber=9164444444 [2011-02-04 14:10:59] VERBOSE[20457] res_agi.c: AGI Tx >> agi_channel: SIP/8-208.94.157.10-0000387b [2011-02-04 14:10:59] VERBOSE[20457] res_agi.c: AGI Tx >> agi_language: en [2011-02-04 14:10:59] VERBOSE[20457] res_agi.c: AGI Tx >> agi_type: SIP [2011-02-04 14:10:59] VERBOSE[20457] res_agi.c: AGI Tx >> agi_uniqueid: 1296846659.38375 [2011-02-04 14:10:59] VERBOSE[20457] res_agi.c: AGI Tx >> agi_version: 1.6.2.13 [2011-02-04 14:10:59] VERBOSE[20457] res_agi.c: AGI Tx >> agi_callerid: 8165350000 [2011-02-04 14:10:59] VERBOSE[20457] res_agi.c: AGI Tx >> agi_calleridname: unknown [2011-02-04 14:10:59] VERBOSE[20457] res_agi.c: AGI Tx >> agi_callingpres: 0 [2011-02-04 14:10:59] VERBOSE[20457] res_agi.c: AGI Tx >> agi_callingani2: 0 [2011-02-04 14:10:59] VERBOSE[20457] res_agi.c: AGI Tx >> agi_callington: 0 [2011-02-04 14:10:59] VERBOSE[20457] res_agi.c: AGI Tx >> agi_callingtns: 0 [2011-02-04 14:10:59] VERBOSE[20457] res_agi.c: AGI Tx >> agi_dnid: 19164444444 [2011-02-04 14:10:59] VERBOSE[20457] res_agi.c: AGI Tx >> agi_rdnis: unknown [2011-02-04 14:10:59] VERBOSE[20457] res_agi.c: AGI Tx >> agi_context: from-external [2011-02-04 14:10:59] VERBOSE[20457] res_agi.c: AGI Tx >> agi_extension: 19164444444 [2011-02-04 14:10:59] VERBOSE[20457] res_agi.c: AGI Tx >> agi_priority: 1 [2011-02-04 14:10:59] VERBOSE[20457] res_agi.c: AGI Tx >> agi_enhanced: 0.0 [2011-02-04 14:10:59] VERBOSE[20457] res_agi.c: AGI Tx >> agi_accountcode: [2011-02-04 14:10:59] VERBOSE[20457] res_agi.c: AGI Tx >> agi_threadid: 1095907648 [2011-02-04 14:10:59] VERBOSE[20457] res_agi.c: AGI Tx >> [2011-02-04 14:10:59] VERBOSE[20457] res_agi.c: AGI Rx << VERBOSE "AGI starting at Fri Feb 4 14:10:59 2011 (1296846659)" 9 [2011-02-04 14:10:59] VERBOSE[20457] res_agi.c: AGI Tx >> 200 result=1 [2011-02-04 14:10:59] VERBOSE[20457] res_agi.c: AGI Rx << SET VARIABLE CHANNEL(language) "en" [2011-02-04 14:10:59] VERBOSE[20457] res_agi.c: AGI Tx >> 200 result=1 [2011-02-04 14:10:59] VERBOSE[20457] res_agi.c: AGI Rx << GET VARIABLE ENSWITCH-CALLID [2011-02-04 14:10:59] VERBOSE[20457] res_agi.c: AGI Tx >> 200 result=0 [2011-02-04 14:10:59] VERBOSE[20457] res_agi.c: AGI Rx << SET VARIABLE __ENSWITCH-CALLID "1296846659.38375" [2011-02-04 14:10:59] VERBOSE[20457] res_agi.c: AGI Tx >> 200 result=1 [2011-02-04 14:10:59] VERBOSE[20457] res_agi.c: AGI Rx << GET VARIABLE ENSWITCH-SCREEN [2011-02-04 14:10:59] VERBOSE[20457] res_agi.c: AGI Tx >> 200 result=0 [2011-02-04 14:10:59] VERBOSE[20457] res_agi.c: AGI Rx << GET VARIABLE ENSWITCH-DIVERTED [2011-02-04 14:10:59] VERBOSE[20457] res_agi.c: AGI Tx >> 200 result=0 [2011-02-04 14:10:59] VERBOSE[20457] res_agi.c: AGI Rx << GET VARIABLE ENSWITCH-DIVERSION [2011-02-04 14:10:59] VERBOSE[20457] res_agi.c: AGI Tx >> 200 result=0 [2011-02-04 14:10:59] VERBOSE[20457] res_agi.c: AGI Rx << SET VARIABLE __ENSWITCH-MUSIC "default" [2011-02-04 14:10:59] VERBOSE[20457] res_agi.c: AGI Tx >> 200 result=1 [2011-02-04 14:10:59] VERBOSE[20457] res_agi.c: AGI Rx << SET VARIABLE CHANNEL(musicclass) "default" [2011-02-04 14:10:59] VERBOSE[20457] res_agi.c: AGI Tx >> 200 result=1 [2011-02-04 14:10:59] VERBOSE[20457] res_agi.c: AGI Rx << SET VARIABLE __TRANSFER_CONTEXT "from-internal" [2011-02-04 14:10:59] VERBOSE[20457] res_agi.c: AGI Tx >> 200 result=1 [2011-02-04 14:10:59] VERBOSE[20457] res_agi.c: AGI Rx << SET VARIABLE TIMEOUT(absolute) "86400" [2011-02-04 14:10:59] VERBOSE[20457] res_agi.c: AGI Tx >> 200 result=1 [2011-02-04 14:10:59] VERBOSE[20457] res_agi.c: AGI Rx << GET VARIABLE ENSWITCH-CUTOFF [2011-02-04 14:10:59] VERBOSE[20457] res_agi.c: AGI Tx >> 200 result=0 [2011-02-04 14:10:59] VERBOSE[20457] res_agi.c: AGI Rx << SET VARIABLE TIMEOUT(absolute) "86400" [2011-02-04 14:10:59] VERBOSE[20457] res_agi.c: AGI Tx >> 200 result=1 [2011-02-04 14:10:59] VERBOSE[20457] res_agi.c: AGI Rx << GET VARIABLE ENSWITCH-SCUSTOMER [2011-02-04 14:10:59] VERBOSE[20457] res_agi.c: AGI Tx >> 200 result=0 [2011-02-04 14:10:59] VERBOSE[20457] res_agi.c: AGI Rx << GET VARIABLE SIPCHANINFO(recvip) [2011-02-04 14:10:59] VERBOSE[20457] res_agi.c: AGI Tx >> 200 result=1 (208.94.157.10) [2011-02-04 14:10:59] VERBOSE[20457] res_agi.c: AGI Rx << GET VARIABLE ENSWITCH-PRESENTATION [2011-02-04 14:10:59] VERBOSE[20457] res_agi.c: AGI Tx >> 200 result=0 [2011-02-04 14:10:59] VERBOSE[20457] res_agi.c: AGI Rx << GET VARIABLE ENSWITCH-TRANSFER-PHONE [2011-02-04 14:10:59] VERBOSE[20457] res_agi.c: AGI Tx >> 200 result=0 [2011-02-04 14:10:59] VERBOSE[20457] res_agi.c: AGI Rx << VERBOSE "Resolving type '', number '9164444444', old '', forward 1 for channel SIP/8-208.94.157.10-0000387b" 5 [2011-02-04 14:10:59] VERBOSE[20457] res_agi.c: AGI Tx >> 200 result=1 [2011-02-04 14:10:59] VERBOSE[20457] res_agi.c: AGI Rx << SET VARIABLE __ENSWITCH-DIVERSION "9164444444" [2011-02-04 14:10:59] VERBOSE[20457] res_agi.c: AGI Tx >> 200 result=1 [2011-02-04 14:10:59] VERBOSE[20457] res_agi.c: AGI Rx << SET VARIABLE CHANNEL(language) "en" [2011-02-04 14:10:59] VERBOSE[20457] res_agi.c: AGI Tx >> 200 result=1 [2011-02-04 14:10:59] VERBOSE[20457] res_agi.c: AGI Rx << VERBOSE "Starting recording group '3' expires '1297710659' file '/var/lib/enswitch/recordings/betapbx/938/375/record_129684665938375_20389'" 9 [2011-02-04 14:10:59] VERBOSE[20457] res_agi.c: AGI Tx >> 200 result=1 [2011-02-04 14:10:59] VERBOSE[20457] res_agi.c: AGI Rx << EXEC Monitor "gsm,/var/lib/enswitch/recordings/betapbx/938/375/record_129684665938375_20389" [2011-02-04 14:10:59] VERBOSE[20457] res_agi.c: AGI Tx >> 200 result=0 [2011-02-04 14:10:59] VERBOSE[20457] res_agi.c: AGI Rx << VERBOSE "Resolving type 'queue', number '2', old '', forward 2 for channel SIP/8-208.94.157.10-0000387b" 5 [2011-02-04 14:10:59] VERBOSE[20457] res_agi.c: AGI Tx >> 200 result=1 [2011-02-04 14:10:59] VERBOSE[20457] res_agi.c: AGI Rx << VERBOSE "Customer 3 has 86398 inbound seconds available." 9 [2011-02-04 14:10:59] VERBOSE[20457] res_agi.c: AGI Tx >> 200 result=1 [2011-02-04 14:10:59] VERBOSE[20457] res_agi.c: AGI Rx << SET VARIABLE TIMEOUT(absolute) "86398" [2011-02-04 14:10:59] VERBOSE[20457] res_agi.c: AGI Tx >> 200 result=1 [2011-02-04 14:10:59] VERBOSE[20457] res_agi.c: AGI Rx << VERBOSE "Writing entry to active table for 1296846659.38375, external:8165350000 - queue:2." 9 [2011-02-04 14:10:59] VERBOSE[20457] res_agi.c: AGI Tx >> 200 result=1 [2011-02-04 14:10:59] VERBOSE[20457] res_agi.c: AGI Rx << GET VARIABLE SIP_HEADER(Call-ID) [2011-02-04 14:10:59] VERBOSE[20457] res_agi.c: AGI Tx >> 200 result=1 (CXC-224-75aa1390-a9d5ed0-13c4-4d4c4fb9-afe2be8-5559069d@208.94.157.10) [2011-02-04 14:10:59] VERBOSE[20457] res_agi.c: AGI Rx << SET VARIABLE TIMEOUT(absolute) "86398" [2011-02-04 14:10:59] VERBOSE[20457] res_agi.c: AGI Tx >> 200 result=1 [2011-02-04 14:10:59] VERBOSE[20457] res_agi.c: AGI Rx << SET VARIABLE __ENSWITCH-DCUSTOMER "3" [2011-02-04 14:10:59] VERBOSE[20457] res_agi.c: AGI Tx >> 200 result=1 [2011-02-04 14:10:59] VERBOSE[20457] res_agi.c: AGI Rx << SET VARIABLE __ENSWITCH-DTYPE "queue" [2011-02-04 14:10:59] VERBOSE[20457] res_agi.c: AGI Tx >> 200 result=1 [2011-02-04 14:10:59] VERBOSE[20457] res_agi.c: AGI Rx << SET VARIABLE __ENSWITCH-DNUMBER "2" [2011-02-04 14:10:59] VERBOSE[20457] res_agi.c: AGI Tx >> 200 result=1 [2011-02-04 14:10:59] VERBOSE[20457] res_agi.c: AGI Rx << SET VARIABLE __LIMIT_PLAYAUDIO_CALLER "no" [2011-02-04 14:10:59] VERBOSE[20457] res_agi.c: AGI Tx >> 200 result=1 [2011-02-04 14:10:59] VERBOSE[20457] res_agi.c: AGI Rx << SET VARIABLE __LIMIT_PLAYAUDIO_CALLEE "yes" [2011-02-04 14:10:59] VERBOSE[20457] res_agi.c: AGI Tx >> 200 result=1 [2011-02-04 14:10:59] VERBOSE[20457] res_agi.c: AGI Rx << SET VARIABLE CALLERID(number) "8165350000" [2011-02-04 14:10:59] VERBOSE[20457] res_agi.c: AGI Tx >> 200 result=1 [2011-02-04 14:10:59] VERBOSE[20457] res_agi.c: AGI Rx << SET VARIABLE __ENSWITCH-TRANSFER-CUSTOMER "3" [2011-02-04 14:10:59] VERBOSE[20457] res_agi.c: AGI Tx >> 200 result=1 [2011-02-04 14:10:59] VERBOSE[20457] res_agi.c: AGI Rx << SET VARIABLE __ENSWITCH-PRESENTATION "3" [2011-02-04 14:10:59] VERBOSE[20457] res_agi.c: AGI Tx >> 200 result=1 [2011-02-04 14:10:59] VERBOSE[20457] res_agi.c: AGI Rx << SET VARIABLE __ENSWITCH-CUTOFF "86398" [2011-02-04 14:10:59] VERBOSE[20457] res_agi.c: AGI Tx >> 200 result=1 [2011-02-04 14:10:59] VERBOSE[20457] res_agi.c: AGI Rx << EXEC Answer "" [2011-02-04 14:10:59] VERBOSE[20457] chan_sip.c: Audio is at XXX.XXX.XXX11 port 18622 [2011-02-04 14:10:59] VERBOSE[20457] chan_sip.c: Adding codec 0x4 (ulaw) to SDP [2011-02-04 14:10:59] VERBOSE[20457] chan_sip.c: Adding non-codec 0x1 (telephone-event) to SDP [2011-02-04 14:10:59] VERBOSE[20457] chan_sip.c: <--- Reliably Transmitting (no NAT) to 208.94.157.10:5060 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 208.94.157.10:5060;branch=z9hG4bK-88b22-4d4c4fb9-afe2be8-1a7a20e0;received=208.94.157.10 From: ;tag=a9d5ed0-13c4-4d4c4fb9-afe2be8-cb43ee3 To: ;tag=as2992c8f8 Call-ID: CXC-224-75aa1390-a9d5ed0-13c4-4d4c4fb9-afe2be8-5559069d@208.94.157.10 CSeq: 1 INVITE Server: i-Communicate Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO Supported: replaces, timer Contact: Content-Type: application/sdp Content-Length: 235 v=0 o=root 1678076165 1678076165 IN IP4 XXX.XXX.XXX11 s=Asterisk PBX 1.6.2.13 c=IN IP4 XXX.XXX.XXX11 t=0 0 m=audio 18622 RTP/AVP 0 101 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 a=sendrecv <------------> [2011-02-04 14:10:59] VERBOSE[17753] chan_sip.c: <--- SIP read from UDP:208.94.157.10:5060 ---> ACK sip:19164444444@XXX.XXX.XXX11 SIP/2.0 From: ;tag=a9d5ed0-13c4-4d4c4fb9-afe2be8-cb43ee3 To: ;tag=as2992c8f8 Call-ID: CXC-224-75aa1390-a9d5ed0-13c4-4d4c4fb9-afe2be8-5559069d@208.94.157.10 CSeq: 1 ACK Via: SIP/2.0/UDP 208.94.157.10:5060;branch=z9hG4bK-88b2a-4d4c4fb9-afe2cca-2c4f701f Max-Forwards: 69 Contact: Content-Length: 0 <-------------> [2011-02-04 14:10:59] VERBOSE[17753] chan_sip.c: --- (9 headers 0 lines) --- [2011-02-04 14:10:59] VERBOSE[20457] res_agi.c: AGI Tx >> 200 result=0 [2011-02-04 14:10:59] VERBOSE[20457] res_agi.c: AGI Rx << EXEC StartMusicOnHold "default" [2011-02-04 14:10:59] WARNING[20457] res_musiconhold.c: Unable to start music on hold class 'default' on channel SIP/8-208.94.157.10-0000387b [2011-02-04 14:10:59] VERBOSE[20457] res_agi.c: AGI Tx >> 200 result=0 [2011-02-04 14:10:59] VERBOSE[20457] res_agi.c: AGI Rx << SET VARIABLE __ENSWITCH-CALLERID-EXTERNAL "8165350000" [2011-02-04 14:10:59] VERBOSE[20457] res_agi.c: AGI Tx >> 200 result=1 [2011-02-04 14:10:59] VERBOSE[20457] res_agi.c: AGI Rx << SET VARIABLE __ENSWITCH-SCUSTOMER "3" [2011-02-04 14:10:59] VERBOSE[20457] res_agi.c: AGI Tx >> 200 result=1 [2011-02-04 14:10:59] VERBOSE[20457] res_agi.c: AGI Rx << SET VARIABLE __ENSWITCH-STYPE "queue" [2011-02-04 14:10:59] VERBOSE[20457] res_agi.c: AGI Tx >> 200 result=1 [2011-02-04 14:10:59] VERBOSE[20457] res_agi.c: AGI Rx << SET VARIABLE __ENSWITCH-SNUMBER "2" [2011-02-04 14:10:59] VERBOSE[20457] res_agi.c: AGI Tx >> 200 result=1 [2011-02-04 14:10:59] VERBOSE[20457] res_agi.c: AGI Rx << SET VARIABLE __ENSWITCH-CTYPE-1 "phone" [2011-02-04 14:10:59] VERBOSE[20457] res_agi.c: AGI Tx >> 200 result=1 [2011-02-04 14:10:59] VERBOSE[20457] res_agi.c: AGI Rx << SET VARIABLE __ENSWITCH-CNUMBER-1 "00000001" [2011-02-04 14:10:59] VERBOSE[20457] res_agi.c: AGI Tx >> 200 result=1 [2011-02-04 14:10:59] VERBOSE[20457] res_agi.c: AGI Rx << EXEC Dial "Local/1@enswitch-call-exten/n,30,Hgmo" [2011-02-04 14:10:59] VERBOSE[20459] res_agi.c: AGI Tx >> agi_network: yes [2011-02-04 14:10:59] VERBOSE[20459] res_agi.c: AGI Tx >> agi_network_script: enswitch?scustomer=3&stype=queue&snumber=2&ctype=phone&cnumber=00000001&append_callerid=&loopback=1 [2011-02-04 14:10:59] VERBOSE[20459] res_agi.c: AGI Tx >> agi_request: agi://127.0.0.1/enswitch?scustomer=3&stype=queue&snumber=2&ctype=phone&cnumber=00000001&append_callerid=&loopback=1 [2011-02-04 14:10:59] VERBOSE[20459] res_agi.c: AGI Tx >> agi_channel: Local/1@enswitch-call-exten-6417;2 [2011-02-04 14:10:59] VERBOSE[20459] res_agi.c: AGI Tx >> agi_language: en [2011-02-04 14:10:59] VERBOSE[20459] res_agi.c: AGI Tx >> agi_type: Local [2011-02-04 14:10:59] VERBOSE[20459] res_agi.c: AGI Tx >> agi_uniqueid: 1296846659.38377 [2011-02-04 14:10:59] VERBOSE[20459] res_agi.c: AGI Tx >> agi_version: 1.6.2.13 [2011-02-04 14:10:59] VERBOSE[20459] res_agi.c: AGI Tx >> agi_callerid: 8165350000 [2011-02-04 14:10:59] VERBOSE[20459] res_agi.c: AGI Tx >> agi_calleridname: unknown [2011-02-04 14:10:59] VERBOSE[20459] res_agi.c: AGI Tx >> agi_callingpres: 0 [2011-02-04 14:10:59] VERBOSE[20459] res_agi.c: AGI Tx >> agi_callingani2: 0 [2011-02-04 14:10:59] VERBOSE[20459] res_agi.c: AGI Tx >> agi_callington: 0 [2011-02-04 14:10:59] VERBOSE[20459] res_agi.c: AGI Tx >> agi_callingtns: 1 [2011-02-04 14:10:59] VERBOSE[20459] res_agi.c: AGI Tx >> agi_dnid: unknown [2011-02-04 14:10:59] VERBOSE[20459] res_agi.c: AGI Tx >> agi_rdnis: unknown [2011-02-04 14:10:59] VERBOSE[20459] res_agi.c: AGI Tx >> agi_context: enswitch-call-exten [2011-02-04 14:10:59] VERBOSE[20459] res_agi.c: AGI Tx >> agi_extension: 1 [2011-02-04 14:10:59] VERBOSE[20459] res_agi.c: AGI Tx >> agi_priority: 1 [2011-02-04 14:10:59] VERBOSE[20459] res_agi.c: AGI Tx >> agi_enhanced: 0.0 [2011-02-04 14:10:59] VERBOSE[20459] res_agi.c: AGI Tx >> agi_accountcode: [2011-02-04 14:10:59] VERBOSE[20459] res_agi.c: AGI Tx >> agi_threadid: 1112635712 [2011-02-04 14:10:59] VERBOSE[20459] res_agi.c: AGI Tx >> [2011-02-04 14:10:59] VERBOSE[20459] res_agi.c: AGI Rx << VERBOSE "AGI starting at Fri Feb 4 14:10:59 2011 (1296846659)" 9 [2011-02-04 14:10:59] VERBOSE[20459] res_agi.c: AGI Tx >> 200 result=1 [2011-02-04 14:10:59] VERBOSE[20459] res_agi.c: AGI Rx << SET VARIABLE CHANNEL(language) "en" [2011-02-04 14:10:59] VERBOSE[20459] res_agi.c: AGI Tx >> 200 result=1 [2011-02-04 14:10:59] VERBOSE[20459] res_agi.c: AGI Rx << GET VARIABLE ENSWITCH-CALLID [2011-02-04 14:10:59] VERBOSE[20459] res_agi.c: AGI Tx >> 200 result=1 (1296846659.38375) [2011-02-04 14:10:59] VERBOSE[20459] res_agi.c: AGI Rx << GET VARIABLE ENSWITCH-SCREEN [2011-02-04 14:10:59] VERBOSE[20459] res_agi.c: AGI Tx >> 200 result=0 [2011-02-04 14:10:59] VERBOSE[20459] res_agi.c: AGI Rx << GET VARIABLE ENSWITCH-DIVERTED [2011-02-04 14:10:59] VERBOSE[20459] res_agi.c: AGI Tx >> 200 result=0 [2011-02-04 14:10:59] VERBOSE[20459] res_agi.c: AGI Rx << GET VARIABLE ENSWITCH-DIVERSION [2011-02-04 14:10:59] VERBOSE[20459] res_agi.c: AGI Tx >> 200 result=1 (9164444444) [2011-02-04 14:10:59] VERBOSE[20459] res_agi.c: AGI Rx << SET VARIABLE __ENSWITCH-MUSIC "default" [2011-02-04 14:10:59] VERBOSE[20459] res_agi.c: AGI Tx >> 200 result=1 [2011-02-04 14:10:59] VERBOSE[20459] res_agi.c: AGI Rx << SET VARIABLE CHANNEL(musicclass) "default" [2011-02-04 14:10:59] VERBOSE[20459] res_agi.c: AGI Tx >> 200 result=1 [2011-02-04 14:10:59] VERBOSE[20459] res_agi.c: AGI Rx << SET VARIABLE __TRANSFER_CONTEXT "from-internal" [2011-02-04 14:10:59] VERBOSE[20459] res_agi.c: AGI Tx >> 200 result=1 [2011-02-04 14:10:59] VERBOSE[20459] res_agi.c: AGI Rx << SET VARIABLE TIMEOUT(absolute) "86400" [2011-02-04 14:10:59] VERBOSE[20459] res_agi.c: AGI Tx >> 200 result=1 [2011-02-04 14:10:59] VERBOSE[20459] res_agi.c: AGI Rx << GET VARIABLE ENSWITCH-CUTOFF [2011-02-04 14:10:59] VERBOSE[20459] res_agi.c: AGI Tx >> 200 result=1 (86398) [2011-02-04 14:10:59] VERBOSE[20459] res_agi.c: AGI Rx << SET VARIABLE TIMEOUT(absolute) "86398" [2011-02-04 14:10:59] VERBOSE[20459] res_agi.c: AGI Tx >> 200 result=1 [2011-02-04 14:10:59] VERBOSE[20459] res_agi.c: AGI Rx << GET VARIABLE ENSWITCH-PRESENTATION [2011-02-04 14:10:59] VERBOSE[20459] res_agi.c: AGI Tx >> 200 result=1 (3) [2011-02-04 14:10:59] VERBOSE[20459] res_agi.c: AGI Rx << GET VARIABLE ENSWITCH-TRANSFER-PHONE [2011-02-04 14:10:59] VERBOSE[20459] res_agi.c: AGI Tx >> 200 result=0 [2011-02-04 14:10:59] VERBOSE[20459] res_agi.c: AGI Rx << GET VARIABLE ENSWITCH-CALLERID-EXTERNAL [2011-02-04 14:10:59] VERBOSE[20459] res_agi.c: AGI Tx >> 200 result=1 (8165350000) [2011-02-04 14:10:59] VERBOSE[20459] res_agi.c: AGI Rx << VERBOSE "Resolving type 'phone', number '00000001', old '', forward 1 for channel Local/1@enswitch-call-exten-6417;2" 5 [2011-02-04 14:10:59] VERBOSE[20459] res_agi.c: AGI Tx >> 200 result=1 [2011-02-04 14:10:59] VERBOSE[20459] res_agi.c: AGI Rx << VERBOSE "Starting recording group '1' expires '1299438659' file '/var/lib/enswitch/recordings/betapbx/938/377/record_129684665938377_20187'" 9 [2011-02-04 14:10:59] VERBOSE[20459] res_agi.c: AGI Tx >> 200 result=1 [2011-02-04 14:10:59] VERBOSE[20459] res_agi.c: AGI Rx << EXEC Monitor "gsm,/var/lib/enswitch/recordings/betapbx/938/377/record_129684665938377_20187" [2011-02-04 14:10:59] VERBOSE[20459] res_agi.c: AGI Tx >> 200 result=0 [2011-02-04 14:10:59] VERBOSE[20459] res_agi.c: AGI Rx << VERBOSE "Writing entry to active table for 1296846659.38377, queue:2 - phone:00000001." 9 [2011-02-04 14:10:59] VERBOSE[20459] res_agi.c: AGI Tx >> 200 result=1 [2011-02-04 14:10:59] VERBOSE[20459] res_agi.c: AGI Rx << SET VARIABLE TIMEOUT(absolute) "86398" [2011-02-04 14:10:59] VERBOSE[20459] res_agi.c: AGI Tx >> 200 result=1 [2011-02-04 14:10:59] VERBOSE[20459] res_agi.c: AGI Rx << EXEC StartMusicOnHold "default" [2011-02-04 14:10:59] WARNING[20459] res_musiconhold.c: Unable to start music on hold class 'default' on channel Local/1@enswitch-call-exten-6417;2 [2011-02-04 14:10:59] VERBOSE[20459] res_agi.c: AGI Tx >> 200 result=0 [2011-02-04 14:10:59] VERBOSE[20459] res_agi.c: AGI Rx << SET VARIABLE __ENSWITCH-DCUSTOMER "3" [2011-02-04 14:10:59] VERBOSE[20459] res_agi.c: AGI Tx >> 200 result=1 [2011-02-04 14:10:59] VERBOSE[20459] res_agi.c: AGI Rx << SET VARIABLE __ENSWITCH-DTYPE "phone" [2011-02-04 14:10:59] VERBOSE[20459] res_agi.c: AGI Tx >> 200 result=1 [2011-02-04 14:10:59] VERBOSE[20459] res_agi.c: AGI Rx << SET VARIABLE __ENSWITCH-DNUMBER "00000001" [2011-02-04 14:10:59] VERBOSE[20459] res_agi.c: AGI Tx >> 200 result=1 [2011-02-04 14:10:59] VERBOSE[20459] res_agi.c: AGI Rx << SET VARIABLE __LIMIT_PLAYAUDIO_CALLER "no" [2011-02-04 14:10:59] VERBOSE[20459] res_agi.c: AGI Tx >> 200 result=1 [2011-02-04 14:10:59] VERBOSE[20459] res_agi.c: AGI Rx << SET VARIABLE __LIMIT_PLAYAUDIO_CALLEE "yes" [2011-02-04 14:10:59] VERBOSE[20459] res_agi.c: AGI Tx >> 200 result=1 [2011-02-04 14:10:59] VERBOSE[20459] res_agi.c: AGI Rx << SET VARIABLE CALLERID(number) "8165350000" [2011-02-04 14:10:59] VERBOSE[20459] res_agi.c: AGI Tx >> 200 result=1 [2011-02-04 14:10:59] VERBOSE[20459] res_agi.c: AGI Rx << SET VARIABLE __ENSWITCH-TRANSFER-CUSTOMER "3" [2011-02-04 14:10:59] VERBOSE[20459] res_agi.c: AGI Tx >> 200 result=1 [2011-02-04 14:10:59] VERBOSE[20459] res_agi.c: AGI Rx << SET VARIABLE __ENSWITCH-PRESENTATION "3" [2011-02-04 14:10:59] VERBOSE[20459] res_agi.c: AGI Tx >> 200 result=1 [2011-02-04 14:10:59] VERBOSE[20459] res_agi.c: AGI Rx << SET VARIABLE __ENSWITCH-CUTOFF "86398" [2011-02-04 14:10:59] VERBOSE[20459] res_agi.c: AGI Tx >> 200 result=1 [2011-02-04 14:10:59] VERBOSE[20459] res_agi.c: AGI Rx << SET VARIABLE __SIPADDHEADER06 "Diversion: " [2011-02-04 14:10:59] VERBOSE[20459] res_agi.c: AGI Tx >> 200 result=1 [2011-02-04 14:10:59] VERBOSE[20459] res_agi.c: AGI Rx << SET VARIABLE __ENSWITCH-PHONE-DOMAIN "XXX.XXX.XXX16:5060" [2011-02-04 14:10:59] VERBOSE[20459] res_agi.c: AGI Tx >> 200 result=1 [2011-02-04 14:10:59] VERBOSE[20459] res_agi.c: AGI Rx << SET VARIABLE __ENSWITCH-PHONE-OPTS "oit" [2011-02-04 14:10:59] VERBOSE[20459] res_agi.c: AGI Tx >> 200 result=1 [2011-02-04 14:10:59] VERBOSE[20459] res_agi.c: AGI Rx << SET VARIABLE __ENSWITCH-TRANSFER-PHONE "00000001" [2011-02-04 14:10:59] VERBOSE[20459] res_agi.c: AGI Tx >> 200 result=1 [2011-02-04 14:11:00] VERBOSE[20459] res_agi.c: AGI Rx << EXEC Dial "Local/00000001@enswitch-phone/n,30,o" [2011-02-04 14:11:00] VERBOSE[20464] chan_sip.c: Audio is at XXX.XXX.XXX11 port 19192 [2011-02-04 14:11:00] VERBOSE[20464] chan_sip.c: Adding codec 0x4 (ulaw) to SDP [2011-02-04 14:11:00] VERBOSE[20464] chan_sip.c: Adding codec 0x1000 (g722) to SDP [2011-02-04 14:11:00] VERBOSE[20464] chan_sip.c: Adding non-codec 0x1 (telephone-event) to SDP [2011-02-04 14:11:00] VERBOSE[20464] chan_sip.c: Reliably Transmitting (NAT) to XXX.XXX.XXX16:5060: INVITE sip:00000001@XXX.XXX.XXX16:5060 SIP/2.0 Via: SIP/2.0/UDP XXX.XXX.XXX11:5060;branch=z9hG4bK75944652;rport Max-Forwards: 70 From: "8165350000" ;tag=as1659b3c4 To: Contact: Call-ID: 507d9c9d3c25e9c9449e16716a29f4a6@XXX.XXX.XXX16 CSeq: 102 INVITE User-Agent: i-Communicate Date: Fri, 04 Feb 2011 19:11:00 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO Supported: replaces, timer Diversion: Content-Type: application/sdp Content-Length: 259 v=0 o=root 1408785002 1408785002 IN IP4 XXX.XXX.XXX11 s=Asterisk PBX 1.6.2.13 c=IN IP4 XXX.XXX.XXX11 t=0 0 m=audio 19192 RTP/AVP 0 9 101 a=rtpmap:0 PCMU/8000 a=rtpmap:9 G722/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 a=sendrecv --- [2011-02-04 14:11:00] VERBOSE[17753] chan_sip.c: <--- SIP read from UDP:XXX.XXX.XXX16:5060 ---> SIP/2.0 100 Giving a try Via: SIP/2.0/UDP XXX.XXX.XXX11:5060;branch=z9hG4bK75944652;rport=5060 From: "8165350000" ;tag=as1659b3c4 To: Call-ID: 507d9c9d3c25e9c9449e16716a29f4a6@XXX.XXX.XXX16 CSeq: 102 INVITE Server: Enswitch SIP proxy Content-Length: 0 Warning: 392 XXX.XXX.XXX16:5060 "Noisy feedback tells: pid=24069 req_src_ip=XXX.XXX.XXX11 req_src_port=5060 in_uri=sip:00000001@XXX.XXX.XXX16:5060 out_uri=sip:00000001@72.237.213.162:1029 via_cnt==1" <-------------> [2011-02-04 14:11:00] VERBOSE[17753] chan_sip.c: --- (9 headers 0 lines) --- [2011-02-04 14:11:00] VERBOSE[17753] chan_sip.c: <--- SIP read from UDP:XXX.XXX.XXX16:5060 ---> SIP/2.0 180 Ringing Via: SIP/2.0/UDP XXX.XXX.XXX11:5060;received=XXX.XXX.XXX11;branch=z9hG4bK75944652;rport=5060 From: "8165350000" ;tag=as1659b3c4 To: "E. Schmid." ;tag=5F413971-CB112E56 CSeq: 102 INVITE Call-ID: 507d9c9d3c25e9c9449e16716a29f4a6@XXX.XXX.XXX16 Contact: Record-Route: User-Agent: PolycomSoundPointIP-SPIP_335-UA/3.2.2.0477 Allow-Events: talk,hold,conference Accept-Language: en Content-Length: 0 <-------------> [2011-02-04 14:11:00] VERBOSE[17753] chan_sip.c: --- (12 headers 0 lines) --- [2011-02-04 14:11:01] VERBOSE[17753] chan_sip.c: <--- SIP read from UDP:XXX.XXX.XXX16:32843 ---> OPTIONS sip:XXX.XXX.XXX11:5060 SIP/2.0 Via: SIP/2.0/UDP XXX.XXX.XXX16:52305;branch=z9hG4bK.3282b29b;rport;alias From: sip:sipsak@XXX.XXX.XXX16:52305;tag=38611c14 To: sip:XXX.XXX.XXX11:5060 Call-ID: 945888276@XXX.XXX.XXX16 CSeq: 1 OPTIONS Contact: sip:sipsak@XXX.XXX.XXX16:52305 Content-Length: 0 Max-Forwards: 70 User-Agent: sipsak 0.9.6 Accept: text/plain <-------------> [2011-02-04 14:11:01] VERBOSE[17753] chan_sip.c: --- (11 headers 0 lines) --- [2011-02-04 14:11:01] VERBOSE[17753] chan_sip.c: Looking for s in from-external (domain XXX.XXX.XXX11) [2011-02-04 14:11:01] VERBOSE[17753] chan_sip.c: <--- Transmitting (NAT) to XXX.XXX.XXX16:32843 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP XXX.XXX.XXX16:52305;branch=z9hG4bK.3282b29b;alias;received=XXX.XXX.XXX16;rport=32843 From: sip:sipsak@XXX.XXX.XXX16:52305;tag=38611c14 To: sip:XXX.XXX.XXX11:5060;tag=as5be838bf Call-ID: 945888276@XXX.XXX.XXX16 CSeq: 1 OPTIONS Server: i-Communicate Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO Supported: replaces, timer Contact: Accept: application/sdp Content-Length: 0 <------------> [2011-02-04 14:11:01] VERBOSE[17753] chan_sip.c: Scheduling destruction of SIP dialog '945888276@XXX.XXX.XXX16' in 32000 ms (Method: OPTIONS) [2011-02-04 14:11:02] VERBOSE[17753] chan_sip.c: <--- SIP read from UDP:XXX.XXX.XXX16:5060 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP XXX.XXX.XXX11:5060;received=XXX.XXX.XXX11;branch=z9hG4bK75944652;rport=5060 From: "8165350000" ;tag=as1659b3c4 To: "E. Schmid." ;tag=5F413971-CB112E56 CSeq: 102 INVITE Call-ID: 507d9c9d3c25e9c9449e16716a29f4a6@XXX.XXX.XXX16 Contact: Record-Route: Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER Supported: 100rel,replaces User-Agent: PolycomSoundPointIP-SPIP_335-UA/3.2.2.0477 Accept-Language: en Content-Type: application/sdp Content-Length: 223 v=0 o=- 1296844342 1296844342 IN IP4 72.237.213.162 s=Polycom IP Phone c=IN IP4 72.237.213.162 t=0 0 m=audio 2228 RTP/AVP 0 101 a=sendrecv a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=direction:active <-------------> [2011-02-04 14:11:02] VERBOSE[17753] chan_sip.c: --- (14 headers 10 lines) --- [2011-02-04 14:11:02] VERBOSE[17753] chan_sip.c: Found RTP audio format 0 [2011-02-04 14:11:02] VERBOSE[17753] chan_sip.c: Found RTP audio format 101 [2011-02-04 14:11:02] VERBOSE[17753] chan_sip.c: Found audio description format PCMU for ID 0 [2011-02-04 14:11:02] VERBOSE[17753] chan_sip.c: Found audio description format telephone-event for ID 101 [2011-02-04 14:11:02] VERBOSE[17753] chan_sip.c: Capabilities: us - 0x1004 (ulaw|g722), peer - audio=0x4 (ulaw)/video=0x0 (nothing)/text=0x0 (nothing), combined - 0x4 (ulaw) [2011-02-04 14:11:02] VERBOSE[17753] chan_sip.c: Non-codec capabilities (dtmf): us - 0x1 (telephone-event), peer - 0x1 (telephone-event), combined - 0x1 (telephone-event) [2011-02-04 14:11:02] VERBOSE[17753] chan_sip.c: Peer audio RTP is at port 72.237.213.162:2228 [2011-02-04 14:11:02] VERBOSE[17753] chan_sip.c: list_route: hop: [2011-02-04 14:11:02] VERBOSE[17753] chan_sip.c: set_destination: Parsing for address/port to send to [2011-02-04 14:11:02] VERBOSE[17753] chan_sip.c: set_destination: set destination to XXX.XXX.XXX16, port 5060 [2011-02-04 14:11:02] VERBOSE[17753] chan_sip.c: Transmitting (NAT) to XXX.XXX.XXX16:5060: ACK sip:00000001@72.237.213.162:1029 SIP/2.0 Via: SIP/2.0/UDP XXX.XXX.XXX11:5060;branch=z9hG4bK0332201c;rport Route: Max-Forwards: 70 From: "8165350000" ;tag=as1659b3c4 To: ;tag=5F413971-CB112E56 Contact: Call-ID: 507d9c9d3c25e9c9449e16716a29f4a6@XXX.XXX.XXX16 CSeq: 102 ACK User-Agent: i-Communicate Content-Length: 0 --- [2011-02-04 14:11:03] VERBOSE[17753] chan_sip.c: <--- SIP read from UDP:XXX.XXX.XXX16:5060 ---> ACK sip:8165350000@XXX.XXX.XXX11 SIP/2.0 Record-Route: Via: SIP/2.0/UDP XXX.XXX.XXX16;branch=z9hG4bK309afb2f5786654C Via: SIP/2.0/UDP 72.237.213.162:1029;branch=z9hG4bK309afb2f5786654C From: "E. Schmid." ;tag=5F413971-CB112E56 To: "8165350000" ;tag=as1659b3c4 Route: CSeq: 1 ACK Call-ID: 507d9c9d3c25e9c9449e16716a29f4a6@XXX.XXX.XXX16 Contact: Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER User-Agent: PolycomSoundPointIP-SPIP_335-UA/3.2.2.0477 Accept-Language: en Max-Forwards: 69 Content-Length: 0 X-Enswitch-RURI: sip:8165350000@XXX.XXX.XXX11 X-Enswitch-Source: 72.237.213.162:1029 <-------------> [2011-02-04 14:11:03] VERBOSE[17753] chan_sip.c: --- (17 headers 0 lines) --- [2011-02-04 14:11:03] VERBOSE[17753] chan_sip.c: <--- SIP read from UDP:XXX.XXX.XXX16:5060 ---> INVITE sip:8165350000@XXX.XXX.XXX11 SIP/2.0 Record-Route: Via: SIP/2.0/UDP XXX.XXX.XXX16;branch=z9hG4bK541d.8ed1ed77.0 Via: SIP/2.0/UDP 72.237.213.162:1029;branch=z9hG4bK89b6ad3d864B53D2 From: "E. Schmid." ;tag=5F413971-CB112E56 To: "8165350000" ;tag=as1659b3c4 Route: CSeq: 2 INVITE Call-ID: 507d9c9d3c25e9c9449e16716a29f4a6@XXX.XXX.XXX16 Contact: Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER User-Agent: PolycomSoundPointIP-SPIP_335-UA/3.2.2.0477 Accept-Language: en Supported: 100rel,replaces Allow-Events: talk,hold,conference Proxy-Authorization: Digest username="00000001", realm="XXX.XXX.XXX16", nonce="4d4c4f6500002f7e13ce1b4a1eafc307943903860132d02b", uri="sip:8165350000@XXX.XXX.XXX11", response="0042c019f38a1c61afbc1619a4eb36aa", algorithm=MD5 Max-Forwards: 69 Content-Type: application/sdp Content-Length: 215 X-Enswitch-RURI: sip:8165350000@XXX.XXX.XXX11 X-Enswitch-Source: 72.237.213.162:1029 v=0 o=- 1296844342 1296844343 IN IP4 72.237.213.162 s=Polycom IP Phone c=IN IP4 72.237.213.162 t=0 0 a=sendonly m=audio 2228 RTP/AVP 0 101 a=sendonly a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 <-------------> [2011-02-04 14:11:03] VERBOSE[17753] chan_sip.c: --- (21 headers 10 lines) --- [2011-02-04 14:11:03] VERBOSE[17753] chan_sip.c: Sending to XXX.XXX.XXX16 : 5060 (NAT) [2011-02-04 14:11:03] VERBOSE[17753] chan_sip.c: Found RTP audio format 0 [2011-02-04 14:11:03] VERBOSE[17753] chan_sip.c: Found RTP audio format 101 [2011-02-04 14:11:03] VERBOSE[17753] chan_sip.c: Found audio description format PCMU for ID 0 [2011-02-04 14:11:03] VERBOSE[17753] chan_sip.c: Found audio description format telephone-event for ID 101 [2011-02-04 14:11:03] VERBOSE[17753] chan_sip.c: Capabilities: us - 0x1004 (ulaw|g722), peer - audio=0x4 (ulaw)/video=0x0 (nothing)/text=0x0 (nothing), combined - 0x4 (ulaw) [2011-02-04 14:11:03] VERBOSE[17753] chan_sip.c: Non-codec capabilities (dtmf): us - 0x1 (telephone-event), peer - 0x1 (telephone-event), combined - 0x1 (telephone-event) [2011-02-04 14:11:03] VERBOSE[17753] chan_sip.c: Peer audio RTP is at port 72.237.213.162:2228 [2011-02-04 14:11:03] VERBOSE[17753] chan_sip.c: <--- Transmitting (NAT) to XXX.XXX.XXX16:5060 ---> SIP/2.0 100 Trying Via: SIP/2.0/UDP XXX.XXX.XXX16;branch=z9hG4bK541d.8ed1ed77.0;received=XXX.XXX.XXX16 Via: SIP/2.0/UDP 72.237.213.162:1029;branch=z9hG4bK89b6ad3d864B53D2 Record-Route: From: "E. Schmid." ;tag=5F413971-CB112E56 To: "8165350000" ;tag=as1659b3c4 Call-ID: 507d9c9d3c25e9c9449e16716a29f4a6@XXX.XXX.XXX16 CSeq: 2 INVITE Server: i-Communicate Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO Supported: replaces, timer Contact: Content-Length: 0 <------------> [2011-02-04 14:11:03] VERBOSE[17753] chan_sip.c: Audio is at XXX.XXX.XXX11 port 19192 [2011-02-04 14:11:03] VERBOSE[17753] chan_sip.c: Adding codec 0x4 (ulaw) to SDP [2011-02-04 14:11:03] VERBOSE[17753] chan_sip.c: Adding non-codec 0x1 (telephone-event) to SDP [2011-02-04 14:11:03] VERBOSE[17753] chan_sip.c: <--- Reliably Transmitting (NAT) to XXX.XXX.XXX16:5060 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP XXX.XXX.XXX16;branch=z9hG4bK541d.8ed1ed77.0;received=XXX.XXX.XXX16 Via: SIP/2.0/UDP 72.237.213.162:1029;branch=z9hG4bK89b6ad3d864B53D2 Record-Route: From: "E. Schmid." ;tag=5F413971-CB112E56 To: "8165350000" ;tag=as1659b3c4 Call-ID: 507d9c9d3c25e9c9449e16716a29f4a6@XXX.XXX.XXX16 CSeq: 2 INVITE Server: i-Communicate Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO Supported: replaces, timer Contact: Content-Type: application/sdp Content-Length: 235 v=0 o=root 1408785002 1408785003 IN IP4 XXX.XXX.XXX11 s=Asterisk PBX 1.6.2.13 c=IN IP4 XXX.XXX.XXX11 t=0 0 m=audio 19192 RTP/AVP 0 101 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 a=recvonly <------------> [2011-02-04 14:11:03] VERBOSE[17753] chan_sip.c: <--- SIP read from UDP:XXX.XXX.XXX16:5060 ---> ACK sip:8165350000@XXX.XXX.XXX11 SIP/2.0 Record-Route: Via: SIP/2.0/UDP XXX.XXX.XXX16;branch=z9hG4bK541d.8ed1ed77.2 Via: SIP/2.0/UDP 72.237.213.162:1029;branch=z9hG4bK6d44e4491213218E From: "E. Schmid." ;tag=5F413971-CB112E56 To: "8165350000" ;tag=as1659b3c4 Route: CSeq: 2 ACK Call-ID: 507d9c9d3c25e9c9449e16716a29f4a6@XXX.XXX.XXX16 Contact: Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER User-Agent: PolycomSoundPointIP-SPIP_335-UA/3.2.2.0477 Accept-Language: en Max-Forwards: 69 Content-Length: 0 X-Enswitch-RURI: sip:8165350000@XXX.XXX.XXX11 X-Enswitch-Source: 72.237.213.162:1029 <-------------> [2011-02-04 14:11:03] VERBOSE[17753] chan_sip.c: --- (17 headers 0 lines) --- [2011-02-04 14:11:04] VERBOSE[17753] chan_sip.c: Really destroying SIP dialog '1892047886@XXX.XXX.XXX16' Method: OPTIONS [2011-02-04 14:11:07] VERBOSE[17753] chan_sip.c: <--- SIP read from UDP:XXX.XXX.XXX16:5060 ---> INVITE sip:8522222@XXX.XXX.XXX11:5060;transport=udp SIP/2.0 Record-Route: Via: SIP/2.0/UDP XXX.XXX.XXX16;branch=z9hG4bK003d.80955b67.0 Via: SIP/2.0/UDP 72.237.213.162:1029;branch=z9hG4bKa360525f6513A33C From: "E. Schmid." ;tag=8D086FC4-715FAA95 To: CSeq: 2 INVITE Call-ID: b2a67e60-f63e0c21-5989e5c6@192.168.16.155 Contact: Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER User-Agent: PolycomSoundPointIP-SPIP_335-UA/3.2.2.0477 Accept-Language: en Supported: 100rel,replaces Allow-Events: talk,hold,conference Proxy-Authorization: Digest username="00000001", realm="betaprov.i-evolve.com", nonce="4d4c4f6900002f837ad0e560c465478c27e1ed889810d79c", uri="sip:8522222@betaprov.i-evolve.com;user=phone", response="2cb65275062833aadf0d8e74fa366642", algorithm=MD5 Max-Forwards: 69 Content-Type: application/sdp Content-Length: 298 X-Enswitch-RURI: sip:8522222@betaprov.i-evolve.com;user=phone X-Enswitch-Source: 72.237.213.162:1029 v=0 o=- 1296844347 1296844347 IN IP4 72.237.213.162 s=Polycom IP Phone c=IN IP4 72.237.213.162 t=0 0 a=sendrecv m=audio 2230 RTP/AVP 9 0 8 18 127 a=rtpmap:9 G722/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:18 G729/8000 a=fmtp:18 annexb=no a=rtpmap:127 telephone-event/8000 <-------------> [2011-02-04 14:11:07] VERBOSE[17753] chan_sip.c: --- (20 headers 13 lines) --- [2011-02-04 14:11:07] VERBOSE[17753] chan_sip.c: Sending to XXX.XXX.XXX16 : 5060 (NAT) [2011-02-04 14:11:07] VERBOSE[17753] chan_sip.c: Using INVITE request as basis request - b2a67e60-f63e0c21-5989e5c6@192.168.16.155 [2011-02-04 14:11:07] VERBOSE[17753] chan_sip.c: Found peer 'enswitch-local' for '00000001' from XXX.XXX.XXX16:5060 [2011-02-04 14:11:07] VERBOSE[17753] chan_sip.c: Found RTP audio format 9 [2011-02-04 14:11:07] VERBOSE[17753] chan_sip.c: Found RTP audio format 0 [2011-02-04 14:11:07] VERBOSE[17753] chan_sip.c: Found RTP audio format 8 [2011-02-04 14:11:07] VERBOSE[17753] chan_sip.c: Found RTP audio format 18 [2011-02-04 14:11:07] VERBOSE[17753] chan_sip.c: Found RTP audio format 127 [2011-02-04 14:11:07] VERBOSE[17753] chan_sip.c: Found audio description format G722 for ID 9 [2011-02-04 14:11:07] VERBOSE[17753] chan_sip.c: Found audio description format PCMU for ID 0 [2011-02-04 14:11:07] VERBOSE[17753] chan_sip.c: Found audio description format PCMA for ID 8 [2011-02-04 14:11:07] VERBOSE[17753] chan_sip.c: Found audio description format G729 for ID 18 [2011-02-04 14:11:07] VERBOSE[17753] chan_sip.c: Found audio description format telephone-event for ID 127 [2011-02-04 14:11:07] VERBOSE[17753] chan_sip.c: Capabilities: us - 0x1004 (ulaw|g722), peer - audio=0x110c (ulaw|alaw|g729|g722)/video=0x0 (nothing)/text=0x0 (nothing), combined - 0x1004 (ulaw|g722) [2011-02-04 14:11:07] VERBOSE[17753] chan_sip.c: Non-codec capabilities (dtmf): us - 0x1 (telephone-event), peer - 0x1 (telephone-event), combined - 0x1 (telephone-event) [2011-02-04 14:11:07] VERBOSE[17753] chan_sip.c: Peer audio RTP is at port 72.237.213.162:2230 [2011-02-04 14:11:07] VERBOSE[17753] chan_sip.c: Looking for 8522222 in from-internal (domain XXX.XXX.XXX11) [2011-02-04 14:11:07] VERBOSE[17753] chan_sip.c: list_route: hop: [2011-02-04 14:11:07] VERBOSE[17753] chan_sip.c: <--- Transmitting (NAT) to XXX.XXX.XXX16:5060 ---> SIP/2.0 100 Trying Via: SIP/2.0/UDP XXX.XXX.XXX16;branch=z9hG4bK003d.80955b67.0;received=XXX.XXX.XXX16 Via: SIP/2.0/UDP 72.237.213.162:1029;branch=z9hG4bKa360525f6513A33C Record-Route: From: "E. Schmid." ;tag=8D086FC4-715FAA95 To: Call-ID: b2a67e60-f63e0c21-5989e5c6@192.168.16.155 CSeq: 2 INVITE Server: i-Communicate Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO Supported: replaces, timer Contact: Content-Length: 0 <------------> [2011-02-04 14:11:07] VERBOSE[20518] res_agi.c: AGI Tx >> agi_network: yes [2011-02-04 14:11:07] VERBOSE[20518] res_agi.c: AGI Tx >> agi_network_script: enswitch?stype=internal [2011-02-04 14:11:07] VERBOSE[20518] res_agi.c: AGI Tx >> agi_request: agi://127.0.0.1/enswitch?stype=internal [2011-02-04 14:11:07] VERBOSE[20518] res_agi.c: AGI Tx >> agi_channel: SIP/enswitch-local-0000387d [2011-02-04 14:11:07] VERBOSE[20518] res_agi.c: AGI Tx >> agi_language: en [2011-02-04 14:11:07] VERBOSE[20518] res_agi.c: AGI Tx >> agi_type: SIP [2011-02-04 14:11:07] VERBOSE[20518] res_agi.c: AGI Tx >> agi_uniqueid: 1296846667.38381 [2011-02-04 14:11:07] VERBOSE[20518] res_agi.c: AGI Tx >> agi_version: 1.6.2.13 [2011-02-04 14:11:07] VERBOSE[20518] res_agi.c: AGI Tx >> agi_callerid: 00000001 [2011-02-04 14:11:07] VERBOSE[20518] res_agi.c: AGI Tx >> agi_calleridname: E. Schmid. [2011-02-04 14:11:07] VERBOSE[20518] res_agi.c: AGI Tx >> agi_callingpres: 0 [2011-02-04 14:11:07] VERBOSE[20518] res_agi.c: AGI Tx >> agi_callingani2: 0 [2011-02-04 14:11:07] VERBOSE[20518] res_agi.c: AGI Tx >> agi_callington: 0 [2011-02-04 14:11:07] VERBOSE[20518] res_agi.c: AGI Tx >> agi_callingtns: 0 [2011-02-04 14:11:07] VERBOSE[20518] res_agi.c: AGI Tx >> agi_dnid: 8522222 [2011-02-04 14:11:07] VERBOSE[20518] res_agi.c: AGI Tx >> agi_rdnis: unknown [2011-02-04 14:11:07] VERBOSE[20518] res_agi.c: AGI Tx >> agi_context: from-internal [2011-02-04 14:11:07] VERBOSE[20518] res_agi.c: AGI Tx >> agi_extension: 8522222 [2011-02-04 14:11:07] VERBOSE[20518] res_agi.c: AGI Tx >> agi_priority: 1 [2011-02-04 14:11:07] VERBOSE[20518] res_agi.c: AGI Tx >> agi_enhanced: 0.0 [2011-02-04 14:11:07] VERBOSE[20518] res_agi.c: AGI Tx >> agi_accountcode: [2011-02-04 14:11:07] VERBOSE[20518] res_agi.c: AGI Tx >> agi_threadid: 1110604096 [2011-02-04 14:11:07] VERBOSE[20518] res_agi.c: AGI Tx >> [2011-02-04 14:11:07] VERBOSE[20518] res_agi.c: AGI Rx << VERBOSE "AGI starting at Fri Feb 4 14:11:07 2011 (1296846667)" 9 [2011-02-04 14:11:07] VERBOSE[20518] res_agi.c: AGI Tx >> 200 result=1 [2011-02-04 14:11:07] VERBOSE[20518] res_agi.c: AGI Rx << SET VARIABLE CHANNEL(language) "en" [2011-02-04 14:11:07] VERBOSE[20518] res_agi.c: AGI Tx >> 200 result=1 [2011-02-04 14:11:07] VERBOSE[20518] res_agi.c: AGI Rx << GET VARIABLE ENSWITCH-CALLID [2011-02-04 14:11:07] VERBOSE[20518] res_agi.c: AGI Tx >> 200 result=0 [2011-02-04 14:11:07] VERBOSE[20518] res_agi.c: AGI Rx << SET VARIABLE __ENSWITCH-CALLID "1296846667.38381" [2011-02-04 14:11:07] VERBOSE[20518] res_agi.c: AGI Tx >> 200 result=1 [2011-02-04 14:11:07] VERBOSE[20518] res_agi.c: AGI Rx << GET VARIABLE ENSWITCH-SCREEN [2011-02-04 14:11:07] VERBOSE[20518] res_agi.c: AGI Tx >> 200 result=0 [2011-02-04 14:11:07] VERBOSE[20518] res_agi.c: AGI Rx << GET VARIABLE ENSWITCH-DIVERTED [2011-02-04 14:11:07] VERBOSE[20518] res_agi.c: AGI Tx >> 200 result=0 [2011-02-04 14:11:07] VERBOSE[20518] res_agi.c: AGI Rx << GET VARIABLE ENSWITCH-DIVERSION [2011-02-04 14:11:07] VERBOSE[20518] res_agi.c: AGI Tx >> 200 result=0 [2011-02-04 14:11:07] VERBOSE[20518] res_agi.c: AGI Rx << SET VARIABLE __ENSWITCH-MUSIC "default" [2011-02-04 14:11:07] VERBOSE[20518] res_agi.c: AGI Tx >> 200 result=1 [2011-02-04 14:11:07] VERBOSE[20518] res_agi.c: AGI Rx << SET VARIABLE CHANNEL(musicclass) "default" [2011-02-04 14:11:07] VERBOSE[20518] res_agi.c: AGI Tx >> 200 result=1 [2011-02-04 14:11:07] VERBOSE[20518] res_agi.c: AGI Rx << SET VARIABLE __TRANSFER_CONTEXT "from-internal" [2011-02-04 14:11:07] VERBOSE[20518] res_agi.c: AGI Tx >> 200 result=1 [2011-02-04 14:11:07] VERBOSE[20518] res_agi.c: AGI Rx << SET VARIABLE TIMEOUT(absolute) "86400" [2011-02-04 14:11:07] VERBOSE[20518] res_agi.c: AGI Tx >> 200 result=1 [2011-02-04 14:11:07] VERBOSE[20518] res_agi.c: AGI Rx << GET VARIABLE ENSWITCH-CUTOFF [2011-02-04 14:11:07] VERBOSE[20518] res_agi.c: AGI Tx >> 200 result=0 [2011-02-04 14:11:07] VERBOSE[20518] res_agi.c: AGI Rx << SET VARIABLE TIMEOUT(absolute) "86400" [2011-02-04 14:11:07] VERBOSE[20518] res_agi.c: AGI Tx >> 200 result=1 [2011-02-04 14:11:07] VERBOSE[20518] res_agi.c: AGI Rx << GET VARIABLE ENSWITCH-SCUSTOMER [2011-02-04 14:11:07] VERBOSE[20518] res_agi.c: AGI Tx >> 200 result=0 [2011-02-04 14:11:07] VERBOSE[20518] res_agi.c: AGI Rx << GET VARIABLE SIP_HEADER(X-Enswitch-Source) [2011-02-04 14:11:07] VERBOSE[20518] res_agi.c: AGI Tx >> 200 result=1 (72.237.213.162:1029) [2011-02-04 14:11:07] VERBOSE[20518] res_agi.c: AGI Rx << GET VARIABLE ENSWITCH-PRESENTATION [2011-02-04 14:11:07] VERBOSE[20518] res_agi.c: AGI Tx >> 200 result=0 [2011-02-04 14:11:07] VERBOSE[20518] res_agi.c: AGI Rx << GET VARIABLE SIP_HEADER(X-Enswitch-External) [2011-02-04 14:11:07] VERBOSE[20518] res_agi.c: AGI Tx >> 200 result=0 [2011-02-04 14:11:07] VERBOSE[20518] res_agi.c: AGI Rx << GET VARIABLE ENSWITCH-TRANSFER-PHONE [2011-02-04 14:11:07] VERBOSE[20518] res_agi.c: AGI Tx >> 200 result=0 [2011-02-04 14:11:07] VERBOSE[20518] res_agi.c: AGI Rx << GET VARIABLE ENSWITCH-TRANSFER-CUSTOMER [2011-02-04 14:11:07] VERBOSE[20518] res_agi.c: AGI Tx >> 200 result=0 [2011-02-04 14:11:07] VERBOSE[20518] res_agi.c: AGI Rx << GET VARIABLE SIP_HEADER(Proxy-Authorization) [2011-02-04 14:11:07] VERBOSE[20518] res_agi.c: AGI Tx >> 200 result=1 (Digest username="00000001", realm="betaprov.i-evolve.com", nonce="4d4c4f6900002f837ad0e560c465478c27e1ed889810d79c", uri="sip:8522222@betaprov.i-evolve.com;user=phone", response="2cb65275062833aadf0d8e74fa366642", algorithm=MD5) [2011-02-04 14:11:07] VERBOSE[20518] res_agi.c: AGI Rx << SET VARIABLE CALLERID(name) "E. Schmid." [2011-02-04 14:11:07] VERBOSE[20518] res_agi.c: AGI Tx >> 200 result=1 [2011-02-04 14:11:07] VERBOSE[20518] res_agi.c: AGI Rx << SET VARIABLE CHANNEL(language) "en" [2011-02-04 14:11:07] VERBOSE[20518] res_agi.c: AGI Tx >> 200 result=1 [2011-02-04 14:11:07] VERBOSE[20518] res_agi.c: AGI Rx << VERBOSE "Resolving type '', number '8522222', old '', forward 1 for channel SIP/enswitch-local-0000387d" 5 [2011-02-04 14:11:07] VERBOSE[20518] res_agi.c: AGI Tx >> 200 result=1 [2011-02-04 14:11:07] VERBOSE[20518] res_agi.c: AGI Rx << VERBOSE "Resolving type 'external', number '8522222', old '', forward 2 for channel SIP/enswitch-local-0000387d" 5 [2011-02-04 14:11:07] VERBOSE[20518] res_agi.c: AGI Tx >> 200 result=1 [2011-02-04 14:11:07] VERBOSE[20518] res_agi.c: AGI Rx << VERBOSE "Customer 3 has 86398 outbound seconds available." 9 [2011-02-04 14:11:07] VERBOSE[20518] res_agi.c: AGI Tx >> 200 result=1 [2011-02-04 14:11:07] VERBOSE[20518] res_agi.c: AGI Rx << SET VARIABLE TIMEOUT(absolute) "86398" [2011-02-04 14:11:07] VERBOSE[20518] res_agi.c: AGI Tx >> 200 result=1 [2011-02-04 14:11:07] VERBOSE[20518] res_agi.c: AGI Rx << VERBOSE "Starting recording group '1' expires '1299438667' file '/var/lib/enswitch/recordings/betapbx/738/381/record_129684666738381_20328'" 9 [2011-02-04 14:11:07] VERBOSE[20518] res_agi.c: AGI Tx >> 200 result=1 [2011-02-04 14:11:07] VERBOSE[20518] res_agi.c: AGI Rx << EXEC Monitor "gsm,/var/lib/enswitch/recordings/betapbx/738/381/record_129684666738381_20328" [2011-02-04 14:11:07] VERBOSE[20518] res_agi.c: AGI Tx >> 200 result=0 [2011-02-04 14:11:07] VERBOSE[20518] res_agi.c: AGI Rx << VERBOSE "Writing entry to active table for 1296846667.38381, phone:00000001 - external:8522222." 9 [2011-02-04 14:11:07] VERBOSE[20518] res_agi.c: AGI Tx >> 200 result=1 [2011-02-04 14:11:07] VERBOSE[20518] res_agi.c: AGI Rx << GET VARIABLE SIP_HEADER(Call-ID) [2011-02-04 14:11:07] VERBOSE[20518] res_agi.c: AGI Tx >> 200 result=1 (b2a67e60-f63e0c21-5989e5c6@192.168.16.155) [2011-02-04 14:11:07] VERBOSE[20518] res_agi.c: AGI Rx << SET VARIABLE TIMEOUT(absolute) "86398" [2011-02-04 14:11:07] VERBOSE[20518] res_agi.c: AGI Tx >> 200 result=1 [2011-02-04 14:11:07] VERBOSE[20518] res_agi.c: AGI Rx << SET VARIABLE __ENSWITCH-DCUSTOMER "3" [2011-02-04 14:11:07] VERBOSE[20518] res_agi.c: AGI Tx >> 200 result=1 [2011-02-04 14:11:07] VERBOSE[20518] res_agi.c: AGI Rx << SET VARIABLE __ENSWITCH-DTYPE "external" [2011-02-04 14:11:07] VERBOSE[20518] res_agi.c: AGI Tx >> 200 result=1 [2011-02-04 14:11:07] VERBOSE[20518] res_agi.c: AGI Rx << SET VARIABLE __ENSWITCH-DNUMBER "8522222" [2011-02-04 14:11:07] VERBOSE[20518] res_agi.c: AGI Tx >> 200 result=1 [2011-02-04 14:11:07] VERBOSE[20518] res_agi.c: AGI Rx << SET VARIABLE __ENSWITCH-TRANSFER-CUSTOMER "3" [2011-02-04 14:11:07] VERBOSE[20518] res_agi.c: AGI Tx >> 200 result=1 [2011-02-04 14:11:07] VERBOSE[20518] res_agi.c: AGI Rx << SET VARIABLE __ENSWITCH-PRESENTATION "1" [2011-02-04 14:11:07] VERBOSE[20518] res_agi.c: AGI Tx >> 200 result=1 [2011-02-04 14:11:07] VERBOSE[20518] res_agi.c: AGI Rx << SET VARIABLE __ENSWITCH-CUTOFF "86398" [2011-02-04 14:11:07] VERBOSE[20518] res_agi.c: AGI Tx >> 200 result=1 [2011-02-04 14:11:07] VERBOSE[20518] res_agi.c: AGI Rx << SET VARIABLE __ENSWITCH-UNIQUEID "1296846667.38381" [2011-02-04 14:11:07] VERBOSE[20518] res_agi.c: AGI Tx >> 200 result=1 [2011-02-04 14:11:07] VERBOSE[20518] res_agi.c: AGI Rx << SET VARIABLE ENSWITCH-SCREENED "0" [2011-02-04 14:11:07] VERBOSE[20518] res_agi.c: AGI Tx >> 200 result=1 [2011-02-04 14:11:07] VERBOSE[20518] res_agi.c: AGI Rx << SET VARIABLE CALLERPRES() "allowed_passed_screen" [2011-02-04 14:11:07] VERBOSE[20518] res_agi.c: AGI Tx >> 200 result=1 [2011-02-04 14:11:07] VERBOSE[20518] res_agi.c: AGI Rx << SET VARIABLE CALLERID(number) "9165555555" [2011-02-04 14:11:07] VERBOSE[20518] res_agi.c: AGI Tx >> 200 result=1 [2011-02-04 14:11:07] VERBOSE[20518] res_agi.c: AGI Rx << EXEC Dial "SIP/8522222@2-XXX.XXX.XXX10,600,oL(86398000:60000)" [2011-02-04 14:11:07] VERBOSE[20518] chan_sip.c: Audio is at XXX.XXX.XXX11 port 11382 [2011-02-04 14:11:07] VERBOSE[20518] chan_sip.c: Adding codec 0x1000 (g722) to SDP [2011-02-04 14:11:07] VERBOSE[20518] chan_sip.c: Adding codec 0x4 (ulaw) to SDP [2011-02-04 14:11:07] VERBOSE[20518] chan_sip.c: Adding non-codec 0x1 (telephone-event) to SDP [2011-02-04 14:11:07] VERBOSE[20518] chan_sip.c: Reliably Transmitting (no NAT) to XXX.XXX.XXX10:5060: INVITE sip:8522222@XXX.XXX.XXX10:5060 SIP/2.0 Via: SIP/2.0/UDP XXX.XXX.XXX11:5060;branch=z9hG4bK6a6c21b7;rport Max-Forwards: 70 From: "E. Schmid." ;tag=as6f4e9a9b To: Contact: Call-ID: 08bd259e1b280bd937714f0e7c85b7d9@XXX.XXX.XXX11 CSeq: 102 INVITE User-Agent: i-Communicate Date: Fri, 04 Feb 2011 19:11:07 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO Supported: replaces, timer Content-Type: application/sdp Content-Length: 257 v=0 o=root 500356946 500356946 IN IP4 XXX.XXX.XXX11 s=Asterisk PBX 1.6.2.13 c=IN IP4 XXX.XXX.XXX11 t=0 0 m=audio 11382 RTP/AVP 9 0 101 a=rtpmap:9 G722/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 a=sendrecv --- [2011-02-04 14:11:07] VERBOSE[17753] chan_sip.c: <--- SIP read from UDP:XXX.XXX.XXX10:5060 ---> SIP/2.0 100 Giving a try Via: SIP/2.0/UDP XXX.XXX.XXX11:5060;branch=z9hG4bK6a6c21b7;rport=5060 From: "E. Schmid." ;tag=as6f4e9a9b To: Call-ID: 08bd259e1b280bd937714f0e7c85b7d9@XXX.XXX.XXX11 CSeq: 102 INVITE Server: iCommunicate SIP proxy Content-Length: 0 Warning: 392 XXX.XXX.XXX10:5060 "Noisy feedback tells: pid=25444 req_src_ip=XXX.XXX.XXX11 req_src_port=5060 in_uri=sip:8522222@XXX.XXX.XXX10:5060 out_uri=sip:8522222@XXX.XXX.XXX2:5060;transport=udp via_cnt==1" <-------------> [2011-02-04 14:11:07] VERBOSE[17753] chan_sip.c: --- (9 headers 0 lines) --- [2011-02-04 14:11:08] VERBOSE[17753] chan_sip.c: <--- SIP read from UDP:XXX.XXX.XXX10:5060 ---> SIP/2.0 180 Ringing Via: SIP/2.0/UDP XXX.XXX.XXX11:5060;received=XXX.XXX.XXX11;branch=z9hG4bK6a6c21b7;rport=5060 Record-Route: From: "E. Schmid." ;tag=as6f4e9a9b To: ;tag=as591d2864 Call-ID: 08bd259e1b280bd937714f0e7c85b7d9@XXX.XXX.XXX11 CSeq: 102 INVITE Server: icommunicate Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO Supported: replaces, timer Require: timer Session-Expires: 1800;refresher=uas Contact: Content-Length: 0 <-------------> [2011-02-04 14:11:08] VERBOSE[17753] chan_sip.c: --- (14 headers 0 lines) --- [2011-02-04 14:11:08] VERBOSE[17753] chan_sip.c: <--- SIP read from UDP:XXX.XXX.XXX10:5060 ---> SIP/2.0 183 Session Progress Via: SIP/2.0/UDP XXX.XXX.XXX11:5060;received=XXX.XXX.XXX11;branch=z9hG4bK6a6c21b7;rport=5060 Record-Route: From: "E. Schmid." ;tag=as6f4e9a9b To: ;tag=as591d2864 Call-ID: 08bd259e1b280bd937714f0e7c85b7d9@XXX.XXX.XXX11 CSeq: 102 INVITE Server: icommunicate Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO Supported: replaces, timer Require: timer Session-Expires: 1800;refresher=uas Contact: Content-Type: application/sdp Content-Length: 275 v=0 o=root 251472187 251472187 IN IP4 XXX.XXX.XXX2 s=Asterisk PBX 1.6.1.20 c=IN IP4 XXX.XXX.XXX2 t=0 0 m=audio 15096 RTP/AVP 0 9 101 a=rtpmap:0 PCMU/8000 a=rtpmap:9 G722/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 a=sendrecv a=direction:active <-------------> [2011-02-04 14:11:08] VERBOSE[17753] chan_sip.c: --- (15 headers 13 lines) --- [2011-02-04 14:11:08] VERBOSE[17753] chan_sip.c: Found RTP audio format 0 [2011-02-04 14:11:08] VERBOSE[17753] chan_sip.c: Found RTP audio format 9 [2011-02-04 14:11:08] VERBOSE[17753] chan_sip.c: Found RTP audio format 101 [2011-02-04 14:11:08] VERBOSE[17753] chan_sip.c: Found audio description format PCMU for ID 0 [2011-02-04 14:11:08] VERBOSE[17753] chan_sip.c: Found audio description format G722 for ID 9 [2011-02-04 14:11:08] VERBOSE[17753] chan_sip.c: Found audio description format telephone-event for ID 101 [2011-02-04 14:11:08] VERBOSE[17753] chan_sip.c: Capabilities: us - 0x1004 (ulaw|g722), peer - audio=0x1004 (ulaw|g722)/video=0x0 (nothing)/text=0x0 (nothing), combined - 0x1004 (ulaw|g722) [2011-02-04 14:11:08] VERBOSE[17753] chan_sip.c: Non-codec capabilities (dtmf): us - 0x1 (telephone-event), peer - 0x1 (telephone-event), combined - 0x1 (telephone-event) [2011-02-04 14:11:08] VERBOSE[17753] chan_sip.c: Peer audio RTP is at port XXX.XXX.XXX2:15096 [2011-02-04 14:11:08] VERBOSE[20518] chan_sip.c: <--- Transmitting (NAT) to XXX.XXX.XXX16:5060 ---> SIP/2.0 180 Ringing Via: SIP/2.0/UDP XXX.XXX.XXX16;branch=z9hG4bK003d.80955b67.0;received=XXX.XXX.XXX16 Via: SIP/2.0/UDP 72.237.213.162:1029;branch=z9hG4bKa360525f6513A33C Record-Route: From: "E. Schmid." ;tag=8D086FC4-715FAA95 To: ;tag=as5117f464 Call-ID: b2a67e60-f63e0c21-5989e5c6@192.168.16.155 CSeq: 2 INVITE Server: i-Communicate Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO Supported: replaces, timer Contact: Content-Length: 0 <------------> [2011-02-04 14:11:08] VERBOSE[20518] chan_sip.c: Audio is at XXX.XXX.XXX11 port 12360 [2011-02-04 14:11:08] VERBOSE[20518] chan_sip.c: Adding codec 0x1000 (g722) to SDP [2011-02-04 14:11:08] VERBOSE[20518] chan_sip.c: Adding codec 0x4 (ulaw) to SDP [2011-02-04 14:11:08] VERBOSE[20518] chan_sip.c: Adding non-codec 0x1 (telephone-event) to SDP [2011-02-04 14:11:08] VERBOSE[20518] chan_sip.c: <--- Transmitting (NAT) to XXX.XXX.XXX16:5060 ---> SIP/2.0 183 Session Progress Via: SIP/2.0/UDP XXX.XXX.XXX16;branch=z9hG4bK003d.80955b67.0;received=XXX.XXX.XXX16 Via: SIP/2.0/UDP 72.237.213.162:1029;branch=z9hG4bKa360525f6513A33C Record-Route: From: "E. Schmid." ;tag=8D086FC4-715FAA95 To: ;tag=as5117f464 Call-ID: b2a67e60-f63e0c21-5989e5c6@192.168.16.155 CSeq: 2 INVITE Server: i-Communicate Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO Supported: replaces, timer Contact: Content-Type: application/sdp Content-Length: 259 v=0 o=root 1427844034 1427844034 IN IP4 XXX.XXX.XXX11 s=Asterisk PBX 1.6.2.13 c=IN IP4 XXX.XXX.XXX11 t=0 0 m=audio 12360 RTP/AVP 9 0 127 a=rtpmap:9 G722/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:127 telephone-event/8000 a=fmtp:127 0-16 a=ptime:20 a=sendrecv <------------> [2011-02-04 14:11:11] VERBOSE[17753] chan_sip.c: <--- SIP read from UDP:XXX.XXX.XXX16:47987 ---> OPTIONS sip:XXX.XXX.XXX11:5060 SIP/2.0 Via: SIP/2.0/UDP XXX.XXX.XXX16:47811;branch=z9hG4bK.076109e5;rport;alias From: sip:sipsak@XXX.XXX.XXX16:47811;tag=668edf29 To: sip:XXX.XXX.XXX11:5060 Call-ID: 1720639273@XXX.XXX.XXX16 CSeq: 1 OPTIONS Contact: sip:sipsak@XXX.XXX.XXX16:47811 Content-Length: 0 Max-Forwards: 70 User-Agent: sipsak 0.9.6 Accept: text/plain <-------------> [2011-02-04 14:11:11] VERBOSE[17753] chan_sip.c: --- (11 headers 0 lines) --- [2011-02-04 14:11:11] VERBOSE[17753] chan_sip.c: Looking for s in from-external (domain XXX.XXX.XXX11) [2011-02-04 14:11:11] VERBOSE[17753] chan_sip.c: <--- Transmitting (NAT) to XXX.XXX.XXX16:47987 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP XXX.XXX.XXX16:47811;branch=z9hG4bK.076109e5;alias;received=XXX.XXX.XXX16;rport=47987 From: sip:sipsak@XXX.XXX.XXX16:47811;tag=668edf29 To: sip:XXX.XXX.XXX11:5060;tag=as0462a502 Call-ID: 1720639273@XXX.XXX.XXX16 CSeq: 1 OPTIONS Server: i-Communicate Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO Supported: replaces, timer Contact: Accept: application/sdp Content-Length: 0 <------------> [2011-02-04 14:11:11] VERBOSE[17753] chan_sip.c: Scheduling destruction of SIP dialog '1720639273@XXX.XXX.XXX16' in 32000 ms (Method: OPTIONS) [2011-02-04 14:11:11] VERBOSE[17753] chan_sip.c: <--- SIP read from UDP:XXX.XXX.XXX10:5060 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP XXX.XXX.XXX11:5060;received=XXX.XXX.XXX11;branch=z9hG4bK6a6c21b7;rport=5060 Record-Route: From: "E. Schmid." ;tag=as6f4e9a9b To: ;tag=as591d2864 Call-ID: 08bd259e1b280bd937714f0e7c85b7d9@XXX.XXX.XXX11 CSeq: 102 INVITE Server: icommunicate Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO Supported: replaces, timer Require: timer Session-Expires: 1800;refresher=uas Contact: Content-Type: application/sdp Content-Length: 275 v=0 o=root 251472187 251472188 IN IP4 XXX.XXX.XXX2 s=Asterisk PBX 1.6.1.20 c=IN IP4 XXX.XXX.XXX2 t=0 0 m=audio 15096 RTP/AVP 0 9 101 a=rtpmap:0 PCMU/8000 a=rtpmap:9 G722/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 a=sendrecv a=direction:active <-------------> [2011-02-04 14:11:11] VERBOSE[17753] chan_sip.c: --- (15 headers 13 lines) --- [2011-02-04 14:11:11] VERBOSE[17753] chan_sip.c: Found RTP audio format 0 [2011-02-04 14:11:11] VERBOSE[17753] chan_sip.c: Found RTP audio format 9 [2011-02-04 14:11:11] VERBOSE[17753] chan_sip.c: Found RTP audio format 101 [2011-02-04 14:11:11] VERBOSE[17753] chan_sip.c: Found audio description format PCMU for ID 0 [2011-02-04 14:11:11] VERBOSE[17753] chan_sip.c: Found audio description format G722 for ID 9 [2011-02-04 14:11:11] VERBOSE[17753] chan_sip.c: Found audio description format telephone-event for ID 101 [2011-02-04 14:11:11] VERBOSE[17753] chan_sip.c: Capabilities: us - 0x1004 (ulaw|g722), peer - audio=0x1004 (ulaw|g722)/video=0x0 (nothing)/text=0x0 (nothing), combined - 0x1004 (ulaw|g722) [2011-02-04 14:11:11] VERBOSE[17753] chan_sip.c: Non-codec capabilities (dtmf): us - 0x1 (telephone-event), peer - 0x1 (telephone-event), combined - 0x1 (telephone-event) [2011-02-04 14:11:11] VERBOSE[17753] chan_sip.c: Peer audio RTP is at port XXX.XXX.XXX2:15096 [2011-02-04 14:11:11] VERBOSE[17753] chan_sip.c: list_route: hop: [2011-02-04 14:11:11] VERBOSE[17753] chan_sip.c: set_destination: Parsing for address/port to send to [2011-02-04 14:11:11] VERBOSE[17753] chan_sip.c: set_destination: set destination to XXX.XXX.XXX10, port 5060 [2011-02-04 14:11:11] VERBOSE[17753] chan_sip.c: Transmitting (no NAT) to XXX.XXX.XXX10:5060: ACK sip:8522222@XXX.XXX.XXX2:5060 SIP/2.0 Via: SIP/2.0/UDP XXX.XXX.XXX11:5060;branch=z9hG4bK149a5064;rport Route: Max-Forwards: 70 From: "E. Schmid." ;tag=as6f4e9a9b To: ;tag=as591d2864 Contact: Call-ID: 08bd259e1b280bd937714f0e7c85b7d9@XXX.XXX.XXX11 CSeq: 102 ACK User-Agent: i-Communicate Content-Length: 0 --- [2011-02-04 14:11:11] VERBOSE[20518] chan_sip.c: Audio is at XXX.XXX.XXX11 port 12360 [2011-02-04 14:11:11] VERBOSE[20518] chan_sip.c: Adding codec 0x1000 (g722) to SDP [2011-02-04 14:11:11] VERBOSE[20518] chan_sip.c: Adding codec 0x4 (ulaw) to SDP [2011-02-04 14:11:11] VERBOSE[20518] chan_sip.c: Adding non-codec 0x1 (telephone-event) to SDP [2011-02-04 14:11:11] VERBOSE[20518] chan_sip.c: <--- Reliably Transmitting (NAT) to XXX.XXX.XXX16:5060 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP XXX.XXX.XXX16;branch=z9hG4bK003d.80955b67.0;received=XXX.XXX.XXX16 Via: SIP/2.0/UDP 72.237.213.162:1029;branch=z9hG4bKa360525f6513A33C Record-Route: From: "E. Schmid." ;tag=8D086FC4-715FAA95 To: ;tag=as5117f464 Call-ID: b2a67e60-f63e0c21-5989e5c6@192.168.16.155 CSeq: 2 INVITE Server: i-Communicate Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO Supported: replaces, timer Contact: Content-Type: application/sdp Content-Length: 259 v=0 o=root 1427844034 1427844035 IN IP4 XXX.XXX.XXX11 s=Asterisk PBX 1.6.2.13 c=IN IP4 XXX.XXX.XXX11 t=0 0 m=audio 12360 RTP/AVP 9 0 127 a=rtpmap:9 G722/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:127 telephone-event/8000 a=fmtp:127 0-16 a=ptime:20 a=sendrecv <------------> [2011-02-04 14:11:11] VERBOSE[17753] chan_sip.c: <--- SIP read from UDP:XXX.XXX.XXX16:5060 ---> ACK sip:8522222@XXX.XXX.XXX11:5060 SIP/2.0 Record-Route: Via: SIP/2.0/UDP XXX.XXX.XXX16;branch=z9hG4bK003d.80955b67.2 Via: SIP/2.0/UDP 72.237.213.162:1029;branch=z9hG4bKbbbc374b6C5E7A58 From: "E. Schmid." ;tag=8D086FC4-715FAA95 To: ;tag=as5117f464 Route: CSeq: 2 ACK Call-ID: b2a67e60-f63e0c21-5989e5c6@192.168.16.155 Contact: Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER User-Agent: PolycomSoundPointIP-SPIP_335-UA/3.2.2.0477 Accept-Language: en Max-Forwards: 69 Content-Length: 0 X-Enswitch-RURI: sip:8522222@XXX.XXX.XXX11:5060 X-Enswitch-Source: 72.237.213.162:1029 <-------------> [2011-02-04 14:11:11] VERBOSE[17753] chan_sip.c: --- (17 headers 0 lines) --- [2011-02-04 14:11:13] VERBOSE[17753] chan_sip.c: Really destroying SIP dialog '1569746661@XXX.XXX.XXX16' Method: OPTIONS [2011-02-04 14:11:13] VERBOSE[17753] chan_sip.c: <--- SIP read from UDP:XXX.XXX.XXX16:5060 ---> REFER sip:8165350000@XXX.XXX.XXX11 SIP/2.0 Record-Route: Via: SIP/2.0/UDP XXX.XXX.XXX16;branch=z9hG4bK341d.511a49c1.0 Via: SIP/2.0/UDP 72.237.213.162:1029;branch=z9hG4bKb149cd77BFABDFB4 From: "E. Schmid." ;tag=5F413971-CB112E56 To: "8165350000" ;tag=as1659b3c4 Route: CSeq: 4 REFER Call-ID: 507d9c9d3c25e9c9449e16716a29f4a6@XXX.XXX.XXX16 Contact: User-Agent: PolycomSoundPointIP-SPIP_335-UA/3.2.2.0477 Accept-Language: en Refer-To: Referred-By: Proxy-Authorization: Digest username="00000001", realm="XXX.XXX.XXX16", nonce="4d4c4f6f00002f87dbd2a8f5c4503c167d69f8d5d27070de", uri="sip:8165350000@XXX.XXX.XXX11", response="a9d3def6ea0cd400411eb3587c49f9ee", algorithm=MD5 Max-Forwards: 69 Content-Length: 0 X-Enswitch-RURI: sip:8165350000@XXX.XXX.XXX11 X-Enswitch-Source: 72.237.213.162:1029 <-------------> [2011-02-04 14:11:13] VERBOSE[17753] chan_sip.c: --- (19 headers 0 lines) --- [2011-02-04 14:11:13] VERBOSE[17753] chan_sip.c: Call 507d9c9d3c25e9c9449e16716a29f4a6@XXX.XXX.XXX16 got a SIP call transfer from caller: (REFER)! [2011-02-04 14:11:13] VERBOSE[17753] chan_sip.c: SIP transfer to extension 8522222@from-internal by 00000001@betaprov.i-evolve.com [2011-02-04 14:11:13] VERBOSE[17753] chan_sip.c: <--- Transmitting (NAT) to XXX.XXX.XXX16:5060 ---> SIP/2.0 202 Accepted Via: SIP/2.0/UDP XXX.XXX.XXX16;branch=z9hG4bK341d.511a49c1.0;received=XXX.XXX.XXX16 Via: SIP/2.0/UDP 72.237.213.162:1029;branch=z9hG4bKb149cd77BFABDFB4 Record-Route: From: "E. Schmid." ;tag=5F413971-CB112E56 To: "8165350000" ;tag=as1659b3c4 Call-ID: 507d9c9d3c25e9c9449e16716a29f4a6@XXX.XXX.XXX16 CSeq: 4 REFER Server: i-Communicate Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO Supported: replaces, timer Contact: Content-Length: 0 <------------> [2011-02-04 14:11:13] VERBOSE[17753] chan_sip.c: set_destination: Parsing for address/port to send to [2011-02-04 14:11:13] VERBOSE[17753] chan_sip.c: set_destination: set destination to XXX.XXX.XXX16, port 5060 [2011-02-04 14:11:13] VERBOSE[17753] chan_sip.c: Reliably Transmitting (NAT) to XXX.XXX.XXX16:5060: NOTIFY sip:00000001@72.237.213.162:1029 SIP/2.0 Via: SIP/2.0/UDP XXX.XXX.XXX11:5060;branch=z9hG4bK47fc7ec6;rport Route: Max-Forwards: 70 From: "8165350000" ;tag=as1659b3c4 To: "E. Schmid." ;tag=5F413971-CB112E56 Contact: Call-ID: 507d9c9d3c25e9c9449e16716a29f4a6@XXX.XXX.XXX16 CSeq: 103 NOTIFY User-Agent: i-Communicate Event: refer;id=4 Subscription-state: terminated;reason=noresource Content-Type: message/sipfrag;version=2.0 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO Supported: replaces, timer Content-Length: 16 SIP/2.0 200 OK --- [2011-02-04 14:11:13] VERBOSE[20518] chan_sip.c: Scheduling destruction of SIP dialog 'b2a67e60-f63e0c21-5989e5c6@192.168.16.155' in 32000 ms (Method: ACK) [2011-02-04 14:11:13] VERBOSE[20518] chan_sip.c: set_destination: Parsing for address/port to send to [2011-02-04 14:11:13] VERBOSE[20518] chan_sip.c: set_destination: set destination to XXX.XXX.XXX16, port 5060 [2011-02-04 14:11:13] VERBOSE[20518] chan_sip.c: Reliably Transmitting (NAT) to XXX.XXX.XXX16:5060: BYE sip:00000001@72.237.213.162:1029 SIP/2.0 Via: SIP/2.0/UDP XXX.XXX.XXX11:5060;branch=z9hG4bK1b4e80b4;rport Route: Max-Forwards: 70 From: ;tag=as5117f464 To: "E. Schmid." ;tag=8D086FC4-715FAA95 Call-ID: b2a67e60-f63e0c21-5989e5c6@192.168.16.155 CSeq: 102 BYE User-Agent: i-Communicate X-Asterisk-HangupCause: Normal Clearing X-Asterisk-HangupCauseCode: 16 Content-Length: 0 --- [2011-02-04 14:11:13] VERBOSE[20464] chan_sip.c: Scheduling destruction of SIP dialog '507d9c9d3c25e9c9449e16716a29f4a6@XXX.XXX.XXX16' in 32000 ms (Method: REFER) [2011-02-04 14:11:13] VERBOSE[17753] chan_sip.c: <--- SIP read from UDP:XXX.XXX.XXX16:5060 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP XXX.XXX.XXX11:5060;received=XXX.XXX.XXX11;branch=z9hG4bK47fc7ec6;rport=5060 From: "8165350000" ;tag=as1659b3c4 To: "E. Schmid." ;tag=5F413971-CB112E56 CSeq: 103 NOTIFY Call-ID: 507d9c9d3c25e9c9449e16716a29f4a6@XXX.XXX.XXX16 Contact: Record-Route: Event: refer;id=4 User-Agent: PolycomSoundPointIP-SPIP_335-UA/3.2.2.0477 Accept-Language: en Content-Length: 0 <-------------> [2011-02-04 14:11:13] VERBOSE[17753] chan_sip.c: --- (12 headers 0 lines) --- [2011-02-04 14:11:13] VERBOSE[17753] chan_sip.c: SIP Response message for INCOMING dialog NOTIFY arrived [2011-02-04 14:11:13] VERBOSE[17753] chan_sip.c: <--- SIP read from UDP:XXX.XXX.XXX16:5060 ---> BYE sip:8165350000@XXX.XXX.XXX11 SIP/2.0 Record-Route: Via: SIP/2.0/UDP XXX.XXX.XXX16;branch=z9hG4bK441d.6871ba13.0 Via: SIP/2.0/UDP 72.237.213.162:1029;branch=z9hG4bK81820c454D53B5FA From: "E. Schmid." ;tag=5F413971-CB112E56 To: "8165350000" ;tag=as1659b3c4 Route: CSeq: 5 BYE Call-ID: 507d9c9d3c25e9c9449e16716a29f4a6@XXX.XXX.XXX16 Contact: User-Agent: PolycomSoundPointIP-SPIP_335-UA/3.2.2.0477 Accept-Language: en Proxy-Authorization: Digest username="00000001", realm="XXX.XXX.XXX16", nonce="4d4c4f6f00002f87dbd2a8f5c4503c167d69f8d5d27070de", uri="sip:8165350000@XXX.XXX.XXX11", response="33987bd4dbea68164f21ff90f5a26f23", algorithm=MD5 Max-Forwards: 69 Content-Length: 0 X-Enswitch-RURI: sip:8165350000@XXX.XXX.XXX11 X-Enswitch-Source: 72.237.213.162:1029 <-------------> [2011-02-04 14:11:13] VERBOSE[17753] chan_sip.c: --- (17 headers 0 lines) --- [2011-02-04 14:11:13] VERBOSE[17753] chan_sip.c: Sending to XXX.XXX.XXX16 : 5060 (NAT) [2011-02-04 14:11:13] VERBOSE[17753] chan_sip.c: Scheduling destruction of SIP dialog '507d9c9d3c25e9c9449e16716a29f4a6@XXX.XXX.XXX16' in 32000 ms (Method: BYE) [2011-02-04 14:11:13] VERBOSE[17753] chan_sip.c: <--- Transmitting (NAT) to XXX.XXX.XXX16:5060 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP XXX.XXX.XXX16;branch=z9hG4bK441d.6871ba13.0;received=XXX.XXX.XXX16 Via: SIP/2.0/UDP 72.237.213.162:1029;branch=z9hG4bK81820c454D53B5FA Record-Route: From: "E. Schmid." ;tag=5F413971-CB112E56 To: "8165350000" ;tag=as1659b3c4 Call-ID: 507d9c9d3c25e9c9449e16716a29f4a6@XXX.XXX.XXX16 CSeq: 5 BYE Server: i-Communicate Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO Supported: replaces, timer Content-Length: 0 <------------> [2011-02-04 14:11:13] VERBOSE[17753] chan_sip.c: <--- SIP read from UDP:XXX.XXX.XXX16:5060 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP XXX.XXX.XXX11:5060;received=XXX.XXX.XXX11;branch=z9hG4bK1b4e80b4;rport=5060 From: ;tag=as5117f464 To: "E. Schmid." ;tag=8D086FC4-715FAA95 CSeq: 102 BYE Call-ID: b2a67e60-f63e0c21-5989e5c6@192.168.16.155 Contact: Record-Route: User-Agent: PolycomSoundPointIP-SPIP_335-UA/3.2.2.0477 Accept-Language: en Content-Length: 0 <-------------> [2011-02-04 14:11:13] VERBOSE[17753] chan_sip.c: --- (11 headers 0 lines) --- [2011-02-04 14:11:13] VERBOSE[17753] chan_sip.c: SIP Response message for INCOMING dialog BYE arrived [2011-02-04 14:11:13] VERBOSE[17753] chan_sip.c: Really destroying SIP dialog 'b2a67e60-f63e0c21-5989e5c6@192.168.16.155' Method: ACK [2011-02-04 14:11:21] VERBOSE[17753] chan_sip.c: <--- SIP read from UDP:XXX.XXX.XXX16:51584 ---> OPTIONS sip:XXX.XXX.XXX11:5060 SIP/2.0 Via: SIP/2.0/UDP XXX.XXX.XXX16:48985;branch=z9hG4bK.79701eb4;rport;alias From: sip:sipsak@XXX.XXX.XXX16:48985;tag=59330677 To: sip:XXX.XXX.XXX11:5060 Call-ID: 1496516215@XXX.XXX.XXX16 CSeq: 1 OPTIONS Contact: sip:sipsak@XXX.XXX.XXX16:48985 Content-Length: 0 Max-Forwards: 70 User-Agent: sipsak 0.9.6 Accept: text/plain <-------------> [2011-02-04 14:11:21] VERBOSE[17753] chan_sip.c: --- (11 headers 0 lines) --- [2011-02-04 14:11:21] VERBOSE[17753] chan_sip.c: Looking for s in from-external (domain XXX.XXX.XXX11) [2011-02-04 14:11:21] VERBOSE[17753] chan_sip.c: <--- Transmitting (NAT) to XXX.XXX.XXX16:51584 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP XXX.XXX.XXX16:48985;branch=z9hG4bK.79701eb4;alias;received=XXX.XXX.XXX16;rport=51584 From: sip:sipsak@XXX.XXX.XXX16:48985;tag=59330677 To: sip:XXX.XXX.XXX11:5060;tag=as0b1f783d Call-ID: 1496516215@XXX.XXX.XXX16 CSeq: 1 OPTIONS Server: i-Communicate Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO Supported: replaces, timer Contact: Accept: application/sdp Content-Length: 0 <------------> [2011-02-04 14:11:21] VERBOSE[17753] chan_sip.c: Scheduling destruction of SIP dialog '1496516215@XXX.XXX.XXX16' in 32000 ms (Method: OPTIONS) [2011-02-04 14:11:21] VERBOSE[17753] chan_sip.c: <--- SIP read from UDP:208.94.157.10:5060 ---> INVITE sip:19164444444@XXX.XXX.XXX11:5060;transport=udp SIP/2.0 From: ;tag=a9d5ed0-13c4-4d4c4fd0-afe8509-3264b01d To: Call-ID: CXC-505-6b0320d0-a9d5ed0-13c4-4d4c4fd0-afe8509-1647c2c7@208.94.157.10 CSeq: 1 INVITE Via: SIP/2.0/UDP 208.94.157.10:5060;branch=z9hG4bK-88df0-4d4c4fd0-afe8509-35f60292 Max-Forwards: 68 P-Asserted-Identity: Supported: 100rel Content-Disposition: session;handling=required Contact: Min-SE: 900 Session-Expires: 1800 Content-Type: application/sdp Content-Length: 238 v=0 o=Acme_UAS 0 1 IN IP4 208.94.157.10 s=SIP Media Capabilities c=IN IP4 208.94.157.10 t=0 0 m=audio 24720 RTP/AVP 0 18 101 a=rtpmap:0 PCMU/8000 a=rtpmap:18 G729/8000 a=rtpmap:101 telephone-event/8000 a=maxptime:20 a=sendrecv <-------------> [2011-02-04 14:11:21] VERBOSE[17753] chan_sip.c: --- (15 headers 11 lines) --- [2011-02-04 14:11:21] VERBOSE[17753] chan_sip.c: Sending to 208.94.157.10 : 5060 (NAT) [2011-02-04 14:11:21] VERBOSE[17753] chan_sip.c: Using INVITE request as basis request - CXC-505-6b0320d0-a9d5ed0-13c4-4d4c4fd0-afe8509-1647c2c7@208.94.157.10 [2011-02-04 14:11:21] VERBOSE[17753] chan_sip.c: Found peer '8-208.94.157.10' for '7169074915' from 208.94.157.10:5060 [2011-02-04 14:11:21] VERBOSE[17753] chan_sip.c: Found RTP audio format 0 [2011-02-04 14:11:21] VERBOSE[17753] chan_sip.c: Found RTP audio format 18 [2011-02-04 14:11:21] VERBOSE[17753] chan_sip.c: Found RTP audio format 101 [2011-02-04 14:11:21] VERBOSE[17753] chan_sip.c: Found audio description format PCMU for ID 0 [2011-02-04 14:11:21] VERBOSE[17753] chan_sip.c: Found audio description format G729 for ID 18 [2011-02-04 14:11:21] VERBOSE[17753] chan_sip.c: Found audio description format telephone-event for ID 101 [2011-02-04 14:11:21] VERBOSE[17753] chan_sip.c: Capabilities: us - 0x1004 (ulaw|g722), peer - audio=0x104 (ulaw|g729)/video=0x0 (nothing)/text=0x0 (nothing), combined - 0x4 (ulaw) [2011-02-04 14:11:21] VERBOSE[17753] chan_sip.c: Non-codec capabilities (dtmf): us - 0x1 (telephone-event), peer - 0x1 (telephone-event), combined - 0x1 (telephone-event) [2011-02-04 14:11:21] VERBOSE[17753] chan_sip.c: Peer audio RTP is at port 208.94.157.10:24720 [2011-02-04 14:11:21] VERBOSE[17753] chan_sip.c: Looking for 19164444444 in from-external (domain XXX.XXX.XXX11) [2011-02-04 14:11:21] VERBOSE[17753] chan_sip.c: list_route: hop: [2011-02-04 14:11:21] VERBOSE[17753] chan_sip.c: <--- Transmitting (no NAT) to 208.94.157.10:5060 ---> SIP/2.0 100 Trying Via: SIP/2.0/UDP 208.94.157.10:5060;branch=z9hG4bK-88df0-4d4c4fd0-afe8509-35f60292;received=208.94.157.10 From: ;tag=a9d5ed0-13c4-4d4c4fd0-afe8509-3264b01d To: Call-ID: CXC-505-6b0320d0-a9d5ed0-13c4-4d4c4fd0-afe8509-1647c2c7@208.94.157.10 CSeq: 1 INVITE Server: i-Communicate Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO Supported: replaces, timer Contact: Content-Length: 0 <------------> [2011-02-04 14:11:21] VERBOSE[20533] res_agi.c: AGI Tx >> agi_network: yes [2011-02-04 14:11:21] VERBOSE[20533] res_agi.c: AGI Tx >> agi_network_script: enswitch?stype=external&cnumber=9164444444 [2011-02-04 14:11:21] VERBOSE[20533] res_agi.c: AGI Tx >> agi_request: agi://127.0.0.1/enswitch?stype=external&cnumber=9164444444 [2011-02-04 14:11:21] VERBOSE[20533] res_agi.c: AGI Tx >> agi_channel: SIP/8-208.94.157.10-0000387f [2011-02-04 14:11:21] VERBOSE[20533] res_agi.c: AGI Tx >> agi_language: en [2011-02-04 14:11:21] VERBOSE[20533] res_agi.c: AGI Tx >> agi_type: SIP [2011-02-04 14:11:21] VERBOSE[20533] res_agi.c: AGI Tx >> agi_uniqueid: 1296846681.38383 [2011-02-04 14:11:21] VERBOSE[20533] res_agi.c: AGI Tx >> agi_version: 1.6.2.13 [2011-02-04 14:11:21] VERBOSE[20533] res_agi.c: AGI Tx >> agi_callerid: 7169074915 [2011-02-04 14:11:21] VERBOSE[20533] res_agi.c: AGI Tx >> agi_calleridname: unknown [2011-02-04 14:11:21] VERBOSE[20533] res_agi.c: AGI Tx >> agi_callingpres: 0 [2011-02-04 14:11:21] VERBOSE[20533] res_agi.c: AGI Tx >> agi_callingani2: 0 [2011-02-04 14:11:21] VERBOSE[20533] res_agi.c: AGI Tx >> agi_callington: 0 [2011-02-04 14:11:21] VERBOSE[20533] res_agi.c: AGI Tx >> agi_callingtns: 0 [2011-02-04 14:11:21] VERBOSE[20533] res_agi.c: AGI Tx >> agi_dnid: 19164444444 [2011-02-04 14:11:21] VERBOSE[20533] res_agi.c: AGI Tx >> agi_rdnis: unknown [2011-02-04 14:11:21] VERBOSE[20533] res_agi.c: AGI Tx >> agi_context: from-external [2011-02-04 14:11:21] VERBOSE[20533] res_agi.c: AGI Tx >> agi_extension: 19164444444 [2011-02-04 14:11:21] VERBOSE[20533] res_agi.c: AGI Tx >> agi_priority: 1 [2011-02-04 14:11:21] VERBOSE[20533] res_agi.c: AGI Tx >> agi_enhanced: 0.0 [2011-02-04 14:11:21] VERBOSE[20533] res_agi.c: AGI Tx >> agi_accountcode: [2011-02-04 14:11:21] VERBOSE[20533] res_agi.c: AGI Tx >> agi_threadid: 1107556672 [2011-02-04 14:11:21] VERBOSE[20533] res_agi.c: AGI Tx >> [2011-02-04 14:11:21] VERBOSE[20533] res_agi.c: AGI Rx << VERBOSE "AGI starting at Fri Feb 4 14:11:21 2011 (1296846681)" 9 [2011-02-04 14:11:21] VERBOSE[20533] res_agi.c: AGI Tx >> 200 result=1 [2011-02-04 14:11:21] VERBOSE[20533] res_agi.c: AGI Rx << SET VARIABLE CHANNEL(language) "en" [2011-02-04 14:11:21] VERBOSE[20533] res_agi.c: AGI Tx >> 200 result=1 [2011-02-04 14:11:21] VERBOSE[20533] res_agi.c: AGI Rx << GET VARIABLE ENSWITCH-CALLID [2011-02-04 14:11:21] VERBOSE[20533] res_agi.c: AGI Tx >> 200 result=0 [2011-02-04 14:11:21] VERBOSE[20533] res_agi.c: AGI Rx << SET VARIABLE __ENSWITCH-CALLID "1296846681.38383" [2011-02-04 14:11:21] VERBOSE[20533] res_agi.c: AGI Tx >> 200 result=1 [2011-02-04 14:11:21] VERBOSE[20533] res_agi.c: AGI Rx << GET VARIABLE ENSWITCH-SCREEN [2011-02-04 14:11:21] VERBOSE[20533] res_agi.c: AGI Tx >> 200 result=0 [2011-02-04 14:11:21] VERBOSE[20533] res_agi.c: AGI Rx << GET VARIABLE ENSWITCH-DIVERTED [2011-02-04 14:11:21] VERBOSE[20533] res_agi.c: AGI Tx >> 200 result=0 [2011-02-04 14:11:21] VERBOSE[20533] res_agi.c: AGI Rx << GET VARIABLE ENSWITCH-DIVERSION [2011-02-04 14:11:21] VERBOSE[20533] res_agi.c: AGI Tx >> 200 result=0 [2011-02-04 14:11:21] VERBOSE[20533] res_agi.c: AGI Rx << SET VARIABLE __ENSWITCH-MUSIC "default" [2011-02-04 14:11:21] VERBOSE[20533] res_agi.c: AGI Tx >> 200 result=1 [2011-02-04 14:11:21] VERBOSE[20533] res_agi.c: AGI Rx << SET VARIABLE CHANNEL(musicclass) "default" [2011-02-04 14:11:21] VERBOSE[20533] res_agi.c: AGI Tx >> 200 result=1 [2011-02-04 14:11:21] VERBOSE[20533] res_agi.c: AGI Rx << SET VARIABLE __TRANSFER_CONTEXT "from-internal" [2011-02-04 14:11:21] VERBOSE[20533] res_agi.c: AGI Tx >> 200 result=1 [2011-02-04 14:11:21] VERBOSE[20533] res_agi.c: AGI Rx << SET VARIABLE TIMEOUT(absolute) "86400" [2011-02-04 14:11:21] VERBOSE[20533] res_agi.c: AGI Tx >> 200 result=1 [2011-02-04 14:11:21] VERBOSE[20533] res_agi.c: AGI Rx << GET VARIABLE ENSWITCH-CUTOFF [2011-02-04 14:11:21] VERBOSE[20533] res_agi.c: AGI Tx >> 200 result=0 [2011-02-04 14:11:21] VERBOSE[20533] res_agi.c: AGI Rx << SET VARIABLE TIMEOUT(absolute) "86400" [2011-02-04 14:11:21] VERBOSE[20533] res_agi.c: AGI Tx >> 200 result=1 [2011-02-04 14:11:21] VERBOSE[20533] res_agi.c: AGI Rx << GET VARIABLE ENSWITCH-SCUSTOMER [2011-02-04 14:11:21] VERBOSE[20533] res_agi.c: AGI Tx >> 200 result=0 [2011-02-04 14:11:21] VERBOSE[20533] res_agi.c: AGI Rx << GET VARIABLE SIPCHANINFO(recvip) [2011-02-04 14:11:21] VERBOSE[20533] res_agi.c: AGI Tx >> 200 result=1 (208.94.157.10) [2011-02-04 14:11:21] VERBOSE[20533] res_agi.c: AGI Rx << GET VARIABLE ENSWITCH-PRESENTATION [2011-02-04 14:11:21] VERBOSE[20533] res_agi.c: AGI Tx >> 200 result=0 [2011-02-04 14:11:21] VERBOSE[20533] res_agi.c: AGI Rx << GET VARIABLE ENSWITCH-TRANSFER-PHONE [2011-02-04 14:11:21] VERBOSE[20533] res_agi.c: AGI Tx >> 200 result=0 [2011-02-04 14:11:21] VERBOSE[20533] res_agi.c: AGI Rx << VERBOSE "Resolving type '', number '9164444444', old '', forward 1 for channel SIP/8-208.94.157.10-0000387f" 5 [2011-02-04 14:11:21] VERBOSE[20533] res_agi.c: AGI Tx >> 200 result=1 [2011-02-04 14:11:21] VERBOSE[20533] res_agi.c: AGI Rx << SET VARIABLE __ENSWITCH-DIVERSION "9164444444" [2011-02-04 14:11:21] VERBOSE[20533] res_agi.c: AGI Tx >> 200 result=1 [2011-02-04 14:11:21] VERBOSE[20533] res_agi.c: AGI Rx << SET VARIABLE CHANNEL(language) "en" [2011-02-04 14:11:21] VERBOSE[20533] res_agi.c: AGI Tx >> 200 result=1 [2011-02-04 14:11:21] VERBOSE[20533] res_agi.c: AGI Rx << VERBOSE "Starting recording group '3' expires '1297710681' file '/var/lib/enswitch/recordings/betapbx/138/383/record_129684668138383_20401'" 9 [2011-02-04 14:11:21] VERBOSE[20533] res_agi.c: AGI Tx >> 200 result=1 [2011-02-04 14:11:21] VERBOSE[20533] res_agi.c: AGI Rx << EXEC Monitor "gsm,/var/lib/enswitch/recordings/betapbx/138/383/record_129684668138383_20401" [2011-02-04 14:11:21] VERBOSE[20533] res_agi.c: AGI Tx >> 200 result=0 [2011-02-04 14:11:21] VERBOSE[20533] res_agi.c: AGI Rx << VERBOSE "Resolving type 'queue', number '2', old '', forward 2 for channel SIP/8-208.94.157.10-0000387f" 5 [2011-02-04 14:11:21] VERBOSE[20533] res_agi.c: AGI Tx >> 200 result=1 [2011-02-04 14:11:22] VERBOSE[20533] res_agi.c: AGI Rx << VERBOSE "Customer 3 has 86398 inbound seconds available." 9 [2011-02-04 14:11:22] VERBOSE[20533] res_agi.c: AGI Tx >> 200 result=1 [2011-02-04 14:11:22] VERBOSE[20533] res_agi.c: AGI Rx << SET VARIABLE TIMEOUT(absolute) "86398" [2011-02-04 14:11:22] VERBOSE[20533] res_agi.c: AGI Tx >> 200 result=1 [2011-02-04 14:11:22] VERBOSE[20533] res_agi.c: AGI Rx << VERBOSE "Writing entry to active table for 1296846681.38383, external:7169074915 - queue:2." 9 [2011-02-04 14:11:22] VERBOSE[20533] res_agi.c: AGI Tx >> 200 result=1 [2011-02-04 14:11:22] VERBOSE[20533] res_agi.c: AGI Rx << GET VARIABLE SIP_HEADER(Call-ID) [2011-02-04 14:11:22] VERBOSE[20533] res_agi.c: AGI Tx >> 200 result=1 (CXC-505-6b0320d0-a9d5ed0-13c4-4d4c4fd0-afe8509-1647c2c7@208.94.157.10) [2011-02-04 14:11:22] VERBOSE[20533] res_agi.c: AGI Rx << SET VARIABLE TIMEOUT(absolute) "86398" [2011-02-04 14:11:22] VERBOSE[20533] res_agi.c: AGI Tx >> 200 result=1 [2011-02-04 14:11:22] VERBOSE[20533] res_agi.c: AGI Rx << SET VARIABLE __ENSWITCH-DCUSTOMER "3" [2011-02-04 14:11:22] VERBOSE[20533] res_agi.c: AGI Tx >> 200 result=1 [2011-02-04 14:11:22] VERBOSE[20533] res_agi.c: AGI Rx << SET VARIABLE __ENSWITCH-DTYPE "queue" [2011-02-04 14:11:22] VERBOSE[20533] res_agi.c: AGI Tx >> 200 result=1 [2011-02-04 14:11:22] VERBOSE[20533] res_agi.c: AGI Rx << SET VARIABLE __ENSWITCH-DNUMBER "2" [2011-02-04 14:11:22] VERBOSE[20533] res_agi.c: AGI Tx >> 200 result=1 [2011-02-04 14:11:22] VERBOSE[20533] res_agi.c: AGI Rx << SET VARIABLE __LIMIT_PLAYAUDIO_CALLER "no" [2011-02-04 14:11:22] VERBOSE[20533] res_agi.c: AGI Tx >> 200 result=1 [2011-02-04 14:11:22] VERBOSE[20533] res_agi.c: AGI Rx << SET VARIABLE __LIMIT_PLAYAUDIO_CALLEE "yes" [2011-02-04 14:11:22] VERBOSE[20533] res_agi.c: AGI Tx >> 200 result=1 [2011-02-04 14:11:22] VERBOSE[20533] res_agi.c: AGI Rx << SET VARIABLE CALLERID(number) "7169074915" [2011-02-04 14:11:22] VERBOSE[20533] res_agi.c: AGI Tx >> 200 result=1 [2011-02-04 14:11:22] VERBOSE[20533] res_agi.c: AGI Rx << SET VARIABLE __ENSWITCH-TRANSFER-CUSTOMER "3" [2011-02-04 14:11:22] VERBOSE[20533] res_agi.c: AGI Tx >> 200 result=1 [2011-02-04 14:11:22] VERBOSE[20533] res_agi.c: AGI Rx << SET VARIABLE __ENSWITCH-PRESENTATION "3" [2011-02-04 14:11:22] VERBOSE[20533] res_agi.c: AGI Tx >> 200 result=1 [2011-02-04 14:11:22] VERBOSE[20533] res_agi.c: AGI Rx << SET VARIABLE __ENSWITCH-CUTOFF "86398" [2011-02-04 14:11:22] VERBOSE[20533] res_agi.c: AGI Tx >> 200 result=1 [2011-02-04 14:11:22] VERBOSE[20533] res_agi.c: AGI Rx << EXEC Answer "" [2011-02-04 14:11:22] VERBOSE[20533] chan_sip.c: Audio is at XXX.XXX.XXX11 port 14174 [2011-02-04 14:11:22] VERBOSE[20533] chan_sip.c: Adding codec 0x4 (ulaw) to SDP [2011-02-04 14:11:22] VERBOSE[20533] chan_sip.c: Adding non-codec 0x1 (telephone-event) to SDP [2011-02-04 14:11:22] VERBOSE[20533] chan_sip.c: <--- Reliably Transmitting (no NAT) to 208.94.157.10:5060 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 208.94.157.10:5060;branch=z9hG4bK-88df0-4d4c4fd0-afe8509-35f60292;received=208.94.157.10 From: ;tag=a9d5ed0-13c4-4d4c4fd0-afe8509-3264b01d To: ;tag=as32e5ad28 Call-ID: CXC-505-6b0320d0-a9d5ed0-13c4-4d4c4fd0-afe8509-1647c2c7@208.94.157.10 CSeq: 1 INVITE Server: i-Communicate Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO Supported: replaces, timer Contact: Content-Type: application/sdp Content-Length: 235 v=0 o=root 1524277634 1524277634 IN IP4 XXX.XXX.XXX11 s=Asterisk PBX 1.6.2.13 c=IN IP4 XXX.XXX.XXX11 t=0 0 m=audio 14174 RTP/AVP 0 101 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 a=sendrecv <------------> [2011-02-04 14:11:22] VERBOSE[17753] chan_sip.c: <--- SIP read from UDP:208.94.157.10:5060 ---> ACK sip:19164444444@XXX.XXX.XXX11 SIP/2.0 From: ;tag=a9d5ed0-13c4-4d4c4fd0-afe8509-3264b01d To: ;tag=as32e5ad28 Call-ID: CXC-505-6b0320d0-a9d5ed0-13c4-4d4c4fd0-afe8509-1647c2c7@208.94.157.10 CSeq: 1 ACK Via: SIP/2.0/UDP 208.94.157.10:5060;branch=z9hG4bK-88dfb-4d4c4fd0-afe85fa-575eaaa8 Max-Forwards: 69 Contact: Content-Length: 0 <-------------> [2011-02-04 14:11:22] VERBOSE[17753] chan_sip.c: --- (9 headers 0 lines) --- [2011-02-04 14:11:22] VERBOSE[20533] res_agi.c: AGI Tx >> 200 result=0 [2011-02-04 14:11:22] VERBOSE[20533] res_agi.c: AGI Rx << EXEC StartMusicOnHold "default" [2011-02-04 14:11:22] WARNING[20533] res_musiconhold.c: Unable to start music on hold class 'default' on channel SIP/8-208.94.157.10-0000387f [2011-02-04 14:11:22] VERBOSE[20533] res_agi.c: AGI Tx >> 200 result=0 [2011-02-04 14:11:22] VERBOSE[20533] res_agi.c: AGI Rx << SET VARIABLE __ENSWITCH-CALLERID-EXTERNAL "7169074915" [2011-02-04 14:11:22] VERBOSE[20533] res_agi.c: AGI Tx >> 200 result=1 [2011-02-04 14:11:22] VERBOSE[20533] res_agi.c: AGI Rx << WAIT FOR DIGIT 1000 [2011-02-04 14:11:23] VERBOSE[17753] chan_sip.c: Really destroying SIP dialog '580179761@XXX.XXX.XXX16' Method: OPTIONS [2011-02-04 14:11:23] VERBOSE[20533] res_agi.c: AGI Tx >> 200 result=0 [2011-02-04 14:11:23] VERBOSE[20533] res_agi.c: AGI Rx << WAIT FOR DIGIT 1000 [2011-02-04 14:11:24] VERBOSE[20533] res_agi.c: AGI Tx >> 200 result=0 [2011-02-04 14:11:24] VERBOSE[20533] res_agi.c: AGI Rx << WAIT FOR DIGIT 1000 [2011-02-04 14:11:25] VERBOSE[20533] res_agi.c: AGI Tx >> 200 result=0 [2011-02-04 14:11:25] VERBOSE[20533] res_agi.c: AGI Rx << WAIT FOR DIGIT 1000 [2011-02-04 14:11:26] VERBOSE[20533] res_agi.c: AGI Tx >> 200 result=0 [2011-02-04 14:11:26] VERBOSE[20533] res_agi.c: AGI Rx << WAIT FOR DIGIT 1000 [2011-02-04 14:11:27] VERBOSE[20533] res_agi.c: AGI Tx >> 200 result=0 [2011-02-04 14:11:27] VERBOSE[20533] res_agi.c: AGI Rx << WAIT FOR DIGIT 1000 [2011-02-04 14:11:28] VERBOSE[20533] res_agi.c: AGI Tx >> 200 result=0 [2011-02-04 14:11:28] VERBOSE[20533] res_agi.c: AGI Rx << WAIT FOR DIGIT 1000 [2011-02-04 14:11:29] VERBOSE[20533] res_agi.c: AGI Tx >> 200 result=0 [2011-02-04 14:11:29] VERBOSE[20533] res_agi.c: AGI Rx << WAIT FOR DIGIT 1000 [2011-02-04 14:11:30] VERBOSE[20533] res_agi.c: AGI Tx >> 200 result=0 [2011-02-04 14:11:30] VERBOSE[20533] res_agi.c: AGI Rx << WAIT FOR DIGIT 1000 [2011-02-04 14:11:31] VERBOSE[17753] chan_sip.c: <--- SIP read from UDP:XXX.XXX.XXX16:33033 ---> OPTIONS sip:XXX.XXX.XXX11:5060 SIP/2.0 Via: SIP/2.0/UDP XXX.XXX.XXX16:59230;branch=z9hG4bK.6f94ba94;rport;alias From: sip:sipsak@XXX.XXX.XXX16:59230;tag=6a47b825 To: sip:XXX.XXX.XXX11:5060 Call-ID: 1783085093@XXX.XXX.XXX16 CSeq: 1 OPTIONS Contact: sip:sipsak@XXX.XXX.XXX16:59230 Content-Length: 0 Max-Forwards: 70 User-Agent: sipsak 0.9.6 Accept: text/plain <-------------> [2011-02-04 14:11:31] VERBOSE[17753] chan_sip.c: --- (11 headers 0 lines) --- [2011-02-04 14:11:31] VERBOSE[17753] chan_sip.c: Looking for s in from-external (domain XXX.XXX.XXX11) [2011-02-04 14:11:31] VERBOSE[17753] chan_sip.c: <--- Transmitting (NAT) to XXX.XXX.XXX16:33033 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP XXX.XXX.XXX16:59230;branch=z9hG4bK.6f94ba94;alias;received=XXX.XXX.XXX16;rport=33033 From: sip:sipsak@XXX.XXX.XXX16:59230;tag=6a47b825 To: sip:XXX.XXX.XXX11:5060;tag=as177eb85f Call-ID: 1783085093@XXX.XXX.XXX16 CSeq: 1 OPTIONS Server: i-Communicate Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO Supported: replaces, timer Contact: Accept: application/sdp Content-Length: 0 <------------> [2011-02-04 14:11:31] VERBOSE[17753] chan_sip.c: Scheduling destruction of SIP dialog '1783085093@XXX.XXX.XXX16' in 32000 ms (Method: OPTIONS) [2011-02-04 14:11:31] VERBOSE[20533] res_agi.c: AGI Tx >> 200 result=0 [2011-02-04 14:11:31] VERBOSE[20533] res_agi.c: AGI Rx << WAIT FOR DIGIT 1000 [2011-02-04 14:11:33] VERBOSE[20533] res_agi.c: AGI Tx >> 200 result=0 [2011-02-04 14:11:33] VERBOSE[20533] res_agi.c: AGI Rx << WAIT FOR DIGIT 1000 [2011-02-04 14:11:33] VERBOSE[17753] chan_sip.c: Really destroying SIP dialog '945888276@XXX.XXX.XXX16' Method: OPTIONS [2011-02-04 14:11:34] VERBOSE[20533] res_agi.c: AGI Tx >> 200 result=0 [2011-02-04 14:11:34] VERBOSE[20533] res_agi.c: AGI Rx << WAIT FOR DIGIT 1000