/var/log/asterisk/full - last 2000 lines Jun 11 13:48:07 VERBOSE[3069] logger.c: Asterisk Event Logger restarted Jun 11 13:48:07 VERBOSE[3069] logger.c: Asterisk Queue Logger restarted Jun 11 13:48:15 VERBOSE[2180] logger.c: <-- SIP read from 212.27.52.5:5060: INVITE sip:s@87.90.132.119:5060;transport=udp SIP/2.0 Allow: UPDATE,REFER Call-ID: 01-01753-012b3615-34b3404f3@freephonie.net Contact: Content-Type: application/sdp CSeq: 19747685 INVITE From: ;tag=01-01753-012b3616-21ff30c64 Max-Forwards: 27 To: User-Agent: Cirpack/v4.40 (gw_sip) Via: SIP/2.0/UDP 212.27.52.5:5060;branch=z9hG4bK-6D61-9BA55 Content-Length: 170 v=0 o=cp10 115002649542 115002649543 IN IP4 172.25.7.5 s=SIP Call c=IN IP4 212.27.52.129 t=0 0 m=audio 31116 RTP/AVP 8 b=AS:64 a=rtpmap:8 PCMA/8000/1 a=ptime:30 Jun 11 13:48:15 DEBUG[2180] chan_sip.c: Header 0: INVITE sip:s@87.90.132.119:5060;transport=udp SIP/2.0 (53) Jun 11 13:48:15 DEBUG[2180] chan_sip.c: Header 1: Allow: UPDATE,REFER (19) Jun 11 13:48:15 DEBUG[2180] chan_sip.c: Header 2: Call-ID: 01-01753-012b3615-34b3404f3@freephonie.net (51) Jun 11 13:48:15 DEBUG[2180] chan_sip.c: Header 3: Contact: (31) Jun 11 13:48:15 DEBUG[2180] chan_sip.c: Header 4: Content-Type: application/sdp (29) Jun 11 13:48:15 DEBUG[2180] chan_sip.c: Header 5: CSeq: 19747685 INVITE (21) Jun 11 13:48:15 DEBUG[2180] chan_sip.c: Header 6: From: ;tag=01-01753-012b3616-21ff30c64 (80) Jun 11 13:48:15 DEBUG[2180] chan_sip.c: Header 7: Max-Forwards: 27 (16) Jun 11 13:48:15 DEBUG[2180] chan_sip.c: Header 8: To: (43) Jun 11 13:48:15 DEBUG[2180] chan_sip.c: Header 9: User-Agent: Cirpack/v4.40 (gw_sip) (34) Jun 11 13:48:15 DEBUG[2180] chan_sip.c: Header 10: Via: SIP/2.0/UDP 212.27.52.5:5060;branch=z9hG4bK-6D61-9BA55 (59) Jun 11 13:48:15 DEBUG[2180] chan_sip.c: Header 11: Content-Length: 170 (19) Jun 11 13:48:15 DEBUG[2180] chan_sip.c: Header 12: (0) Jun 11 13:48:15 DEBUG[2180] chan_sip.c: Line: v=0 (3) Jun 11 13:48:15 DEBUG[2180] chan_sip.c: Line: o=cp10 115002649542 115002649543 IN IP4 172.25.7.5 (50) Jun 11 13:48:15 DEBUG[2180] chan_sip.c: Line: s=SIP Call (10) Jun 11 13:48:15 DEBUG[2180] chan_sip.c: Line: c=IN IP4 212.27.52.129 (22) Jun 11 13:48:15 DEBUG[2180] chan_sip.c: Line: t=0 0 (5) Jun 11 13:48:15 DEBUG[2180] chan_sip.c: Line: m=audio 31116 RTP/AVP 8 (23) Jun 11 13:48:15 DEBUG[2180] chan_sip.c: Line: b=AS:64 (7) Jun 11 13:48:15 DEBUG[2180] chan_sip.c: Line: a=rtpmap:8 PCMA/8000/1 (22) Jun 11 13:48:15 DEBUG[2180] chan_sip.c: Line: a=ptime:30 (10) Jun 11 13:48:15 VERBOSE[2180] logger.c: --- (12 headers 9 lines)Jun 11 13:48:15 VERBOSE[2180] logger.c: --- (12 headers 9 lines)--- Jun 11 13:48:15 DEBUG[2180] acl.c: ##### Testing 212.27.52.5 with 192.168.1.0 Jun 11 13:48:15 DEBUG[2180] chan_sip.c: Target address 212.27.52.5 is not local, substituting externip Jun 11 13:48:15 DEBUG[2180] chan_sip.c: Allocating new SIP dialog for 01-01753-012b3615-34b3404f3@freephonie.net - INVITE (With RTP) Jun 11 13:48:15 DEBUG[2180] chan_sip.c: **** Received INVITE (5) - Command in SIP INVITE Jun 11 13:48:15 VERBOSE[2180] logger.c: Using INVITE request as basis request - 01-01753-012b3615-34b3404f3@freephonie.net Jun 11 13:48:15 VERBOSE[2180] logger.c: Sending to 212.27.52.5 : 5060 (NAT) Jun 11 13:48:15 VERBOSE[2180] logger.c: Found peer 'Free_Out' Jun 11 13:48:15 DEBUG[2180] chan_sip.c: Setting NAT on RTP to 524288 Jun 11 13:48:15 VERBOSE[2180] logger.c: Reliably Transmitting (NAT) to 212.27.52.5:5060: SIP/2.0 407 Proxy Authentication Required Via: SIP/2.0/UDP 212.27.52.5:5060;branch=z9hG4bK-6D61-9BA55;received=212.27.52.5 From: ;tag=01-01753-012b3616-21ff30c64 To: ;tag=as20b0a46e Call-ID: 01-01753-012b3615-34b3404f3@freephonie.net CSeq: 19747685 INVITE User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Contact: Proxy-Authenticate: Digest realm="asterisk", nonce="369914f2" Content-Length: 0 --- Jun 11 13:48:15 DEBUG[2180] chan_sip.c: *** SIP TIMER: Initalizing retransmit timer on packet: Id #942 Jun 11 13:48:15 VERBOSE[2180] logger.c: Scheduling destruction of call '01-01753-012b3615-34b3404f3@freephonie.net' in 15000 ms Jun 11 13:48:15 VERBOSE[2180] logger.c: <-- SIP read from 212.27.52.5:5060: ACK sip:s@87.90.132.119:5060;transport=udp SIP/2.0 Call-ID: 01-01753-012b3615-34b3404f3@freephonie.net Contact: CSeq: 19747685 ACK From: ;tag=01-01753-012b3616-21ff30c64 Max-Forwards: 27 To: ;tag=as20b0a46e Via: SIP/2.0/UDP 212.27.52.5:5060;branch=z9hG4bK-6D61-9BA55 Content-Length: 0 Jun 11 13:48:15 DEBUG[2180] chan_sip.c: Header 0: ACK sip:s@87.90.132.119:5060;transport=udp SIP/2.0 (50) Jun 11 13:48:15 DEBUG[2180] chan_sip.c: Header 1: Call-ID: 01-01753-012b3615-34b3404f3@freephonie.net (51) Jun 11 13:48:15 DEBUG[2180] chan_sip.c: Header 2: Contact: (31) Jun 11 13:48:15 DEBUG[2180] chan_sip.c: Header 3: CSeq: 19747685 ACK (18) Jun 11 13:48:15 DEBUG[2180] chan_sip.c: Header 4: From: ;tag=01-01753-012b3616-21ff30c64 (80) Jun 11 13:48:15 DEBUG[2180] chan_sip.c: Header 5: Max-Forwards: 27 (16) Jun 11 13:48:15 DEBUG[2180] chan_sip.c: Header 6: To: ;tag=as20b0a46e (58) Jun 11 13:48:15 DEBUG[2180] chan_sip.c: Header 7: Via: SIP/2.0/UDP 212.27.52.5:5060;branch=z9hG4bK-6D61-9BA55 (59) Jun 11 13:48:15 DEBUG[2180] chan_sip.c: Header 8: Content-Length: 0 (17) Jun 11 13:48:15 DEBUG[2180] chan_sip.c: Header 9: (0) Jun 11 13:48:15 VERBOSE[2180] logger.c: --- (9 headers 0 lines)Jun 11 13:48:15 VERBOSE[2180] logger.c: --- (9 headers 0 lines)--- Jun 11 13:48:15 DEBUG[2180] chan_sip.c: **** Received ACK (6) - Command in SIP ACK Jun 11 13:48:15 DEBUG[2180] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #942 Jun 11 13:48:15 DEBUG[2180] chan_sip.c: Stopping retransmission on '01-01753-012b3615-34b3404f3@freephonie.net' of Response 19747685: Match Found Jun 11 13:48:17 VERBOSE[2180] logger.c: <-- SIP read from 212.27.52.5:5060: Cirpack KeepAlive Packet Jun 11 13:48:17 DEBUG[2180] chan_sip.c: Header 0: Cirpack KeepAlive Packet (24) Jun 11 13:48:17 VERBOSE[2180] logger.c: --- (1 headers 0 lines)Jun 11 13:48:17 VERBOSE[2180] logger.c: --- (1 headers 0 lines)--- Jun 11 13:48:17 VERBOSE[2180] logger.c: <-- SIP read from 212.27.52.5:5060: Cirpack KeepAlive Packet Jun 11 13:48:17 DEBUG[2180] chan_sip.c: Header 0: Cirpack KeepAlive Packet (24) Jun 11 13:48:17 VERBOSE[2180] logger.c: --- (1 headers 0 lines)Jun 11 13:48:17 VERBOSE[2180] logger.c: --- (1 headers 0 lines)--- Jun 11 13:48:28 DEBUG[2180] chan_sip.c: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP) Jun 11 13:48:28 DEBUG[2180] acl.c: ##### Testing 192.168.1.43 with 192.168.1.0 Jun 11 13:48:28 DEBUG[2180] chan_sip.c: Header 0: OPTIONS sip:1001@192.168.1.43:5061 SIP/2.0 (42) Jun 11 13:48:28 DEBUG[2180] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.1.11:5060;branch=z9hG4bK70412103;rport (63) Jun 11 13:48:28 DEBUG[2180] chan_sip.c: Header 2: From: "Unknown" ;tag=as0759c746 (57) Jun 11 13:48:28 DEBUG[2180] chan_sip.c: Header 3: To: (32) Jun 11 13:48:28 DEBUG[2180] chan_sip.c: Header 4: Contact: (35) Jun 11 13:48:28 DEBUG[2180] chan_sip.c: Header 5: Call-ID: 6a867137361ce8b2774bbd4d271f31b8@192.168.1.11 (54) Jun 11 13:48:28 DEBUG[2180] chan_sip.c: Header 6: CSeq: 102 OPTIONS (17) Jun 11 13:48:28 DEBUG[2180] chan_sip.c: Header 7: User-Agent: Asterisk PBX (24) Jun 11 13:48:28 DEBUG[2180] chan_sip.c: Header 8: Max-Forwards: 70 (16) Jun 11 13:48:28 DEBUG[2180] chan_sip.c: Header 9: Date: Sun, 11 Jun 2006 11:48:28 GMT (35) Jun 11 13:48:28 DEBUG[2180] chan_sip.c: Header 10: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY (66) Jun 11 13:48:28 DEBUG[2180] chan_sip.c: Header 11: Content-Length: 0 (17) Jun 11 13:48:28 DEBUG[2180] chan_sip.c: Header 12: (0) Jun 11 13:48:28 VERBOSE[2180] logger.c: 12 headers, 0 lines Jun 11 13:48:28 VERBOSE[2180] logger.c: Reliably Transmitting (NAT) to 192.168.1.43:5061: OPTIONS sip:1001@192.168.1.43:5061 SIP/2.0 Via: SIP/2.0/UDP 192.168.1.11:5060;branch=z9hG4bK70412103;rport From: "Unknown" ;tag=as0759c746 To: Contact: Call-ID: 6a867137361ce8b2774bbd4d271f31b8@192.168.1.11 CSeq: 102 OPTIONS User-Agent: Asterisk PBX Max-Forwards: 70 Date: Sun, 11 Jun 2006 11:48:28 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Content-Length: 0 --- Jun 11 13:48:28 DEBUG[2180] chan_sip.c: *** SIP TIMER: Initalizing retransmit timer on packet: Id #944 Jun 11 13:48:28 VERBOSE[2180] logger.c: <-- SIP read from 192.168.1.43:5061: SIP/2.0 200 Ok Via: SIP/2.0/UDP 192.168.1.11:5060;branch=z9hG4bK70412103;rport From: "Unknown" ;tag=as0759c746 To: ;tag=2052935249 Contact: Call-ID: 6a867137361ce8b2774bbd4d271f31b8@192.168.1.11 Allow: INVITE,ACK,BYE,CANCEL,OPTIONS,NOTIFY CSeq: 102 OPTIONS Server: X-Lite release 1105d Content-Length: 0 Jun 11 13:48:28 DEBUG[2180] chan_sip.c: Header 0: SIP/2.0 200 Ok (14) Jun 11 13:48:28 DEBUG[2180] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.1.11:5060;branch=z9hG4bK70412103;rport (63) Jun 11 13:48:28 DEBUG[2180] chan_sip.c: Header 2: From: "Unknown" ;tag=as0759c746 (57) Jun 11 13:48:28 DEBUG[2180] chan_sip.c: Header 3: To: ;tag=2052935249 (47) Jun 11 13:48:28 DEBUG[2180] chan_sip.c: Header 4: Contact: (37) Jun 11 13:48:28 DEBUG[2180] chan_sip.c: Header 5: Call-ID: 6a867137361ce8b2774bbd4d271f31b8@192.168.1.11 (54) Jun 11 13:48:28 DEBUG[2180] chan_sip.c: Header 6: Allow: INVITE,ACK,BYE,CANCEL,OPTIONS,NOTIFY (43) Jun 11 13:48:28 DEBUG[2180] chan_sip.c: Header 7: CSeq: 102 OPTIONS (17) Jun 11 13:48:28 DEBUG[2180] chan_sip.c: Header 8: Server: X-Lite release 1105d (28) Jun 11 13:48:28 DEBUG[2180] chan_sip.c: Header 9: Content-Length: 0 (17) Jun 11 13:48:28 DEBUG[2180] chan_sip.c: Header 10: (0) Jun 11 13:48:28 VERBOSE[2180] logger.c: --- (10 headers 0 lines)Jun 11 13:48:28 VERBOSE[2180] logger.c: --- (10 headers 0 lines)--- Jun 11 13:48:28 DEBUG[2180] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #944 Jun 11 13:48:28 DEBUG[2180] chan_sip.c: Stopping retransmission on '6a867137361ce8b2774bbd4d271f31b8@192.168.1.11' of Request 102: Match Found Jun 11 13:48:28 VERBOSE[2180] logger.c: Destroying call '6a867137361ce8b2774bbd4d271f31b8@192.168.1.11' Jun 11 13:48:28 DEBUG[2180] chan_sip.c: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP) Jun 11 13:48:28 DEBUG[2180] acl.c: ##### Testing 212.27.52.5 with 192.168.1.0 Jun 11 13:48:31 DEBUG[2180] chan_sip.c: Target address 212.27.52.5 is not local, substituting externip Jun 11 13:48:31 DEBUG[2180] chan_sip.c: Header 0: OPTIONS sip:freephonie.net SIP/2.0 (34) Jun 11 13:48:31 DEBUG[2180] chan_sip.c: Header 1: Via: SIP/2.0/UDP 87.90.132.119:5060;branch=z9hG4bK32fc4acf;rport (64) Jun 11 13:48:31 DEBUG[2180] chan_sip.c: Header 2: From: "Unknown" ;tag=as08297385 (58) Jun 11 13:48:31 DEBUG[2180] chan_sip.c: Header 3: To: (24) Jun 11 13:48:31 DEBUG[2180] chan_sip.c: Header 4: Contact: (36) Jun 11 13:48:31 DEBUG[2180] chan_sip.c: Header 5: Call-ID: 16f2f61423c71428259458ce274bb4e8@87.90.132.119 (55) Jun 11 13:48:31 DEBUG[2180] chan_sip.c: Header 6: CSeq: 102 OPTIONS (17) Jun 11 13:48:31 DEBUG[2180] chan_sip.c: Header 7: User-Agent: Asterisk PBX (24) Jun 11 13:48:31 DEBUG[2180] chan_sip.c: Header 8: Max-Forwards: 70 (16) Jun 11 13:48:31 DEBUG[2180] chan_sip.c: Header 9: Date: Sun, 11 Jun 2006 11:48:31 GMT (35) Jun 11 13:48:31 DEBUG[2180] chan_sip.c: Header 10: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY (66) Jun 11 13:48:31 DEBUG[2180] chan_sip.c: Header 11: Content-Length: 0 (17) Jun 11 13:48:31 DEBUG[2180] chan_sip.c: Header 12: (0) Jun 11 13:48:31 VERBOSE[2180] logger.c: 12 headers, 0 lines Jun 11 13:48:31 VERBOSE[2180] logger.c: Reliably Transmitting (NAT) to 212.27.52.5:5060: OPTIONS sip:freephonie.net SIP/2.0 Via: SIP/2.0/UDP 87.90.132.119:5060;branch=z9hG4bK32fc4acf;rport From: "Unknown" ;tag=as08297385 To: Contact: Call-ID: 16f2f61423c71428259458ce274bb4e8@87.90.132.119 CSeq: 102 OPTIONS User-Agent: Asterisk PBX Max-Forwards: 70 Date: Sun, 11 Jun 2006 11:48:31 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Content-Length: 0 --- Jun 11 13:48:31 DEBUG[2180] chan_sip.c: *** SIP TIMER: Initalizing retransmit timer on packet: Id #947 Jun 11 13:48:31 DEBUG[2180] chan_sip.c: Auto destroying call '01-01753-012b3615-34b3404f3@freephonie.net' Jun 11 13:48:31 VERBOSE[2180] logger.c: Destroying call '01-01753-012b3615-34b3404f3@freephonie.net' Jun 11 13:48:31 VERBOSE[2180] logger.c: <-- SIP read from 212.27.52.5:5060: SIP/2.0 501 not implemented yet Call-ID: 16f2f61423c71428259458ce274bb4e8@87.90.132.119 CSeq: 102 OPTIONS From: "Unknown" ;tag=as08297385 To: ;tag=01-08155-012b3740-6b1cd8d92 Via: SIP/2.0/UDP 87.90.132.119:5060;received=87.90.132.119;rport=5060;branch=z9hG4bK32fc4acf Content-Length: 0 Jun 11 13:48:31 DEBUG[2180] chan_sip.c: Header 0: SIP/2.0 501 not implemented yet (31) Jun 11 13:48:31 DEBUG[2180] chan_sip.c: Header 1: Call-ID: 16f2f61423c71428259458ce274bb4e8@87.90.132.119 (55) Jun 11 13:48:31 DEBUG[2180] chan_sip.c: Header 2: CSeq: 102 OPTIONS (17) Jun 11 13:48:31 DEBUG[2180] chan_sip.c: Header 3: From: "Unknown" ;tag=as08297385 (58) Jun 11 13:48:31 DEBUG[2180] chan_sip.c: Header 4: To: ;tag=01-08155-012b3740-6b1cd8d92 (56) Jun 11 13:48:31 DEBUG[2180] chan_sip.c: Header 5: Via: SIP/2.0/UDP 87.90.132.119:5060;received=87.90.132.119;rport=5060;branch=z9hG4bK32fc4acf (92) Jun 11 13:48:31 DEBUG[2180] chan_sip.c: Header 6: Content-Length: 0 (17) Jun 11 13:48:31 DEBUG[2180] chan_sip.c: Header 7: (0) Jun 11 13:48:31 VERBOSE[2180] logger.c: --- (7 headers 0 lines)Jun 11 13:48:31 VERBOSE[2180] logger.c: --- (7 headers 0 lines)--- Jun 11 13:48:31 DEBUG[2180] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #947 Jun 11 13:48:31 DEBUG[2180] chan_sip.c: Stopping retransmission on '16f2f61423c71428259458ce274bb4e8@87.90.132.119' of Request 102: Match Found Jun 11 13:48:31 VERBOSE[2180] logger.c: Destroying call '16f2f61423c71428259458ce274bb4e8@87.90.132.119' Jun 11 13:48:31 DEBUG[3475] manager.c: Manager received command 'Command' Jun 11 13:48:31 DEBUG[3475] manager.c: Manager received command 'Command' Jun 11 13:48:31 VERBOSE[2180] logger.c: <-- SIP read from 192.168.1.43:5061: Jun 11 13:48:31 DEBUG[2180] chan_sip.c: Header 0: (0) Jun 11 13:48:31 VERBOSE[2180] logger.c: --- (0 headers 0 lines)Jun 11 13:48:31 VERBOSE[2180] logger.c: --- (0 headers 0 lines) Nat keepalive Jun 11 13:48:31 VERBOSE[2180] logger.c: --- (0 headers 0 lines) Nat keepalive --- Jun 11 13:48:31 VERBOSE[2180] logger.c: --- (0 headers 0 lines)Jun 11 13:48:31 VERBOSE[2180] logger.c: --- (0 headers 0 lines) Nat keepalive Jun 11 13:48:31 VERBOSE[2180] logger.c: --- (0 headers 0 lines) Nat keepalive ---