*CLI> core set debug 7 Core debug was 0 and is now 7 *CLI> sip debug history no notify prune reload set show *CLI> sip debug SIP Debugging enabled The 'sip debug' command is deprecated and will be removed in a future release. Please use 'sip set debug' instead. *CLI> iax2 jb no provision prune reload set show test trunk *CLI> iax2 jb no provision prune reload set show test trunk *CLI> iax2 Really destroying SIP dialog 'c2b3da6-53979c20-4be1287b@172.22.120.77' Method: REGISTER set Really destroying SIP dialog 'e6403fa3-7b5da1c1-ed0f5c7a@172.22.120.78' Method: REGISTER debug jb off trunk *CLI> iax2 set debug jb off trunk *CLI> iax2 set debug IAX2 Debugging Enabled *CLI> Tx-Frame Retry[000] -- OSeqno: 000 ISeqno: 000 Type: IAX Subclass: POKE Timestamp: 00014ms SCall: 06022 DCall: 00000 [172.22.120.73:4569] Rx-Frame Retry[Yes] -- OSeqno: 000 ISeqno: 001 Type: IAX Subclass: PONG Timestamp: 00014ms SCall: 00001 DCall: 06022 [172.22.120.73:4569] Tx-Frame Retry[-01] -- OSeqno: 001 ISeqno: 001 Type: IAX Subclass: ACK Timestamp: 00014ms SCall: 06022 DCall: 00001 [172.22.120.73:4569] Rx-Frame Retry[ No] -- OSeqno: 000 ISeqno: 000 Type: IAX Subclass: POKE Timestamp: 00017ms SCall: 00132 DCall: 00000 [172.22.120.73:4569] Rx-Frame Retry[ No] -- OSeqno: 001 ISeqno: 001 Type: IAX Subclass: ACK Timestamp: 00017ms SCall: 00132 DCall: 00001 [172.22.120.73:4569] Rx-Frame Retry[ No] -- OSeqno: 000 ISeqno: 000 Type: IAX Subclass: POKE Timestamp: 00014ms SCall: 14146 DCall: 00000 [172.22.120.72:4569] Rx-Frame Retry[ No] -- OSeqno: 001 ISeqno: 001 Type: IAX Subclass: ACK Timestamp: 00014ms SCall: 14146 DCall: 00001 [172.22.120.72:4569] Tx-Frame Retry[000] -- OSeqno: 000 ISeqno: 000 Type: IAX Subclass: POKE Timestamp: 00003ms SCall: 03389 DCall: 00000 [172.19.20.20:4569] Tx-Frame Retry[000] -- OSeqno: 000 ISeqno: 000 Type: IAX Subclass: POKE Timestamp: 00007ms SCall: 05695 DCall: 00000 [172.22.123.57:4569] Rx-Frame Retry[Yes] -- OSeqno: 000 ISeqno: 001 Type: IAX Subclass: PONG Timestamp: 00000ms SCall: 00001 DCall: 03389 [172.19.20.20:4569] Tx-Frame Retry[-01] -- OSeqno: 001 ISeqno: 001 Type: IAX Subclass: ACK Timestamp: 00127ms SCall: 03389 DCall: 00001 [172.19.20.20:4569] Rx-Frame Retry[ No] -- OSeqno: 000 ISeqno: 000 Type: IAX Subclass: POKE Timestamp: 00005ms SCall: 12170 DCall: 00000 [172.19.20.5:4569] Rx-Frame Retry[Yes] -- OSeqno: 000 ISeqno: 001 Type: IAX Subclass: PONG Timestamp: 00000ms SCall: 00001 DCall: 05695 [172.22.123.57:4569] Tx-Frame Retry[-01] -- OSeqno: 001 ISeqno: 001 Type: IAX Subclass: ACK Timestamp: 00272ms SCall: 05695 DCall: 00001 [172.22.123.57:4569] Rx-Frame Retry[ No] -- OSeqno: 001 ISeqno: 001 Type: IAX Subclass: ACK Timestamp: 00005ms SCall: 12170 DCall: 00001 [172.19.20.5:4569] Rx-Frame Retry[ No] -- OSeqno: 000 ISeqno: 000 Type: IAX Subclass: POKE Timestamp: 00002ms SCall: 08342 DCall: 00000 [172.22.123.57:4569] Rx-Frame Retry[ No] -- OSeqno: 001 ISeqno: 001 Type: IAX Subclass: ACK Timestamp: 00002ms SCall: 08342 DCall: 00001 [172.22.123.57:4569] Rx-Frame Retry[ No] -- OSeqno: 000 ISeqno: 000 Type: IAX Subclass: POKE Timestamp: 00010ms SCall: 00151 DCall: 00000 [172.19.20.20:4569] Rx-Frame Retry[ No] -- OSeqno: 001 ISeqno: 001 Type: IAX Subclass: ACK Timestamp: 00010ms SCall: 00151 DCall: 00001 [172.19.20.20:4569] <--- SIP read from 172.16.121.25:5060 ---> <-------------> Tx-Frame Retry[000] -- OSeqno: 000 ISeqno: 000 Type: IAX Subclass: POKE Timestamp: 00017ms SCall: 03749 DCall: 00000 [172.22.120.73:4569] Rx-Frame Retry[Yes] -- OSeqno: 000 ISeqno: 001 Type: IAX Subclass: PONG Timestamp: 00017ms SCall: 00001 DCall: 03749 [172.22.120.73:4569] Tx-Frame Retry[-01] -- OSeqno: 001 ISeqno: 001 Type: IAX Subclass: ACK Timestamp: 00017ms SCall: 03749 DCall: 00001 [172.22.120.73:4569] Rx-Frame Retry[ No] -- OSeqno: 000 ISeqno: 000 Type: IAX Subclass: POKE Timestamp: 00003ms SCall: 03441 DCall: 00000 [172.22.120.73:4569] Rx-Frame Retry[ No] -- OSeqno: 001 ISeqno: 001 Type: IAX Subclass: ACK Timestamp: 00003ms SCall: 03441 DCall: 00001 [172.22.120.73:4569] Rx-Frame Retry[ No] -- OSeqno: 000 ISeqno: 000 Type: IAX Subclass: POKE Timestamp: 00016ms SCall: 04170 DCall: 00000 [172.22.120.72:4569] Rx-Frame Retry[ No] -- OSeqno: 001 ISeqno: 001 Type: IAX Subclass: ACK Timestamp: 00016ms SCall: 04170 DCall: 00001 [172.22.120.72:4569] Tx-Frame Retry[000] -- OSeqno: 000 ISeqno: 000 Type: IAX Subclass: POKE Timestamp: 00005ms SCall: 01165 DCall: 00000 [172.19.20.20:4569] Rx-Frame Retry[Yes] -- OSeqno: 000 ISeqno: 001 Type: IAX Subclass: PONG Timestamp: 00000ms SCall: 00001 DCall: 01165 [172.19.20.20:4569] Tx-Frame Retry[-01] -- OSeqno: 001 ISeqno: 001 Type: IAX Subclass: ACK Timestamp: 00135ms SCall: 01165 DCall: 00001 [172.19.20.20:4569] Tx-Frame Retry[000] -- OSeqno: 000 ISeqno: 000 Type: IAX Subclass: POKE Timestamp: 00009ms SCall: 13437 DCall: 00000 [172.22.123.57:4569] Rx-Frame Retry[ No] -- OSeqno: 000 ISeqno: 000 Type: IAX Subclass: POKE Timestamp: 00013ms SCall: 00424 DCall: 00000 [172.19.20.5:4569] Rx-Frame Retry[ No] -- OSeqno: 001 ISeqno: 001 Type: IAX Subclass: ACK Timestamp: 00013ms SCall: 00424 DCall: 00001 [172.19.20.5:4569] Rx-Frame Retry[Yes] -- OSeqno: 000 ISeqno: 001 Type: IAX Subclass: PONG Timestamp: 00000ms SCall: 00001 DCall: 13437 [172.22.123.57:4569] Tx-Frame Retry[-01] -- OSeqno: 001 ISeqno: 001 Type: IAX Subclass: ACK Timestamp: 00340ms SCall: 13437 DCall: 00001 [172.22.123.57:4569] Rx-Frame Retry[ No] -- OSeqno: 000 ISeqno: 000 Type: IAX Subclass: POKE Timestamp: 00012ms SCall: 03422 DCall: 00000 [172.22.123.57:4569] Rx-Frame Retry[ No] -- OSeqno: 001 ISeqno: 001 Type: IAX Subclass: ACK Timestamp: 00012ms SCall: 03422 DCall: 00001 [172.22.123.57:4569] Rx-Frame Retry[ No] -- OSeqno: 000 ISeqno: 000 Type: IAX Subclass: POKE Timestamp: 00007ms SCall: 08116 DCall: 00000 [172.19.20.20:4569] Rx-Frame Retry[ No] -- OSeqno: 001 ISeqno: 001 Type: IAX Subclass: ACK Timestamp: 00007ms SCall: 08116 DCall: 00001 [172.19.20.20:4569] Rx-Frame Retry[ No] -- OSeqno: 000 ISeqno: 000 Type: IAX Subclass: NEW Timestamp: 00017ms SCall: 02433 DCall: 00000 [172.22.123.57:4569] VERSION : 2 CALLED NUMBER : 6005 CODEC_PREFS : (ilbc) CALLING NUMBER : 9024821288 CALLING PRESNTN : 0 CALLING TYPEOFN : 0 CALLING TRANSIT : 0 CALLING NAME : LANGUAGE : en USERNAME : Paragon-BDA FORMAT : 1024 CAPABILITY : 1024 ADSICPE : 2 DATE TIME : 2009-10-14 13:44:30 Tx-Frame Retry[000] -- OSeqno: 000 ISeqno: 001 Type: IAX Subclass: AUTHREQ Timestamp: 00009ms SCall: 14413 DCall: 02433 [172.22.123.57:4569] AUTHMETHODS : 3 CHALLENGE : 337024501 USERNAME : Paragon-BDA Rx-Frame Retry[ No] -- OSeqno: 001 ISeqno: 001 Type: IAX Subclass: AUTHREP Timestamp: 00368ms SCall: 02433 DCall: 14413 [172.22.123.57:4569] MD5 RESULT : feea6d9bfa9bebd20350ff2ae26b2ee6 -- Accepting AUTHENTICATED call from 172.22.123.57: > requested format = ilbc, > requested prefs = (ilbc), > actual format = ilbc, > host prefs = (ilbc), > priority = mine -- Executing [6005@iax-trunk:1] Goto("IAX2/Paragon-BDA-14413", "extensions|6005|1") in new stack -- Goto (extensions,6005,1) -- Executing [6005@extensions:1] NoOp("IAX2/Paragon-BDA-14413", "") in new stack -- Executing [6005@extensions:2] Goto("IAX2/Paragon-BDA-14413", "main-line|s|1") in new stack -- Goto (main-line,s,1) Tx-Frame Retry[000] -- OSeqno: 001 ISeqno: 002 Type: IAX Subclass: ACCEPT Timestamp: 00764ms SCall: 14413 DCall: 02433 [172.22.123.57:4569] FORMAT : 1024 -- Executing [s@main-line:1] NoOp("IAX2/Paragon-BDA-14413", "Debug Output") in new stack -- Executing [s@main-line:2] NoOp("IAX2/Paragon-BDA-14413", "CallerID-dnid )") in new stack -- Executing [s@main-line:3] NoOp("IAX2/Paragon-BDA-14413", "CallerID-number 9024821288)") in new stack -- Executing [s@main-line:4] NoOp("IAX2/Paragon-BDA-14413", "CallerID-name )") in new stack -- Executing [s@main-line:5] Wait("IAX2/Paragon-BDA-14413", "2") in new stack Rx-Frame Retry[ No] -- OSeqno: 002 ISeqno: 002 Type: IAX Subclass: ACK Timestamp: 00764ms SCall: 02433 DCall: 14413 [172.22.123.57:4569] Rx-Frame Retry[ No] -- OSeqno: 002 ISeqno: 002 Type: VOICE Subclass: 138 Timestamp: 00917ms SCall: 02433 DCall: 14413 [172.22.123.57:4569] Tx-Frame Retry[-01] -- OSeqno: 002 ISeqno: 003 Type: IAX Subclass: ACK Timestamp: 00917ms SCall: 14413 DCall: 02433 [172.22.123.57:4569] Tx-Frame Retry[000] -- OSeqno: 000 ISeqno: 000 Type: IAX Subclass: POKE Timestamp: 00018ms SCall: 13204 DCall: 00000 [172.22.120.73:4569] Rx-Frame Retry[Yes] -- OSeqno: 000 ISeqno: 001 Type: IAX Subclass: PONG Timestamp: 00018ms SCall: 00001 DCall: 13204 [172.22.120.73:4569] Tx-Frame Retry[-01] -- OSeqno: 001 ISeqno: 001 Type: IAX Subclass: ACK Timestamp: 00018ms SCall: 13204 DCall: 00001 [172.22.120.73:4569] Rx-Frame Retry[ No] -- OSeqno: 000 ISeqno: 000 Type: IAX Subclass: POKE Timestamp: 00002ms SCall: 13890 DCall: 00000 [172.22.120.73:4569] Rx-Frame Retry[ No] -- OSeqno: 001 ISeqno: 001 Type: IAX Subclass: ACK Timestamp: 00002ms SCall: 13890 DCall: 00001 [172.22.120.73:4569] Rx-Frame Retry[ No] -- OSeqno: 000 ISeqno: 000 Type: IAX Subclass: POKE Timestamp: 00017ms SCall: 12113 DCall: 00000 [172.22.120.72:4569] Rx-Frame Retry[ No] -- OSeqno: 001 ISeqno: 001 Type: IAX Subclass: ACK Timestamp: 00017ms SCall: 12113 DCall: 00001 [172.22.120.72:4569] -- Executing [s@main-line:6] Answer("IAX2/Paragon-BDA-14413", "") in new stack -- Executing [s@main-line:7] Playback("IAX2/Paragon-BDA-14413", "/var/lib/asterisk/sounds/custom/queue_greeting") in new stack -- Playing '/var/lib/asterisk/sounds/custom/queue_greeting' (language 'en') Tx-Frame Retry[000] -- OSeqno: 002 ISeqno: 003 Type: CONTROL Subclass: ANSWER Timestamp: 02814ms SCall: 14413 DCall: 02433 [172.22.123.57:4569] Tx-Frame Retry[000] -- OSeqno: 003 ISeqno: 003 Type: CONTROL Subclass: (255?) Timestamp: 02817ms SCall: 14413 DCall: 02433 [172.22.123.57:4569] Tx-Frame Retry[000] -- OSeqno: 000 ISeqno: 000 Type: IAX Subclass: POKE Timestamp: 00013ms SCall: 02530 DCall: 00000 [172.19.20.20:4569] Tx-Frame Retry[000] -- OSeqno: 004 ISeqno: 003 Type: VOICE Subclass: 138 Timestamp: 02850ms SCall: 14413 DCall: 02433 [172.22.123.57:4569] Rx-Frame Retry[Yes] -- OSeqno: 000 ISeqno: 001 Type: IAX Subclass: PONG Timestamp: 00000ms SCall: 00001 DCall: 02530 [172.19.20.20:4569] Tx-Frame Retry[-01] -- OSeqno: 001 ISeqno: 001 Type: IAX Subclass: ACK Timestamp: 00142ms SCall: 02530 DCall: 00001 [172.19.20.20:4569] Rx-Frame Retry[ No] -- OSeqno: 003 ISeqno: 004 Type: IAX Subclass: ACK Timestamp: 02817ms SCall: 02433 DCall: 14413 [172.22.123.57:4569] Rx-Frame Retry[ No] -- OSeqno: 003 ISeqno: 005 Type: IAX Subclass: ACK Timestamp: 02850ms SCall: 02433 DCall: 14413 [172.22.123.57:4569] Rx-Frame Retry[ No] -- OSeqno: 003 ISeqno: 003 Type: IAX Subclass: ACK Timestamp: 02814ms SCall: 02433 DCall: 14413 [172.22.123.57:4569] Rx-Frame Retry[ No] -- OSeqno: 003 ISeqno: 005 Type: CONTROL Subclass: (20?) Timestamp: 03080ms SCall: 02433 DCall: 14413 [172.22.123.57:4569] Tx-Frame Retry[-01] -- OSeqno: 005 ISeqno: 004 Type: IAX Subclass: ACK Timestamp: 03080ms SCall: 14413 DCall: 02433 [172.22.123.57:4569] Rx-Frame Retry[ No] -- OSeqno: 004 ISeqno: 005 Type: CONTROL Subclass: (20?) Timestamp: 03083ms SCall: 02433 DCall: 14413 [172.22.123.57:4569] Tx-Frame Retry[-01] -- OSeqno: 005 ISeqno: 005 Type: IAX Subclass: ACK Timestamp: 03083ms SCall: 14413 DCall: 02433 [172.22.123.57:4569] Rx-Frame Retry[ No] -- OSeqno: 005 ISeqno: 005 Type: CONTROL Subclass: (20?) Timestamp: 03086ms SCall: 02433 DCall: 14413 [172.22.123.57:4569] Tx-Frame Retry[-01] -- OSeqno: 005 ISeqno: 006 Type: IAX Subclass: ACK Timestamp: 03086ms SCall: 14413 DCall: 02433 [172.22.123.57:4569] Rx-Frame Retry[ No] -- OSeqno: 000 ISeqno: 000 Type: IAX Subclass: POKE Timestamp: 00003ms SCall: 11820 DCall: 00000 [172.19.20.5:4569] Tx-Frame Retry[000] -- OSeqno: 000 ISeqno: 000 Type: IAX Subclass: POKE Timestamp: 00018ms SCall: 02045 DCall: 00000 [172.22.123.57:4569] Rx-Frame Retry[ No] -- OSeqno: 001 ISeqno: 001 Type: IAX Subclass: ACK Timestamp: 00003ms SCall: 11820 DCall: 00001 [172.19.20.5:4569] Rx-Frame Retry[Yes] -- OSeqno: 000 ISeqno: 001 Type: IAX Subclass: PONG Timestamp: 00000ms SCall: 00001 DCall: 02045 [172.22.123.57:4569] Tx-Frame Retry[-01] -- OSeqno: 001 ISeqno: 001 Type: IAX Subclass: ACK Timestamp: 00294ms SCall: 02045 DCall: 00001 [172.22.123.57:4569] Rx-Frame Retry[ No] -- OSeqno: 000 ISeqno: 000 Type: IAX Subclass: POKE Timestamp: 00001ms SCall: 09974 DCall: 00000 [172.22.123.57:4569] Rx-Frame Retry[ No] -- OSeqno: 000 ISeqno: 000 Type: IAX Subclass: POKE Timestamp: 00017ms SCall: 03885 DCall: 00000 [172.19.20.20:4569] Reliably Transmitting (no NAT) to 172.22.120.77:5060: OPTIONS sip:6001@172.22.120.77 SIP/2.0 Via: SIP/2.0/UDP 172.16.120.75:5060;branch=z9hG4bK56352149;rport From: "asterisk" ;tag=as4a91d20f To: Contact: Call-ID: 41b3db7b3b53545c1bb1a39d35234ebd@172.16.120.75 CSeq: 102 OPTIONS User-Agent: Asterisk PBX Max-Forwards: 70 Date: Wed, 14 Oct 2009 16:47:58 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO Supported: replaces Content-Length: 0 --- Rx-Frame Retry[ No] -- OSeqno: 001 ISeqno: 001 Type: IAX Subclass: ACK Timestamp: 00001ms SCall: 09974 DCall: 00001 [172.22.123.57:4569] <--- SIP read from 172.22.120.77:5060 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 172.16.120.75:5060;branch=z9hG4bK56352149;rport From: "asterisk" ;tag=as4a91d20f To: ;tag=C5026313-C7AA62BE CSeq: 102 OPTIONS Call-ID: 41b3db7b3b53545c1bb1a39d35234ebd@172.16.120.75 Contact: Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER User-Agent: PolycomSoundPointIP-SPIP_501-UA/3.1.3.0439 Accept-Language: en Accept: application/sdp,text/plain,message/sipfrag,application/dialog-info+xml Accept-Encoding: identity Supported: 100rel,timer,replaces Content-Length: 0 <-------------> --- (14 headers 0 lines) --- Really destroying SIP dialog '41b3db7b3b53545c1bb1a39d35234ebd@172.16.120.75' Method: OPTIONS Rx-Frame Retry[ No] -- OSeqno: 001 ISeqno: 001 Type: IAX Subclass: ACK Timestamp: 00017ms SCall: 03885 DCall: 00001 [172.19.20.20:4569] Reliably Transmitting (no NAT) to 172.22.120.78:5060: OPTIONS sip:6002@172.22.120.78 SIP/2.0 Via: SIP/2.0/UDP 172.16.120.75:5060;branch=z9hG4bK7bf0b051;rport From: "asterisk" ;tag=as66368989 To: Contact: Call-ID: 425ebf925e8c1b342d55b51a7ef3889d@172.16.120.75 CSeq: 102 OPTIONS User-Agent: Asterisk PBX Max-Forwards: 70 Date: Wed, 14 Oct 2009 16:47:59 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO Supported: replaces Content-Length: 0 --- <--- SIP read from 172.22.120.78:5060 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 172.16.120.75:5060;branch=z9hG4bK7bf0b051;rport From: "asterisk" ;tag=as66368989 To: ;tag=4CB3B19C-9100AAA5 CSeq: 102 OPTIONS Call-ID: 425ebf925e8c1b342d55b51a7ef3889d@172.16.120.75 Contact: Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER User-Agent: PolycomSoundPointIP-SPIP_501-UA/3.1.3.0439 Accept-Language: en-ca,en;q=0.9 Accept: application/sdp,text/plain,message/sipfrag,application/dialog-info+xml Accept-Encoding: identity Supported: 100rel,timer,replaces Content-Length: 0 <-------------> --- (14 headers 0 lines) --- Really destroying SIP dialog '425ebf925e8c1b342d55b51a7ef3889d@172.16.120.75' Method: OPTIONS Tx-Frame Retry[000] -- OSeqno: 005 ISeqno: 006 Type: IAX Subclass: LAGRQ Timestamp: 10028ms SCall: 14413 DCall: 02433 [172.22.123.57:4569] Rx-Frame Retry[ No] -- OSeqno: 006 ISeqno: 005 Type: IAX Subclass: LAGRQ Timestamp: 10018ms SCall: 02433 DCall: 14413 [172.22.123.57:4569] Tx-Frame Retry[000] -- OSeqno: 006 ISeqno: 007 Type: IAX Subclass: LAGRP Timestamp: 10018ms SCall: 14413 DCall: 02433 [172.22.123.57:4569] Rx-Frame Retry[ No] -- OSeqno: 007 ISeqno: 007 Type: IAX Subclass: ACK Timestamp: 10018ms SCall: 02433 DCall: 14413 [172.22.123.57:4569] Rx-Frame Retry[ No] -- OSeqno: 007 ISeqno: 006 Type: IAX Subclass: LAGRP Timestamp: 10028ms SCall: 02433 DCall: 14413 [172.22.123.57:4569] Tx-Frame Retry[-01] -- OSeqno: 006 ISeqno: 008 Type: IAX Subclass: ACK Timestamp: 10028ms SCall: 14413 DCall: 02433 [172.22.123.57:4569] -- Executing [s@main-line:8] MixMonitor("IAX2/Paragon-BDA-14413", "/var/www/monitor/20091014-124806_9024821288_1255538876.0.WAV||") in new stack -- Executing [s@main-line:9] Set("IAX2/Paragon-BDA-14413", "AUDIOHOOK_INHERIT(MixMonitor)=yes") in new stack -- Executing [s@main-line:10] Queue("IAX2/Paragon-BDA-14413", "TestQueue1|t|||3600") in new stack -- Started music on hold, class 'default', on IAX2/Paragon-BDA-14413 -- outgoing agentcall, to agent '400', on 'Local/6002@extensions-94ba,1' -- Executing [6002@extensions:1] Dial("Local/6002@extensions-94ba,2", "SIP/6002") in new stack == Begin MixMonitor Recording IAX2/Paragon-BDA-14413 Audio is at 172.16.120.75 port 15098 Adding codec 0x400 (ilbc) to SDP Adding codec 0x4 (ulaw) to SDP Adding non-codec 0x1 (telephone-event) to SDP Reliably Transmitting (no NAT) to 172.22.120.78:5060: INVITE sip:6002@172.22.120.78 SIP/2.0 Via: SIP/2.0/UDP 172.16.120.75:5060;branch=z9hG4bK09d053fe;rport From: "9024821288" ;tag=as1f83b9a5 To: Contact: Call-ID: 5a838208265dab0d413732396f10951c@172.16.120.75 CSeq: 102 INVITE User-Agent: Asterisk PBX Max-Forwards: 70 Date: Wed, 14 Oct 2009 16:48:06 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO Supported: replaces Content-Type: application/sdp Content-Length: 285 v=0 o=root 2801 2801 IN IP4 172.16.120.75 s=session c=IN IP4 172.16.120.75 t=0 0 m=audio 15098 RTP/AVP 97 0 101 a=rtpmap:97 iLBC/8000 a=fmtp:97 mode=30 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=silenceSupp:off - - - - a=ptime:20 a=sendrecv --- -- Called 6002 <--- SIP read from 172.22.120.78:5060 ---> SIP/2.0 100 Trying Via: SIP/2.0/UDP 172.16.120.75:5060;branch=z9hG4bK09d053fe;rport From: "9024821288" ;tag=as1f83b9a5 To: ;tag=5E437550-3EEDC319 CSeq: 102 INVITE Call-ID: 5a838208265dab0d413732396f10951c@172.16.120.75 Contact: User-Agent: PolycomSoundPointIP-SPIP_501-UA/3.1.3.0439 Accept-Language: en-ca,en;q=0.9 Content-Length: 0 <-------------> --- (10 headers 0 lines) --- <--- SIP read from 172.22.120.78:5060 ---> SIP/2.0 180 Ringing Via: SIP/2.0/UDP 172.16.120.75:5060;branch=z9hG4bK09d053fe;rport From: "9024821288" ;tag=as1f83b9a5 To: ;tag=5E437550-3EEDC319 CSeq: 102 INVITE Call-ID: 5a838208265dab0d413732396f10951c@172.16.120.75 Contact: User-Agent: PolycomSoundPointIP-SPIP_501-UA/3.1.3.0439 Allow-Events: talk,hold,conference Accept-Language: en-ca,en;q=0.9 Content-Length: 0 <-------------> --- (11 headers 0 lines) --- -- SIP/6002-b7e09a28 is ringing -- Agent/400 is ringing Tx-Frame Retry[000] -- OSeqno: 000 ISeqno: 000 Type: IAX Subclass: POKE Timestamp: 00019ms SCall: 10962 DCall: 00000 [172.22.120.73:4569] Rx-Frame Retry[Yes] -- OSeqno: 000 ISeqno: 001 Type: IAX Subclass: PONG Timestamp: 00019ms SCall: 00001 DCall: 10962 [172.22.120.73:4569] Tx-Frame Retry[-01] -- OSeqno: 001 ISeqno: 001 Type: IAX Subclass: ACK Timestamp: 00019ms SCall: 10962 DCall: 00001 [172.22.120.73:4569] Rx-Frame Retry[ No] -- OSeqno: 000 ISeqno: 000 Type: IAX Subclass: POKE Timestamp: 00003ms SCall: 00291 DCall: 00000 [172.22.120.73:4569] Rx-Frame Retry[ No] -- OSeqno: 001 ISeqno: 001 Type: IAX Subclass: ACK Timestamp: 00003ms SCall: 00291 DCall: 00001 [172.22.120.73:4569] Rx-Frame Retry[ No] -- OSeqno: 000 ISeqno: 000 Type: IAX Subclass: POKE Timestamp: 00003ms SCall: 02100 DCall: 00000 [172.22.120.72:4569] Rx-Frame Retry[ No] -- OSeqno: 001 ISeqno: 001 Type: IAX Subclass: ACK Timestamp: 00003ms SCall: 02100 DCall: 00001 [172.22.120.72:4569] Rx-Frame Retry[Yes] -- OSeqno: 007 ISeqno: 007 Type: IAX Subclass: LAGRP Timestamp: 10028ms SCall: 02433 DCall: 14413 [172.22.123.57:4569] Tx-Frame Retry[-01] -- OSeqno: 007 ISeqno: 008 Type: IAX Subclass: ACK Timestamp: 10028ms SCall: 14413 DCall: 02433 [172.22.123.57:4569] Tx-Frame Retry[000] -- OSeqno: 000 ISeqno: 000 Type: IAX Subclass: POKE Timestamp: 00019ms SCall: 13820 DCall: 00000 [172.19.20.20:4569] <--- SIP read from 172.22.120.78:5060 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 172.16.120.75:5060;branch=z9hG4bK09d053fe;rport From: "9024821288" ;tag=as1f83b9a5 To: ;tag=5E437550-3EEDC319 CSeq: 102 INVITE Call-ID: 5a838208265dab0d413732396f10951c@172.16.120.75 Contact: Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER User-Agent: PolycomSoundPointIP-SPIP_501-UA/3.1.3.0439 Accept-Language: en-ca,en;q=0.9 Content-Type: application/sdp Content-Length: 201 v=0 o=- 1255537307 1255537307 IN IP4 172.22.120.78 s=Polycom IP Phone c=IN IP4 172.22.120.78 t=0 0 m=audio 2240 RTP/AVP 0 101 a=sendrecv a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 <-------------> --- (12 headers 9 lines) --- Found RTP audio format 0 Found RTP audio format 101 Peer audio RTP is at port 172.22.120.78:2240 Found audio description format PCMU for ID 0 Found audio description format telephone-event for ID 101 Capabilities: us - 0x404 (ulaw|ilbc), peer - audio=0x4 (ulaw)/video=0x0 (nothing), combined - 0x4 (ulaw) Non-codec capabilities (dtmf): us - 0x1 (telephone-event), peer - 0x1 (telephone-event), combined - 0x1 (telephone-event) Peer audio RTP is at port 172.22.120.78:2240 list_route: hop: set_destination: Parsing for address/port to send to set_destination: set destination to 172.22.120.78, port 5060 Transmitting (no NAT) to 172.22.120.78:5060: ACK sip:6002@172.22.120.78 SIP/2.0 Via: SIP/2.0/UDP 172.16.120.75:5060;branch=z9hG4bK65db288f;rport From: "9024821288" ;tag=as1f83b9a5 To: ;tag=5E437550-3EEDC319 Contact: Call-ID: 5a838208265dab0d413732396f10951c@172.16.120.75 CSeq: 102 ACK User-Agent: Asterisk PBX Max-Forwards: 70 Content-Length: 0 --- -- SIP/6002-b7e09a28 answered Local/6002@extensions-94ba,2 -- Agent/400 answered IAX2/Paragon-BDA-14413 -- Stopped music on hold on IAX2/Paragon-BDA-14413 Tx-Frame Retry[000] -- OSeqno: 007 ISeqno: 008 Type: CONTROL Subclass: (255?) Timestamp: 12993ms SCall: 14413 DCall: 02433 [172.22.123.57:4569] Tx-Frame Retry[000] -- OSeqno: 008 ISeqno: 008 Type: CONTROL Subclass: (20?) Timestamp: 12996ms SCall: 14413 DCall: 02433 [172.22.123.57:4569] Tx-Frame Retry[000] -- OSeqno: 009 ISeqno: 008 Type: CONTROL Subclass: (20?) Timestamp: 12999ms SCall: 14413 DCall: 02433 [172.22.123.57:4569] Tx-Frame Retry[000] -- OSeqno: 010 ISeqno: 008 Type: CONTROL Subclass: (255?) Timestamp: 13002ms SCall: 14413 DCall: 02433 [172.22.123.57:4569] Tx-Frame Retry[000] -- OSeqno: 011 ISeqno: 008 Type: CONTROL Subclass: (20?) Timestamp: 13005ms SCall: 14413 DCall: 02433 [172.22.123.57:4569] Tx-Frame Retry[000] -- OSeqno: 012 ISeqno: 008 Type: CONTROL Subclass: (20?) Timestamp: 13008ms SCall: 14413 DCall: 02433 [172.22.123.57:4569] == Spawn extension (extensions, 6002, 1) exited non-zero on 'Local/6002@extensions-94ba,2' Rx-Frame Retry[Yes] -- OSeqno: 000 ISeqno: 001 Type: IAX Subclass: PONG Timestamp: 00000ms SCall: 00001 DCall: 13820 [172.19.20.20:4569] Tx-Frame Retry[-01] -- OSeqno: 001 ISeqno: 001 Type: IAX Subclass: ACK Timestamp: 00168ms SCall: 13820 DCall: 00001 [172.19.20.20:4569] Rx-Frame Retry[ No] -- OSeqno: 008 ISeqno: 009 Type: IAX Subclass: ACK Timestamp: 12996ms SCall: 02433 DCall: 14413 [172.22.123.57:4569] Rx-Frame Retry[ No] -- OSeqno: 008 ISeqno: 008 Type: IAX Subclass: ACK Timestamp: 12993ms SCall: 02433 DCall: 14413 [172.22.123.57:4569] Rx-Frame Retry[ No] -- OSeqno: 008 ISeqno: 010 Type: IAX Subclass: ACK Timestamp: 12999ms SCall: 02433 DCall: 14413 [172.22.123.57:4569] Rx-Frame Retry[ No] -- OSeqno: 008 ISeqno: 011 Type: IAX Subclass: ACK Timestamp: 13002ms SCall: 02433 DCall: 14413 [172.22.123.57:4569] Rx-Frame Retry[ No] -- OSeqno: 008 ISeqno: 012 Type: IAX Subclass: ACK Timestamp: 13005ms SCall: 02433 DCall: 14413 [172.22.123.57:4569] Rx-Frame Retry[ No] -- OSeqno: 009 ISeqno: 012 Type: CONTROL Subclass: (20?) Timestamp: 13133ms SCall: 02433 DCall: 14413 [172.22.123.57:4569] Rx-Frame Retry[ No] -- OSeqno: 008 ISeqno: 012 Type: CONTROL Subclass: (20?) Timestamp: 13130ms SCall: 02433 DCall: 14413 [172.22.123.57:4569] Tx-Frame Retry[000] -- OSeqno: 008 ISeqno: 008 Type: IAX Subclass: VNAK Timestamp: 13173ms SCall: 14413 DCall: 02433 [172.22.123.57:4569] Rx-Frame Retry[ No] -- OSeqno: 010 ISeqno: 012 Type: CONTROL Subclass: (20?) Timestamp: 13136ms SCall: 02433 DCall: 14413 [172.22.123.57:4569] Tx-Frame Retry[000] -- OSeqno: 008 ISeqno: 008 Type: IAX Subclass: VNAK Timestamp: 13176ms SCall: 14413 DCall: 02433 [172.22.123.57:4569] Tx-Frame Retry[-01] -- OSeqno: 012 ISeqno: 009 Type: IAX Subclass: ACK Timestamp: 13130ms SCall: 14413 DCall: 02433 [172.22.123.57:4569] Rx-Frame Retry[ No] -- OSeqno: 008 ISeqno: 013 Type: IAX Subclass: ACK Timestamp: 13008ms SCall: 02433 DCall: 14413 [172.22.123.57:4569] Rx-Frame Retry[ No] -- OSeqno: 011 ISeqno: 012 Type: CONTROL Subclass: (20?) Timestamp: 13139ms SCall: 02433 DCall: 14413 [172.22.123.57:4569] Tx-Frame Retry[000] -- OSeqno: 009 ISeqno: 009 Type: IAX Subclass: VNAK Timestamp: 13179ms SCall: 14413 DCall: 02433 [172.22.123.57:4569] Rx-Frame Retry[ No] -- OSeqno: 000 ISeqno: 000 Type: IAX Subclass: POKE Timestamp: 00012ms SCall: 13770 DCall: 00000 [172.19.20.5:4569] Rx-Frame Retry[ No] -- OSeqno: 008 ISeqno: 012 Type: CONTROL Subclass: (20?) Timestamp: 13130ms SCall: 02433 DCall: 14413 [172.22.123.57:4569] Tx-Frame Retry[-01] -- OSeqno: 012 ISeqno: 009 Type: IAX Subclass: ACK Timestamp: 13130ms SCall: 14413 DCall: 02433 [172.22.123.57:4569] Rx-Frame Retry[ No] -- OSeqno: 009 ISeqno: 012 Type: CONTROL Subclass: (20?) Timestamp: 13133ms SCall: 02433 DCall: 14413 [172.22.123.57:4569] Rx-Frame Retry[ No] -- OSeqno: 011 ISeqno: 012 Type: CONTROL Subclass: (20?) Tx-Frame Retry[-01] -- OSeqno: 012 ISeqno: 010 Type: IAX Subclass: ACK Timestamp: 13133ms SCall: 14413 DCall: 02433 [172.22.123.57:4569] Timestamp: 13139ms SCall: 02433 DCall: 14413 [172.22.123.57:4569] Rx-Frame Retry[ No] -- OSeqno: 010 ISeqno: 012 Type: CONTROL Subclass: (20?) Tx-Frame Retry[000] -- OSeqno: 010 ISeqno: 010 Type: IAX Subclass: VNAK Timestamp: 13333ms SCall: 14413 DCall: 02433 [172.22.123.57:4569] Timestamp: 13136ms SCall: 02433 DCall: 14413 [172.22.123.57:4569] Tx-Frame Retry[-01] -- OSeqno: 012 ISeqno: 011 Type: IAX Subclass: ACK Timestamp: 13136ms SCall: 14413 DCall: 02433 [172.22.123.57:4569] Rx-Frame Retry[ No] -- OSeqno: 008 ISeqno: 012 Type: CONTROL Subclass: (20?) Rx-Frame Retry[ No] -- OSeqno: 009 ISeqno: 012 Type: CONTROL Subclass: (20?) Timestamp: 13133ms SCall: 02433 DCall: 14413 [172.22.123.57:4569] Tx-Frame Retry[-01] -- OSeqno: 012 ISeqno: 011 Type: IAX Subclass: ACK Timestamp: 13133ms SCall: 14413 DCall: 02433 [172.22.123.57:4569] Rx-Frame Retry[ No] -- OSeqno: 010 ISeqno: 012 Type: CONTROL Subclass: (20?) Timestamp: 13136ms SCall: 02433 DCall: 14413 [172.22.123.57:4569] Tx-Frame Retry[-01] -- OSeqno: 012 ISeqno: 011 Type: IAX Subclass: ACK Timestamp: 13136ms SCall: 14413 DCall: 02433 [172.22.123.57:4569] Timestamp: 13130ms SCall: 02433 DCall: 14413 [172.22.123.57:4569] Tx-Frame Retry[-01] -- OSeqno: 012 ISeqno: 011 Type: IAX Subclass: ACK Timestamp: 13130ms SCall: 14413 DCall: 02433 [172.22.123.57:4569] Rx-Frame Retry[ No] -- OSeqno: 011 ISeqno: 012 Type: CONTROL Subclass: (20?) Timestamp: 13139ms SCall: 02433 DCall: 14413 [172.22.123.57:4569] Tx-Frame Retry[-01] -- OSeqno: 012 ISeqno: 012 Type: IAX Subclass: ACK Timestamp: 13139ms SCall: 14413 DCall: 02433 [172.22.123.57:4569] Rx-Frame Retry[ No] -- OSeqno: 009 ISeqno: 012 Type: CONTROL Subclass: (20?) Timestamp: 13133ms SCall: 02433 DCall: 14413 [172.22.123.57:4569] Rx-Frame Retry[ No] -- OSeqno: 010 ISeqno: 012 Type: CONTROL Subclass: (20?) Tx-Frame Retry[-01] -- OSeqno: 012 ISeqno: 012 Type: IAX Subclass: ACK Timestamp: 13136ms SCall: 02433 DCall: 14413 [172.22.123.57:4569] Rx-Frame Retry[ No] -- OSeqno: 011 ISeqno: 012 Type: CONTROL Subclass: (20?) Timestamp: 13139ms SCall: 02433 DCall: 14413 [172.22.123.57:4569] Timestamp: 13133ms SCall: 14413 DCall: 02433 [172.22.123.57:4569] Tx-Frame Retry[-01] -- OSeqno: 012 ISeqno: 012 Type: IAX Subclass: ACK Timestamp: 13136ms SCall: 14413 DCall: 02433 [172.22.123.57:4569] Tx-Frame Retry[-01] -- OSeqno: 012 ISeqno: 012 Type: IAX Subclass: ACK Timestamp: 13139ms SCall: 14413 DCall: 02433 [172.22.123.57:4569] Rx-Frame Retry[ No] -- OSeqno: 001 ISeqno: 001 Type: IAX Subclass: ACK Timestamp: 00012ms SCall: 13770 DCall: 00001 [172.19.20.5:4569] Tx-Frame Retry[000] -- OSeqno: 000 ISeqno: 000 Type: IAX Subclass: POKE Timestamp: 00013ms SCall: 07387 DCall: 00000 [172.22.123.57:4569] Rx-Frame Retry[ No] -- OSeqno: 010 ISeqno: 012 Type: CONTROL Subclass: (20?) Timestamp: 13136ms SCall: 02433 DCall: 14413 [172.22.123.57:4569] Tx-Frame Retry[-01] -- OSeqno: 012 ISeqno: 012 Type: IAX Subclass: ACK Timestamp: 13136ms SCall: 14413 DCall: 02433 [172.22.123.57:4569] Rx-Frame Retry[ No] -- OSeqno: 011 ISeqno: 012 Type: CONTROL Subclass: (20?) Timestamp: 13139ms SCall: 02433 DCall: 14413 [172.22.123.57:4569] Tx-Frame Retry[-01] -- OSeqno: 012 ISeqno: 012 Type: IAX Subclass: ACK Timestamp: 13139ms SCall: 14413 DCall: 02433 [172.22.123.57:4569] Rx-Frame Retry[Yes] -- OSeqno: 000 ISeqno: 001 Type: IAX Subclass: PONG Timestamp: 00000ms SCall: 00001 DCall: 07387 [172.22.123.57:4569] Tx-Frame Retry[-01] -- OSeqno: 001 ISeqno: 001 Type: IAX Subclass: ACK Timestamp: 00183ms SCall: 07387 DCall: 00001 [172.22.123.57:4569] Rx-Frame Retry[ No] -- OSeqno: 000 ISeqno: 000 Type: IAX Subclass: POKE Timestamp: 00002ms SCall: 14445 DCall: 00000 [172.22.123.57:4569] Rx-Frame Retry[ No] -- OSeqno: 000 ISeqno: 000 Type: IAX Subclass: POKE Timestamp: 00005ms SCall: 08450 DCall: 00000 [172.19.20.20:4569] Rx-Frame Retry[ No] -- OSeqno: 001 ISeqno: 001 Type: IAX Subclass: ACK Timestamp: 00002ms SCall: 14445 DCall: 00001 [172.22.123.57:4569] Rx-Frame Retry[ No] -- OSeqno: 001 ISeqno: 001 Type: IAX Subclass: ACK Timestamp: 00005ms SCall: 08450 DCall: 00001 [172.19.20.20:4569] <--- SIP read from 172.22.120.78:5060 ---> INVITE sip:9024821288@172.16.120.75 SIP/2.0 Via: SIP/2.0/UDP 172.22.120.78;branch=z9hG4bKb0d85f7DED48C0E From: ;tag=5E437550-3EEDC319 To: "9024821288" ;tag=as1f83b9a5 CSeq: 1 INVITE Call-ID: 5a838208265dab0d413732396f10951c@172.16.120.75 Contact: Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER User-Agent: PolycomSoundPointIP-SPIP_501-UA/3.1.3.0439 Accept-Language: en-ca,en;q=0.9 Supported: 100rel,replaces Allow-Events: talk,hold,conference Max-Forwards: 70 Content-Type: application/sdp Content-Length: 213 v=0 o=- 1255537307 1255537308 IN IP4 172.22.120.78 s=Polycom IP Phone c=IN IP4 172.22.120.78 t=0 0 a=sendonly m=audio 2240 RTP/AVP 0 101 a=sendonly a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 <-------------> --- (15 headers 10 lines) --- Sending to 172.22.120.78 : 5060 (no NAT) Found RTP audio format 0 Found RTP audio format 101 Peer audio RTP is at port 172.22.120.78:2240 Found audio description format PCMU for ID 0 Found audio description format telephone-event for ID 101 Capabilities: us - 0x404 (ulaw|ilbc), peer - audio=0x4 (ulaw)/video=0x0 (nothing), combined - 0x4 (ulaw) Non-codec capabilities (dtmf): us - 0x1 (telephone-event), peer - 0x1 (telephone-event), combined - 0x1 (telephone-event) Peer audio RTP is at port 172.22.120.78:2240 <--- Transmitting (no NAT) to 172.22.120.78:5060 ---> SIP/2.0 100 Trying Via: SIP/2.0/UDP 172.22.120.78;branch=z9hG4bKb0d85f7DED48C0E;received=172.22.120.78 From: ;tag=5E437550-3EEDC319 To: "9024821288" ;tag=as1f83b9a5 Call-ID: 5a838208265dab0d413732396f10951c@172.16.120.75 CSeq: 1 INVITE User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO Supported: replaces Contact: Content-Length: 0 <------------> Audio is at 172.16.120.75 port 15098 Adding codec 0x4 (ulaw) to SDP Adding non-codec 0x1 (telephone-event) to SDP <--- Reliably Transmitting (no NAT) to 172.22.120.78:5060 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 172.22.120.78;branch=z9hG4bKb0d85f7DED48C0E;received=172.22.120.78 From: ;tag=5E437550-3EEDC319 To: "9024821288" ;tag=as1f83b9a5 Call-ID: 5a838208265dab0d413732396f10951c@172.16.120.75 CSeq: 1 INVITE User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO Supported: replaces Contact: Content-Type: application/sdp Content-Length: 240 v=0 o=root 2801 2802 IN IP4 172.16.120.75 s=session c=IN IP4 172.16.120.75 t=0 0 m=audio 15098 RTP/AVP 0 101 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=silenceSupp:off - - - - a=ptime:20 a=recvonly <------------> -- Started music on hold, class 'default', on IAX2/Paragon-BDA-14413 <--- SIP read from 172.22.120.78:5060 ---> ACK sip:9024821288@172.16.120.75 SIP/2.0 Via: SIP/2.0/UDP 172.22.120.78;branch=z9hG4bKdc58e9541A9048C From: ;tag=5E437550-3EEDC319 To: "9024821288" ;tag=as1f83b9a5 CSeq: 1 ACK Call-ID: 5a838208265dab0d413732396f10951c@172.16.120.75 Contact: Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER User-Agent: PolycomSoundPointIP-SPIP_501-UA/3.1.3.0439 Accept-Language: en-ca,en;q=0.9 Max-Forwards: 70 Content-Length: 0 <-------------> --- (12 headers 0 lines) --- <--- SIP read from 172.16.121.25:5060 ---> <-------------> <--- SIP read from 172.22.120.78:5060 ---> INVITE sip:6001@172.16.120.75:5060;user=phone SIP/2.0 Via: SIP/2.0/UDP 172.22.120.78;branch=z9hG4bKcd4c56ca43755111 From: "Darrin Henshaw" ;tag=E21B1804-74B0CA8D To: CSeq: 1 INVITE Call-ID: 9960fac8-5e806886-fba9faef@172.22.120.78 Contact: Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER User-Agent: PolycomSoundPointIP-SPIP_501-UA/3.1.3.0439 Accept-Language: en-ca,en;q=0.9 Supported: 100rel,replaces Allow-Events: talk,hold,conference Max-Forwards: 70 Content-Type: application/sdp Content-Length: 260 v=0 o=- 1255537313 1255537313 IN IP4 172.22.120.78 s=Polycom IP Phone c=IN IP4 172.22.120.78 t=0 0 m=audio 2242 RTP/AVP 0 8 18 101 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:18 G729/8000 a=fmtp:18 annexb=no a=rtpmap:101 telephone-event/8000 <-------------> --- (15 headers 11 lines) --- Sending to 172.22.120.78 : 5060 (no NAT) Using INVITE request as basis request - 9960fac8-5e806886-fba9faef@172.22.120.78 <--- Reliably Transmitting (no NAT) to 172.22.120.78:5060 ---> SIP/2.0 407 Proxy Authentication Required Via: SIP/2.0/UDP 172.22.120.78;branch=z9hG4bKcd4c56ca43755111;received=172.22.120.78 From: "Darrin Henshaw" ;tag=E21B1804-74B0CA8D To: ;tag=as7da98d4c Call-ID: 9960fac8-5e806886-fba9faef@172.22.120.78 CSeq: 1 INVITE User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO Supported: replaces Proxy-Authenticate: Digest algorithm=MD5, realm="asterisk", nonce="329c70a8" Content-Length: 0 <------------> Scheduling destruction of SIP dialog '9960fac8-5e806886-fba9faef@172.22.120.78' in 32000 ms (Method: INVITE) Found user '6002' <--- SIP read from 172.22.120.78:5060 ---> ACK sip:6001@172.16.120.75:5060 SIP/2.0 Via: SIP/2.0/UDP 172.22.120.78;branch=z9hG4bKcd4c56ca43755111 From: "Darrin Henshaw" ;tag=E21B1804-74B0CA8D To: ;tag=as7da98d4c CSeq: 1 ACK Call-ID: 9960fac8-5e806886-fba9faef@172.22.120.78 Contact: Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER User-Agent: PolycomSoundPointIP-SPIP_501-UA/3.1.3.0439 Accept-Language: en-ca,en;q=0.9 Max-Forwards: 70 Content-Length: 0 <-------------> --- (12 headers 0 lines) --- <--- SIP read from 172.22.120.78:5060 ---> INVITE sip:6001@172.16.120.75:5060;user=phone SIP/2.0 Via: SIP/2.0/UDP 172.22.120.78;branch=z9hG4bK1099b7eb7E588142 From: "Darrin Henshaw" ;tag=E21B1804-74B0CA8D To: CSeq: 2 INVITE Call-ID: 9960fac8-5e806886-fba9faef@172.22.120.78 Contact: Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER User-Agent: PolycomSoundPointIP-SPIP_501-UA/3.1.3.0439 Accept-Language: en-ca,en;q=0.9 Supported: 100rel,replaces Allow-Events: talk,hold,conference Proxy-Authorization: Digest username="6002", realm="asterisk", nonce="329c70a8", uri="sip:6001@172.16.120.75:5060;user=phone", response="8902300ca3230973bf555d0cafbc5b00", algorithm=MD5 Max-Forwards: 70 Content-Type: application/sdp Content-Length: 260 v=0 o=- 1255537313 1255537313 IN IP4 172.22.120.78 s=Polycom IP Phone c=IN IP4 172.22.120.78 t=0 0 m=audio 2242 RTP/AVP 0 8 18 101 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:18 G729/8000 a=fmtp:18 annexb=no a=rtpmap:101 telephone-event/8000 <-------------> --- (16 headers 11 lines) --- Sending to 172.22.120.78 : 5060 (no NAT) Using INVITE request as basis request - 9960fac8-5e806886-fba9faef@172.22.120.78 Found user '6002' Found RTP audio format 0 Found RTP audio format 8 Found RTP audio format 18 Found RTP audio format 101 Peer audio RTP is at port 172.22.120.78:2242 Found audio description format PCMU for ID 0 Found audio description format PCMA for ID 8 Found audio description format G729 for ID 18 Got unsupported a:fmtp in SDP offer Found audio description format telephone-event for ID 101 Capabilities: us - 0x404 (ulaw|ilbc), peer - audio=0x10c (ulaw|alaw|g729)/video=0x0 (nothing), combined - 0x4 (ulaw) Non-codec capabilities (dtmf): us - 0x1 (telephone-event), peer - 0x1 (telephone-event), combined - 0x1 (telephone-event) Peer audio RTP is at port 172.22.120.78:2242 Looking for 6001 in outgoing-extensions (domain 172.16.120.75) list_route: hop: <--- Transmitting (no NAT) to 172.22.120.78:5060 ---> SIP/2.0 100 Trying Via: SIP/2.0/UDP 172.22.120.78;branch=z9hG4bK1099b7eb7E588142;received=172.22.120.78 From: "Darrin Henshaw" ;tag=E21B1804-74B0CA8D To: Call-ID: 9960fac8-5e806886-fba9faef@172.22.120.78 CSeq: 2 INVITE User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO Supported: replaces Contact: Content-Length: 0 <------------> -- Executing [6001@outgoing-extensions:1] Goto("SIP/6002-08593e70", "extensions|6001|1") in new stack -- Goto (extensions,6001,1) -- Executing [6001@extensions:1] Dial("SIP/6002-08593e70", "SIP/6001") in new stack Audio is at 172.16.120.75 port 12346 Adding codec 0x4 (ulaw) to SDP Adding codec 0x400 (ilbc) to SDP Adding non-codec 0x1 (telephone-event) to SDP Reliably Transmitting (no NAT) to 172.22.120.77:5060: INVITE sip:6001@172.22.120.77 SIP/2.0 Via: SIP/2.0/UDP 172.16.120.75:5060;branch=z9hG4bK432ada97;rport From: "Test Line" ;tag=as0c6c6d0e To: Contact: Call-ID: 6ca8057727fb59d343acfd7178d04c2e@172.16.120.75 CSeq: 102 INVITE User-Agent: Asterisk PBX Max-Forwards: 70 Date: Wed, 14 Oct 2009 16:48:13 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO Supported: replaces Content-Type: application/sdp Content-Length: 285 v=0 o=root 2801 2801 IN IP4 172.16.120.75 s=session c=IN IP4 172.16.120.75 t=0 0 m=audio 12346 RTP/AVP 0 97 101 a=rtpmap:0 PCMU/8000 a=rtpmap:97 iLBC/8000 a=fmtp:97 mode=30 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=silenceSupp:off - - - - a=ptime:20 a=sendrecv --- -- Called 6001 <--- SIP read from 172.22.120.77:5060 ---> SIP/2.0 100 Trying Via: SIP/2.0/UDP 172.16.120.75:5060;branch=z9hG4bK432ada97;rport From: "Test Line" ;tag=as0c6c6d0e To: ;tag=6664EC6F-3E8A585A CSeq: 102 INVITE Call-ID: 6ca8057727fb59d343acfd7178d04c2e@172.16.120.75 Contact: User-Agent: PolycomSoundPointIP-SPIP_501-UA/3.1.3.0439 Accept-Language: en Content-Length: 0 <-------------> --- (10 headers 0 lines) --- <--- SIP read from 172.22.120.77:5060 ---> SIP/2.0 180 Ringing Via: SIP/2.0/UDP 172.16.120.75:5060;branch=z9hG4bK432ada97;rport From: "Test Line" ;tag=as0c6c6d0e To: ;tag=6664EC6F-3E8A585A CSeq: 102 INVITE Call-ID: 6ca8057727fb59d343acfd7178d04c2e@172.16.120.75 Contact: User-Agent: PolycomSoundPointIP-SPIP_501-UA/3.1.3.0439 Allow-Events: talk,hold,conference Accept-Language: en Content-Length: 0 <-------------> --- (11 headers 0 lines) --- -- SIP/6001-085b0ee0 is ringing <--- Transmitting (no NAT) to 172.22.120.78:5060 ---> SIP/2.0 180 Ringing Via: SIP/2.0/UDP 172.22.120.78;branch=z9hG4bK1099b7eb7E588142;received=172.22.120.78 From: "Darrin Henshaw" ;tag=E21B1804-74B0CA8D To: ;tag=as7d326c8b Call-ID: 9960fac8-5e806886-fba9faef@172.22.120.78 CSeq: 2 INVITE User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO Supported: replaces Contact: Content-Length: 0 <------------> <--- SIP read from 172.22.120.78:5060 ---> REFER sip:9024821288@172.16.120.75 SIP/2.0 Via: SIP/2.0/UDP 172.22.120.78;branch=z9hG4bK2f4cbe7442C60FE From: ;tag=5E437550-3EEDC319 To: "9024821288" ;tag=as1f83b9a5 CSeq: 2 REFER Call-ID: 5a838208265dab0d413732396f10951c@172.16.120.75 Contact: User-Agent: PolycomSoundPointIP-SPIP_501-UA/3.1.3.0439 Accept-Language: en-ca,en;q=0.9 Refer-To: Referred-By: Max-Forwards: 70 Content-Length: 0 <-------------> --- (13 headers 0 lines) --- Call 5a838208265dab0d413732396f10951c@172.16.120.75 got a SIP call transfer from caller: (REFER)! SIP transfer to extension 6001@outgoing-extensions by 6002@172.16.120.75 <--- Transmitting (no NAT) to 172.22.120.78:5060 ---> SIP/2.0 202 Accepted Via: SIP/2.0/UDP 172.22.120.78;branch=z9hG4bK2f4cbe7442C60FE;received=172.22.120.78 From: ;tag=5E437550-3EEDC319 To: "9024821288" ;tag=as1f83b9a5 Call-ID: 5a838208265dab0d413732396f10951c@172.16.120.75 CSeq: 2 REFER User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO Supported: replaces Contact: Content-Length: 0 <------------> -- Stopped music on hold on IAX2/Paragon-BDA-14413 set_destination: Parsing for address/port to send to set_destination: set destination to 172.22.120.78, port 5060 Reliably Transmitting (no NAT) to 172.22.120.78:5060: NOTIFY sip:6002@172.22.120.78 SIP/2.0 Via: SIP/2.0/UDP 172.16.120.75:5060;branch=z9hG4bK621e0b6a;rport From: "9024821288" ;tag=as1f83b9a5 To: ;tag=5E437550-3EEDC319 Contact: Call-ID: 5a838208265dab0d413732396f10951c@172.16.120.75 CSeq: 103 NOTIFY User-Agent: Asterisk PBX Max-Forwards: 70 Event: refer;id=2 Subscription-state: terminated;reason=noresource Content-Type: message/sipfrag;version=2.0 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO Supported: replaces Content-Length: 16 SIP/2.0 200 OK --- Scheduling destruction of SIP dialog '9960fac8-5e806886-fba9faef@172.22.120.78' in 32000 ms (Method: INVITE) <--- Reliably Transmitting (no NAT) to 172.22.120.78:5060 ---> SIP/2.0 603 Declined Via: SIP/2.0/UDP 172.22.120.78;branch=z9hG4bK1099b7eb7E588142;received=172.22.120.78 From: "Darrin Henshaw" ;tag=E21B1804-74B0CA8D To: ;tag=as7d326c8b Call-ID: 9960fac8-5e806886-fba9faef@172.22.120.78 CSeq: 2 INVITE User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO Supported: replaces Content-Length: 0 <------------> Tx-Frame Retry[000] -- OSeqno: 013 ISeqno: 012 Type: CONTROL Subclass: RINGING Timestamp: 19023ms SCall: 14413 DCall: 02433 [172.22.123.57:4569] <--- SIP read from 172.22.120.78:5060 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 172.16.120.75:5060;branch=z9hG4bK621e0b6a;rport From: "9024821288" ;tag=as1f83b9a5 To: ;tag=5E437550-3EEDC319 CSeq: 103 NOTIFY Call-ID: 5a838208265dab0d413732396f10951c@172.16.120.75 Contact: Event: refer;id=2 User-Agent: PolycomSoundPointIP-SPIP_501-UA/3.1.3.0439 Accept-Language: en-ca,en;q=0.9 Content-Length: 0 <-------------> --- (11 headers 0 lines) --- SIP Response message for INCOMING dialog NOTIFY arrived <--- SIP read from 172.22.120.78:5060 ---> BYE sip:9024821288@172.16.120.75 SIP/2.0 Via: SIP/2.0/UDP 172.22.120.78;branch=z9hG4bK8c9e2855884C77C From: ;tag=5E437550-3EEDC319 To: "9024821288" ;tag=as1f83b9a5 CSeq: 3 BYE Call-ID: 5a838208265dab0d413732396f10951c@172.16.120.75 Contact: User-Agent: PolycomSoundPointIP-SPIP_501-UA/3.1.3.0439 Accept-Language: en-ca,en;q=0.9 Max-Forwards: 70 Content-Length: 0 <-------------> --- (11 headers 0 lines) --- Sending to 172.22.120.78 : 5060 (no NAT) <--- Transmitting (no NAT) to 172.22.120.78:5060 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 172.22.120.78;branch=z9hG4bK8c9e2855884C77C;received=172.22.120.78 From: ;tag=5E437550-3EEDC319 To: "9024821288" ;tag=as1f83b9a5 Call-ID: 5a838208265dab0d413732396f10951c@172.16.120.75 CSeq: 3 BYE User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO Supported: replaces Content-Length: 0 <------------> Scheduling destruction of SIP dialog '5a838208265dab0d413732396f10951c@172.16.120.75' in 6400 ms (Method: BYE) == Spawn extension (main-line, s, 10) exited non-zero on 'SIP/6002-08593e70' Segmentation fault (core dumped)