[Jul 5 16:53:08] Asterisk SVN-trunk-r393757 built by root @ rnewton-office-lab on a i686 running Linux on 2013-07-05 20:38:29 UTC [Jul 5 16:53:08] VERBOSE[29879] config.c: == Parsing '/etc/asterisk/logger.conf': Found [Jul 5 16:53:08] VERBOSE[29879] logger.c: Asterisk Queue Logger restarted [Jul 5 16:53:19] VERBOSE[29861] res_sip_logger.c: <--- Received SIP request (878 bytes) from UDP:10.24.18.16:5060 ---> INVITE sip:6002@10.24.17.228 SIP/2.0 Via: SIP/2.0/UDP 10.24.18.16:5060;rport;branch=z9hG4bKPjrBts9GqxiLUbdcxT11g5kH6gWywiDyXu Max-Forwards: 70 From: "RustyONE" ;tag=CgRKq5d.yYn3sWNSXDs07Snc.xOPiY-J To: Contact: "RustyONE" Call-ID: viK-ckG11ziQiTtcfJZDz1xCDKX0FRZF CSeq: 11029 INVITE Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS Supported: replaces, 100rel, timer, norefersub Session-Expires: 1800 Min-SE: 90 User-Agent: Digium D40 1_3_2_0_54993 Content-Type: application/sdp Content-Length: 240 v=0 o=- 68432491 68432491 IN IP4 10.24.18.16 s=digphn c=IN IP4 10.24.18.16 t=0 0 a=X-nat:0 m=audio 4008 RTP/AVP 0 96 a=rtcp:4009 IN IP4 10.24.18.16 a=rtpmap:0 PCMU/8000 a=sendrecv a=rtpmap:96 telephone-event/8000 a=fmtp:96 0-15 [Jul 5 16:53:19] VERBOSE[29921] res_sip_logger.c: <--- Transmitting SIP response (523 bytes) to UDP:10.24.18.16:5060 ---> SIP/2.0 401 Unauthorized Via: SIP/2.0/UDP 10.24.18.16:5060;rport;received=10.24.18.16;branch=z9hG4bKPjrBts9GqxiLUbdcxT11g5kH6gWywiDyXu Call-ID: viK-ckG11ziQiTtcfJZDz1xCDKX0FRZF From: "RustyONE" ;tag=CgRKq5d.yYn3sWNSXDs07Snc.xOPiY-J To: ;tag=z9hG4bKPjrBts9GqxiLUbdcxT11g5kH6gWywiDyXu CSeq: 11029 INVITE WWW-Authenticate: Digest realm="asterisk",nonce="1373061199/a74d92ffa7ed954b268d0745e0be945b",opaque="0ae0e60f205258b8",algorithm=md5,qop="auth" Content-Length: 0 [Jul 5 16:53:19] VERBOSE[29861] res_sip_logger.c: <--- Received SIP request (379 bytes) from UDP:10.24.18.16:5060 ---> ACK sip:6002@10.24.17.228 SIP/2.0 Via: SIP/2.0/UDP 10.24.18.16:5060;rport;branch=z9hG4bKPjrBts9GqxiLUbdcxT11g5kH6gWywiDyXu Max-Forwards: 70 From: "RustyONE" ;tag=CgRKq5d.yYn3sWNSXDs07Snc.xOPiY-J To: ;tag=z9hG4bKPjrBts9GqxiLUbdcxT11g5kH6gWywiDyXu Call-ID: viK-ckG11ziQiTtcfJZDz1xCDKX0FRZF CSeq: 11029 ACK Content-Length: 0 [Jul 5 16:53:19] VERBOSE[29861] res_sip_logger.c: <--- Received SIP request (1170 bytes) from UDP:10.24.18.16:5060 ---> INVITE sip:6002@10.24.17.228 SIP/2.0 Via: SIP/2.0/UDP 10.24.18.16:5060;rport;branch=z9hG4bKPjQ8oMJ0kdrAc3uWaujX5y67BL8M2ZdENX Max-Forwards: 70 From: "RustyONE" ;tag=CgRKq5d.yYn3sWNSXDs07Snc.xOPiY-J To: Contact: "RustyONE" Call-ID: viK-ckG11ziQiTtcfJZDz1xCDKX0FRZF CSeq: 11030 INVITE Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS Supported: replaces, 100rel, timer, norefersub Session-Expires: 1800 Min-SE: 90 User-Agent: Digium D40 1_3_2_0_54993 Authorization: Digest username="6001", realm="asterisk", nonce="1373061199/a74d92ffa7ed954b268d0745e0be945b", uri="sip:6002@10.24.17.228", response="445a1c75df94f6e955bb4a0dd4846fde", algorithm=md5, cnonce="4lERZSU41o56Kua19vc3Tb1ls8xLUyK2", opaque="0ae0e60f205258b8", qop=auth, nc=00000001 Content-Type: application/sdp Content-Length: 240 v=0 o=- 68432491 68432491 IN IP4 10.24.18.16 s=digphn c=IN IP4 10.24.18.16 t=0 0 a=X-nat:0 m=audio 4008 RTP/AVP 0 96 a=rtcp:4009 IN IP4 10.24.18.16 a=rtpmap:0 PCMU/8000 a=sendrecv a=rtpmap:96 telephone-event/8000 a=fmtp:96 0-15 [Jul 5 16:53:19] VERBOSE[29921] res_sip_logger.c: <--- Transmitting SIP response (324 bytes) to UDP:10.24.18.16:5060 ---> SIP/2.0 100 Trying Via: SIP/2.0/UDP 10.24.18.16:5060;rport;received=10.24.18.16;branch=z9hG4bKPjQ8oMJ0kdrAc3uWaujX5y67BL8M2ZdENX Call-ID: viK-ckG11ziQiTtcfJZDz1xCDKX0FRZF From: "RustyONE" ;tag=CgRKq5d.yYn3sWNSXDs07Snc.xOPiY-J To: CSeq: 11030 INVITE Content-Length: 0 [Jul 5 16:53:19] VERBOSE[29929][C-00000004] pbx.c: -- Executing [6002@internal:1] Dial("Gulp/6001-00000008", "GULP/6002") in new stack [Jul 5 16:53:19] VERBOSE[29921] res_sip_logger.c: <--- Transmitting SIP request (931 bytes) to UDP:10.24.18.138:5060 ---> INVITE sip:6002@10.24.18.138:5060 SIP/2.0 Via: SIP/2.0/UDP 10.24.17.228:5060;rport;branch=z9hG4bKPj3a7cb891-9e67-4ba8-a054-47ba0932b9bf Max-Forwards: 70 From: ;tag=a5e6668b-aadd-41cd-8f84-6d6a2a069ecf To: sip:6002@10.24.18.138 Contact: Call-ID: 2ff77642-33ef-4cb9-a1c4-23a3af48b5f1 CSeq: 5864 INVITE Allow: OPTIONS, SUBSCRIBE, NOTIFY, PUBLISH, INVITE, ACK, BYE, CANCEL, UPDATE, PRACK, MESSAGE, REFER, REGISTER Supported: 100rel, timer, replaces, norefersub Session-Expires: 1800 Min-SE: 90 Content-Type: application/sdp Content-Length: 257 v=0 o=Asterisk 2141095964 2141095964 IN IP4 rnewton-office-lab s=Asterisk c=IN IP4 10.24.17.228 t=0 0 m=audio 10336 RTP/AVP 0 101 c=IN IP4 10.24.17.228 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 a=sendrecv [Jul 5 16:53:19] VERBOSE[29861] res_sip_logger.c: <--- Received SIP response (363 bytes) from UDP:10.24.18.138:5060 ---> SIP/2.0 100 Trying Via: SIP/2.0/UDP 10.24.17.228:5060;rport=5060;received=10.24.17.228;branch=z9hG4bKPj3a7cb891-9e67-4ba8-a054-47ba0932b9bf Call-ID: 2ff77642-33ef-4cb9-a1c4-23a3af48b5f1 From: ;tag=a5e6668b-aadd-41cd-8f84-6d6a2a069ecf To: CSeq: 5864 INVITE Content-Length: 0 [Jul 5 16:53:19] VERBOSE[29929][C-00000004] app_dial.c: -- Called GULP/6002 [Jul 5 16:53:19] VERBOSE[29861] res_sip_logger.c: <--- Received SIP response (581 bytes) from UDP:10.24.18.138:5060 ---> SIP/2.0 180 ringing Via: SIP/2.0/UDP 10.24.17.228:5060;rport=5060;received=10.24.17.228;branch=z9hG4bKPj3a7cb891-9e67-4ba8-a054-47ba0932b9bf Call-ID: 2ff77642-33ef-4cb9-a1c4-23a3af48b5f1 From: ;tag=a5e6668b-aadd-41cd-8f84-6d6a2a069ecf To: ;tag=HrMGO6aMMBzvRM1EbO70sGEgg4Cw9AGY CSeq: 5864 INVITE Contact: "RustyTWO" Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS User-Agent: Digium D40 1_3_2_0_54993 Content-Length: 0 [Jul 5 16:53:19] VERBOSE[29929][C-00000004] app_dial.c: -- Gulp/6002-00000009 is ringing [Jul 5 16:53:19] VERBOSE[29922] res_sip_logger.c: <--- Transmitting SIP response (511 bytes) to UDP:10.24.18.16:5060 ---> SIP/2.0 180 Ringing Via: SIP/2.0/UDP 10.24.18.16:5060;rport;received=10.24.18.16;branch=z9hG4bKPjQ8oMJ0kdrAc3uWaujX5y67BL8M2ZdENX Call-ID: viK-ckG11ziQiTtcfJZDz1xCDKX0FRZF From: "RustyONE" ;tag=CgRKq5d.yYn3sWNSXDs07Snc.xOPiY-J To: ;tag=f35a7fd8-a508-480f-a556-6f741f907990 CSeq: 11030 INVITE Contact: Allow: OPTIONS, SUBSCRIBE, NOTIFY, PUBLISH, INVITE, ACK, BYE, CANCEL, UPDATE, PRACK, MESSAGE, REFER, REGISTER Content-Length: 0 [Jul 5 16:53:24] VERBOSE[29861] res_sip_logger.c: <--- Received SIP request (377 bytes) from UDP:10.24.18.16:5060 ---> CANCEL sip:6002@10.24.17.228 SIP/2.0 Via: SIP/2.0/UDP 10.24.18.16:5060;rport;branch=z9hG4bKPjQ8oMJ0kdrAc3uWaujX5y67BL8M2ZdENX Max-Forwards: 70 From: "RustyONE" ;tag=CgRKq5d.yYn3sWNSXDs07Snc.xOPiY-J To: Call-ID: viK-ckG11ziQiTtcfJZDz1xCDKX0FRZF CSeq: 11030 CANCEL User-Agent: Digium D40 1_3_2_0_54993 Content-Length: 0 [Jul 5 16:53:24] VERBOSE[29921] res_sip_logger.c: <--- Transmitting SIP response (361 bytes) to UDP:10.24.18.16:5060 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 10.24.18.16:5060;rport;received=10.24.18.16;branch=z9hG4bKPjQ8oMJ0kdrAc3uWaujX5y67BL8M2ZdENX Call-ID: viK-ckG11ziQiTtcfJZDz1xCDKX0FRZF From: "RustyONE" ;tag=CgRKq5d.yYn3sWNSXDs07Snc.xOPiY-J To: ;tag=f35a7fd8-a508-480f-a556-6f741f907990 CSeq: 11030 CANCEL Content-Length: 0 [Jul 5 16:53:24] VERBOSE[29921] res_sip_logger.c: <--- Transmitting SIP response (488 bytes) to UDP:10.24.18.16:5060 ---> SIP/2.0 487 Request Terminated Via: SIP/2.0/UDP 10.24.18.16:5060;rport;received=10.24.18.16;branch=z9hG4bKPjQ8oMJ0kdrAc3uWaujX5y67BL8M2ZdENX Call-ID: viK-ckG11ziQiTtcfJZDz1xCDKX0FRZF From: "RustyONE" ;tag=CgRKq5d.yYn3sWNSXDs07Snc.xOPiY-J To: ;tag=f35a7fd8-a508-480f-a556-6f741f907990 CSeq: 11030 INVITE Allow: OPTIONS, SUBSCRIBE, NOTIFY, PUBLISH, INVITE, ACK, BYE, CANCEL, UPDATE, PRACK, MESSAGE, REFER, REGISTER Content-Length: 0 [Jul 5 16:53:24] VERBOSE[29861] res_sip_logger.c: <--- Received SIP request (374 bytes) from UDP:10.24.18.16:5060 ---> ACK sip:6002@10.24.17.228 SIP/2.0 Via: SIP/2.0/UDP 10.24.18.16:5060;rport;branch=z9hG4bKPjQ8oMJ0kdrAc3uWaujX5y67BL8M2ZdENX Max-Forwards: 70 From: "RustyONE" ;tag=CgRKq5d.yYn3sWNSXDs07Snc.xOPiY-J To: ;tag=f35a7fd8-a508-480f-a556-6f741f907990 Call-ID: viK-ckG11ziQiTtcfJZDz1xCDKX0FRZF CSeq: 11030 ACK Content-Length: 0 [Jul 5 16:53:24] VERBOSE[29921] res_sip_logger.c: <--- Transmitting SIP request (398 bytes) to UDP:10.24.18.138:5060 ---> CANCEL sip:6002@10.24.18.138:5060 SIP/2.0 Via: SIP/2.0/UDP 10.24.17.228:5060;rport;branch=z9hG4bKPj3a7cb891-9e67-4ba8-a054-47ba0932b9bf Max-Forwards: 70 From: ;tag=a5e6668b-aadd-41cd-8f84-6d6a2a069ecf To: sip:6002@10.24.18.138 Call-ID: 2ff77642-33ef-4cb9-a1c4-23a3af48b5f1 CSeq: 5864 CANCEL Reason: Q.850;cause=0 Content-Length: 0 [Jul 5 16:53:24] VERBOSE[29861] res_sip_logger.c: <--- Received SIP response (396 bytes) from UDP:10.24.18.138:5060 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 10.24.17.228:5060;rport=5060;received=10.24.17.228;branch=z9hG4bKPj3a7cb891-9e67-4ba8-a054-47ba0932b9bf Call-ID: 2ff77642-33ef-4cb9-a1c4-23a3af48b5f1 From: ;tag=a5e6668b-aadd-41cd-8f84-6d6a2a069ecf To: ;tag=HrMGO6aMMBzvRM1EbO70sGEgg4Cw9AGY CSeq: 5864 CANCEL Content-Length: 0 [Jul 5 16:53:24] VERBOSE[29861] res_sip_logger.c: <--- Received SIP response (542 bytes) from UDP:10.24.18.138:5060 ---> SIP/2.0 487 Request Terminated Via: SIP/2.0/UDP 10.24.17.228:5060;rport=5060;received=10.24.17.228;branch=z9hG4bKPj3a7cb891-9e67-4ba8-a054-47ba0932b9bf Call-ID: 2ff77642-33ef-4cb9-a1c4-23a3af48b5f1 From: ;tag=a5e6668b-aadd-41cd-8f84-6d6a2a069ecf To: ;tag=HrMGO6aMMBzvRM1EbO70sGEgg4Cw9AGY CSeq: 5864 INVITE Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS User-Agent: Digium D40 1_3_2_0_54993 Content-Length: 0 [Jul 5 16:53:24] VERBOSE[29922] res_sip_logger.c: <--- Transmitting SIP request (406 bytes) to UDP:10.24.18.138:5060 ---> ACK sip:6002@10.24.18.138:5060 SIP/2.0 Via: SIP/2.0/UDP 10.24.17.228:5060;rport;branch=z9hG4bKPj3a7cb891-9e67-4ba8-a054-47ba0932b9bf Max-Forwards: 70 From: ;tag=a5e6668b-aadd-41cd-8f84-6d6a2a069ecf To: sip:6002@10.24.18.138;tag=HrMGO6aMMBzvRM1EbO70sGEgg4Cw9AGY Call-ID: 2ff77642-33ef-4cb9-a1c4-23a3af48b5f1 CSeq: 5864 ACK Content-Length: 0 [Jul 5 16:53:24] VERBOSE[29929][C-00000004] pbx.c: == Spawn extension (internal, 6002, 1) exited non-zero on 'Gulp/6001-00000008'