[Jun 22 19:17:26] VERBOSE[26518] config.c: == Parsing '/etc/asterisk/logger.conf': [Jun 22 19:17:26] DEBUG[26518] config.c: Parsing /etc/asterisk/logger.conf [Jun 22 19:17:26] VERBOSE[26518] config.c: == Found [Jun 22 19:17:26] VERBOSE[26518] logger.c: Asterisk Event Logger restarted [Jun 22 19:17:26] VERBOSE[26518] logger.c: Asterisk Queue Logger restarted [Jun 22 19:17:27] DEBUG[4682] chan_sip.c: Destroying SIP dialog 1616fdc1-ddf70745-7c49244@70.167.153.136 [Jun 22 19:17:27] VERBOSE[4682] chan_sip.c: Really destroying SIP dialog '1616fdc1-ddf70745-7c49244@70.167.153.136' Method: OPTIONS [Jun 22 19:17:33] DEBUG[4682] chan_sip.c: Allocating new SIP dialog for 6d48c0085417b8157d3a42f06d744dca@10.10.5.16 - OPTIONS (No RTP) [Jun 22 19:17:33] DEBUG[4682] acl.c: Found IP address for this socket [Jun 22 19:17:33] DEBUG[4682] chan_sip.c: Setting SIP_TRANSPORT_UDP with address 10.10.5.16:5060 [Jun 22 19:17:33] DEBUG[4682] chan_sip.c: Initializing initreq for method OPTIONS - callid 735b0c57480790eb08537b193aa42aaa@10.10.5.16 [Jun 22 19:17:33] DEBUG[4682] chan_sip.c: Header 0 [ 40]: OPTIONS sip:150@10.10.5.124:5060 SIP/2.0 [Jun 22 19:17:33] DEBUG[4682] chan_sip.c: Header 1 [ 61]: Via: SIP/2.0/UDP 10.10.5.16:5060;branch=z9hG4bK626babf9;rport [Jun 22 19:17:33] DEBUG[4682] chan_sip.c: Header 2 [ 16]: Max-Forwards: 70 [Jun 22 19:17:33] DEBUG[4682] chan_sip.c: Header 3 [ 55]: From: "Unknown" ;tag=as290591e1 [Jun 22 19:17:33] DEBUG[4682] chan_sip.c: Header 4 [ 30]: To: [Jun 22 19:17:33] DEBUG[4682] chan_sip.c: Header 5 [ 33]: Contact: [Jun 22 19:17:33] DEBUG[4682] chan_sip.c: Header 6 [ 52]: Call-ID: 735b0c57480790eb08537b193aa42aaa@10.10.5.16 [Jun 22 19:17:33] DEBUG[4682] chan_sip.c: Header 7 [ 17]: CSeq: 102 OPTIONS [Jun 22 19:17:33] DEBUG[4682] chan_sip.c: Header 8 [ 32]: User-Agent: Asterisk PBX 1.6.2.9 [Jun 22 19:17:33] DEBUG[4682] chan_sip.c: Header 9 [ 35]: Date: Tue, 22 Jun 2010 23:17:33 GMT [Jun 22 19:17:33] DEBUG[4682] chan_sip.c: Header 10 [ 72]: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO [Jun 22 19:17:33] DEBUG[4682] chan_sip.c: Header 11 [ 26]: Supported: replaces, timer [Jun 22 19:17:33] DEBUG[4682] chan_sip.c: Header 12 [ 17]: Content-Length: 0 [Jun 22 19:17:33] VERBOSE[4682] chan_sip.c: Reliably Transmitting (NAT) to 10.10.5.124:5060: OPTIONS sip:150@10.10.5.124:5060 SIP/2.0 Via: SIP/2.0/UDP 10.10.5.16:5060;branch=z9hG4bK626babf9;rport Max-Forwards: 70 From: "Unknown" ;tag=as290591e1 To: Contact: Call-ID: 735b0c57480790eb08537b193aa42aaa@10.10.5.16 CSeq: 102 OPTIONS User-Agent: Asterisk PBX 1.6.2.9 Date: Tue, 22 Jun 2010 23:17:33 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO Supported: replaces, timer Content-Length: 0 --- [Jun 22 19:17:33] DEBUG[4682] chan_sip.c: *** SIP TIMER: Initializing retransmit timer on packet: Id #138213 [Jun 22 19:17:33] DEBUG[4682] chan_sip.c: Trying to put 'OPTIONS sip' onto UDP socket destined for 10.10.5.124:5060 [Jun 22 19:17:33] VERBOSE[4682] chan_sip.c: <--- SIP read from UDP:10.10.5.124:5060 ---> SIP/2.0 200 OK To: ;tag=9348f7ccd535c00ei0 From: "Unknown" ;tag=as290591e1 Call-ID: 735b0c57480790eb08537b193aa42aaa@10.10.5.16 CSeq: 102 OPTIONS Via: SIP/2.0/UDP 10.10.5.16:5060;branch=z9hG4bK626babf9 Server: Linksys/SPA2102-3.3.6 Content-Length: 0 Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER Supported: x-sipura <-------------> [Jun 22 19:17:33] DEBUG[4682] chan_sip.c: Header 0 [ 14]: SIP/2.0 200 OK [Jun 22 19:17:33] DEBUG[4682] chan_sip.c: Header 1 [ 53]: To: ;tag=9348f7ccd535c00ei0 [Jun 22 19:17:33] DEBUG[4682] chan_sip.c: Header 2 [ 55]: From: "Unknown" ;tag=as290591e1 [Jun 22 19:17:33] DEBUG[4682] chan_sip.c: Header 3 [ 52]: Call-ID: 735b0c57480790eb08537b193aa42aaa@10.10.5.16 [Jun 22 19:17:33] DEBUG[4682] chan_sip.c: Header 4 [ 17]: CSeq: 102 OPTIONS [Jun 22 19:17:33] DEBUG[4682] chan_sip.c: Header 5 [ 55]: Via: SIP/2.0/UDP 10.10.5.16:5060;branch=z9hG4bK626babf9 [Jun 22 19:17:33] DEBUG[4682] chan_sip.c: Header 6 [ 29]: Server: Linksys/SPA2102-3.3.6 [Jun 22 19:17:33] DEBUG[4682] chan_sip.c: Header 7 [ 17]: Content-Length: 0 [Jun 22 19:17:33] DEBUG[4682] chan_sip.c: Header 8 [ 61]: Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER [Jun 22 19:17:33] DEBUG[4682] chan_sip.c: Header 9 [ 19]: Supported: x-sipura [Jun 22 19:17:33] DEBUG[4682] chan_sip.c: Header 10 [ 0]: [Jun 22 19:17:33] VERBOSE[4682] chan_sip.c: --- (10 headers 0 lines) --- [Jun 22 19:17:33] DEBUG[4682] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #138213 [Jun 22 19:17:33] DEBUG[4682] chan_sip.c: Stopping retransmission on '735b0c57480790eb08537b193aa42aaa@10.10.5.16' of Request 102: Match Found [Jun 22 19:17:33] DEBUG[4682] chan_sip.c: Destroying SIP dialog 735b0c57480790eb08537b193aa42aaa@10.10.5.16 [Jun 22 19:17:33] VERBOSE[4682] chan_sip.c: Really destroying SIP dialog '735b0c57480790eb08537b193aa42aaa@10.10.5.16' Method: OPTIONS [Jun 22 19:17:34] VERBOSE[4682] chan_sip.c: <--- SIP read from UDP:10.10.5.128:5060 ---> NOTIFY sip:10.10.5.16 SIP/2.0 Via: SIP/2.0/UDP 10.10.5.128:5060;branch=z9hG4bK-ffd5e62d From: ;tag=7f58383bbdd660f4o0 To: Call-ID: eba68027-8734d9f8@10.10.5.128 CSeq: 6 NOTIFY Max-Forwards: 70 Event: keep-alive User-Agent: Linksys/SPA941-5.1.8 Content-Length: 0 <-------------> [Jun 22 19:17:34] DEBUG[4682] chan_sip.c: Header 0 [ 29]: NOTIFY sip:10.10.5.16 SIP/2.0 [Jun 22 19:17:34] DEBUG[4682] chan_sip.c: Header 1 [ 57]: Via: SIP/2.0/UDP 10.10.5.128:5060;branch=z9hG4bK-ffd5e62d [Jun 22 19:17:34] DEBUG[4682] chan_sip.c: Header 2 [ 49]: From: ;tag=7f58383bbdd660f4o0 [Jun 22 19:17:34] DEBUG[4682] chan_sip.c: Header 3 [ 20]: To: [Jun 22 19:17:34] DEBUG[4682] chan_sip.c: Header 4 [ 38]: Call-ID: eba68027-8734d9f8@10.10.5.128 [Jun 22 19:17:34] DEBUG[4682] chan_sip.c: Header 5 [ 14]: CSeq: 6 NOTIFY [Jun 22 19:17:34] DEBUG[4682] chan_sip.c: Header 6 [ 16]: Max-Forwards: 70 [Jun 22 19:17:34] DEBUG[4682] chan_sip.c: Header 7 [ 17]: Event: keep-alive [Jun 22 19:17:34] DEBUG[4682] chan_sip.c: Header 8 [ 32]: User-Agent: Linksys/SPA941-5.1.8 [Jun 22 19:17:34] DEBUG[4682] chan_sip.c: Header 9 [ 17]: Content-Length: 0 [Jun 22 19:17:34] DEBUG[4682] chan_sip.c: Header 10 [ 0]: [Jun 22 19:17:34] VERBOSE[4682] chan_sip.c: --- (10 headers 0 lines) --- [Jun 22 19:17:34] DEBUG[4682] acl.c: Found IP address for this socket [Jun 22 19:17:34] DEBUG[4682] chan_sip.c: Setting SIP_TRANSPORT_UDP with address 10.10.5.16:5060 [Jun 22 19:17:34] VERBOSE[4682] chan_sip.c: <--- Transmitting (no NAT) to 10.10.5.128:5060 ---> SIP/2.0 489 Bad event Via: SIP/2.0/UDP 10.10.5.128:5060;branch=z9hG4bK-ffd5e62d;received=10.10.5.128 From: ;tag=7f58383bbdd660f4o0 To: ;tag=as21b7f261 Call-ID: eba68027-8734d9f8@10.10.5.128 CSeq: 6 NOTIFY Server: Asterisk PBX 1.6.2.9 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO Supported: replaces, timer Content-Length: 0 <------------> [Jun 22 19:17:34] DEBUG[4682] chan_sip.c: Trying to put 'SIP/2.0 489' onto UDP socket destined for 10.10.5.128:5060 [Jun 22 19:17:34] DEBUG[4682] chan_sip.c: Invalid SIP message - rejected , no callid, len 311 [Jun 22 19:17:34] DEBUG[4682] chan_sip.c: Allocating new SIP dialog for 0182f5b905f8faf12ef2223e7677c855@10.10.5.16 - OPTIONS (No RTP) [Jun 22 19:17:34] DEBUG[4682] acl.c: Found IP address for this socket [Jun 22 19:17:34] DEBUG[4682] chan_sip.c: Setting SIP_TRANSPORT_UDP with address 10.10.5.16:5060 [Jun 22 19:17:34] DEBUG[4682] chan_sip.c: Initializing initreq for method OPTIONS - callid 575ccf3852228bc272d9a8af70fd3f8e@10.10.5.16 [Jun 22 19:17:34] DEBUG[4682] chan_sip.c: Header 0 [ 40]: OPTIONS sip:151@10.10.5.124:5061 SIP/2.0 [Jun 22 19:17:34] DEBUG[4682] chan_sip.c: Header 1 [ 61]: Via: SIP/2.0/UDP 10.10.5.16:5060;branch=z9hG4bK0294ec65;rport [Jun 22 19:17:34] DEBUG[4682] chan_sip.c: Header 2 [ 16]: Max-Forwards: 70 [Jun 22 19:17:34] DEBUG[4682] chan_sip.c: Header 3 [ 55]: From: "Unknown" ;tag=as5ebf8080 [Jun 22 19:17:34] DEBUG[4682] chan_sip.c: Header 4 [ 30]: To: [Jun 22 19:17:34] DEBUG[4682] chan_sip.c: Header 5 [ 33]: Contact: [Jun 22 19:17:34] DEBUG[4682] chan_sip.c: Header 6 [ 52]: Call-ID: 575ccf3852228bc272d9a8af70fd3f8e@10.10.5.16 [Jun 22 19:17:34] DEBUG[4682] chan_sip.c: Header 7 [ 17]: CSeq: 102 OPTIONS [Jun 22 19:17:34] DEBUG[4682] chan_sip.c: Header 8 [ 32]: User-Agent: Asterisk PBX 1.6.2.9 [Jun 22 19:17:34] DEBUG[4682] chan_sip.c: Header 9 [ 35]: Date: Tue, 22 Jun 2010 23:17:34 GMT [Jun 22 19:17:34] DEBUG[4682] chan_sip.c: Header 10 [ 72]: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO [Jun 22 19:17:34] DEBUG[4682] chan_sip.c: Header 11 [ 26]: Supported: replaces, timer [Jun 22 19:17:34] DEBUG[4682] chan_sip.c: Header 12 [ 17]: Content-Length: 0 [Jun 22 19:17:34] VERBOSE[4682] chan_sip.c: Reliably Transmitting (NAT) to 10.10.5.124:5061: OPTIONS sip:151@10.10.5.124:5061 SIP/2.0 Via: SIP/2.0/UDP 10.10.5.16:5060;branch=z9hG4bK0294ec65;rport Max-Forwards: 70 From: "Unknown" ;tag=as5ebf8080 To: Contact: Call-ID: 575ccf3852228bc272d9a8af70fd3f8e@10.10.5.16 CSeq: 102 OPTIONS User-Agent: Asterisk PBX 1.6.2.9 Date: Tue, 22 Jun 2010 23:17:34 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO Supported: replaces, timer Content-Length: 0 --- [Jun 22 19:17:34] DEBUG[4682] chan_sip.c: *** SIP TIMER: Initializing retransmit timer on packet: Id #138216 [Jun 22 19:17:34] DEBUG[4682] chan_sip.c: Trying to put 'OPTIONS sip' onto UDP socket destined for 10.10.5.124:5061 [Jun 22 19:17:34] VERBOSE[4682] chan_sip.c: <--- SIP read from UDP:10.10.5.124:5061 ---> SIP/2.0 200 OK To: ;tag=6e2189dcf9fd53ei1 From: "Unknown" ;tag=as5ebf8080 Call-ID: 575ccf3852228bc272d9a8af70fd3f8e@10.10.5.16 CSeq: 102 OPTIONS Via: SIP/2.0/UDP 10.10.5.16:5060;branch=z9hG4bK0294ec65 Server: Linksys/SPA2102-3.3.6 Content-Length: 0 Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER Supported: x-sipura <-------------> [Jun 22 19:17:34] DEBUG[4682] chan_sip.c: Header 0 [ 14]: SIP/2.0 200 OK [Jun 22 19:17:34] DEBUG[4682] chan_sip.c: Header 1 [ 52]: To: ;tag=6e2189dcf9fd53ei1 [Jun 22 19:17:34] DEBUG[4682] chan_sip.c: Header 2 [ 55]: From: "Unknown" ;tag=as5ebf8080 [Jun 22 19:17:34] DEBUG[4682] chan_sip.c: Header 3 [ 52]: Call-ID: 575ccf3852228bc272d9a8af70fd3f8e@10.10.5.16 [Jun 22 19:17:34] DEBUG[4682] chan_sip.c: Header 4 [ 17]: CSeq: 102 OPTIONS [Jun 22 19:17:34] DEBUG[4682] chan_sip.c: Header 5 [ 55]: Via: SIP/2.0/UDP 10.10.5.16:5060;branch=z9hG4bK0294ec65 [Jun 22 19:17:34] DEBUG[4682] chan_sip.c: Header 6 [ 29]: Server: Linksys/SPA2102-3.3.6 [Jun 22 19:17:34] DEBUG[4682] chan_sip.c: Header 7 [ 17]: Content-Length: 0 [Jun 22 19:17:34] DEBUG[4682] chan_sip.c: Header 8 [ 61]: Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER [Jun 22 19:17:34] DEBUG[4682] chan_sip.c: Header 9 [ 19]: Supported: x-sipura [Jun 22 19:17:34] DEBUG[4682] chan_sip.c: Header 10 [ 0]: [Jun 22 19:17:34] VERBOSE[4682] chan_sip.c: --- (10 headers 0 lines) --- [Jun 22 19:17:34] DEBUG[4682] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #138216 [Jun 22 19:17:34] DEBUG[4682] chan_sip.c: Stopping retransmission on '575ccf3852228bc272d9a8af70fd3f8e@10.10.5.16' of Request 102: Match Found [Jun 22 19:17:34] DEBUG[4682] chan_sip.c: Destroying SIP dialog 575ccf3852228bc272d9a8af70fd3f8e@10.10.5.16 [Jun 22 19:17:34] VERBOSE[4682] chan_sip.c: Really destroying SIP dialog '575ccf3852228bc272d9a8af70fd3f8e@10.10.5.16' Method: OPTIONS [Jun 22 19:17:47] DEBUG[4682] chan_sip.c: Allocating new SIP dialog for 07a93bfa639c44eb2f19059c510ae6bf@10.10.5.16 - OPTIONS (No RTP) [Jun 22 19:17:47] DEBUG[4682] acl.c: Found IP address for this socket [Jun 22 19:17:47] DEBUG[4682] chan_sip.c: Setting SIP_TRANSPORT_UDP with address 10.10.5.16:5060 [Jun 22 19:17:47] DEBUG[4682] chan_sip.c: Initializing initreq for method OPTIONS - callid 64e70cd635e3839511bf167b6a6436ac@10.10.5.16 [Jun 22 19:17:47] DEBUG[4682] chan_sip.c: Header 0 [ 40]: OPTIONS sip:111@10.10.5.122:5060 SIP/2.0 [Jun 22 19:17:47] DEBUG[4682] chan_sip.c: Header 1 [ 61]: Via: SIP/2.0/UDP 10.10.5.16:5060;branch=z9hG4bK10ed56f4;rport [Jun 22 19:17:47] DEBUG[4682] chan_sip.c: Header 2 [ 16]: Max-Forwards: 70 [Jun 22 19:17:47] DEBUG[4682] chan_sip.c: Header 3 [ 55]: From: "Unknown" ;tag=as62b54ff7 [Jun 22 19:17:47] DEBUG[4682] chan_sip.c: Header 4 [ 30]: To: [Jun 22 19:17:47] DEBUG[4682] chan_sip.c: Header 5 [ 33]: Contact: [Jun 22 19:17:47] DEBUG[4682] chan_sip.c: Header 6 [ 52]: Call-ID: 64e70cd635e3839511bf167b6a6436ac@10.10.5.16 [Jun 22 19:17:47] DEBUG[4682] chan_sip.c: Header 7 [ 17]: CSeq: 102 OPTIONS [Jun 22 19:17:47] DEBUG[4682] chan_sip.c: Header 8 [ 32]: User-Agent: Asterisk PBX 1.6.2.9 [Jun 22 19:17:47] DEBUG[4682] chan_sip.c: Header 9 [ 35]: Date: Tue, 22 Jun 2010 23:17:47 GMT [Jun 22 19:17:47] DEBUG[4682] chan_sip.c: Header 10 [ 72]: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO [Jun 22 19:17:47] DEBUG[4682] chan_sip.c: Header 11 [ 26]: Supported: replaces, timer [Jun 22 19:17:47] DEBUG[4682] chan_sip.c: Header 12 [ 17]: Content-Length: 0 [Jun 22 19:17:47] VERBOSE[4682] chan_sip.c: Reliably Transmitting (NAT) to 10.10.5.122:5060: OPTIONS sip:111@10.10.5.122:5060 SIP/2.0 Via: SIP/2.0/UDP 10.10.5.16:5060;branch=z9hG4bK10ed56f4;rport Max-Forwards: 70 From: "Unknown" ;tag=as62b54ff7 To: Contact: Call-ID: 64e70cd635e3839511bf167b6a6436ac@10.10.5.16 CSeq: 102 OPTIONS User-Agent: Asterisk PBX 1.6.2.9 Date: Tue, 22 Jun 2010 23:17:47 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO Supported: replaces, timer Content-Length: 0 --- [Jun 22 19:17:47] DEBUG[4682] chan_sip.c: *** SIP TIMER: Initializing retransmit timer on packet: Id #138219 [Jun 22 19:17:47] DEBUG[4682] chan_sip.c: Trying to put 'OPTIONS sip' onto UDP socket destined for 10.10.5.122:5060 [Jun 22 19:17:47] VERBOSE[4682] chan_sip.c: <--- SIP read from UDP:10.10.5.122:5060 ---> SIP/2.0 200 OK To: ;tag=d789a7f2e1e55eb8i0 From: "Unknown" ;tag=as62b54ff7 Call-ID: 64e70cd635e3839511bf167b6a6436ac@10.10.5.16 CSeq: 102 OPTIONS Via: SIP/2.0/UDP 10.10.5.16:5060;branch=z9hG4bK10ed56f4 Server: Linksys/SPA941-5.1.8 Content-Length: 0 Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER Supported: replaces <-------------> [Jun 22 19:17:47] DEBUG[4682] chan_sip.c: Header 0 [ 14]: SIP/2.0 200 OK [Jun 22 19:17:47] DEBUG[4682] chan_sip.c: Header 1 [ 53]: To: ;tag=d789a7f2e1e55eb8i0 [Jun 22 19:17:47] DEBUG[4682] chan_sip.c: Header 2 [ 55]: From: "Unknown" ;tag=as62b54ff7 [Jun 22 19:17:47] DEBUG[4682] chan_sip.c: Header 3 [ 52]: Call-ID: 64e70cd635e3839511bf167b6a6436ac@10.10.5.16 [Jun 22 19:17:47] DEBUG[4682] chan_sip.c: Header 4 [ 17]: CSeq: 102 OPTIONS [Jun 22 19:17:47] DEBUG[4682] chan_sip.c: Header 5 [ 55]: Via: SIP/2.0/UDP 10.10.5.16:5060;branch=z9hG4bK10ed56f4 [Jun 22 19:17:47] DEBUG[4682] chan_sip.c: Header 6 [ 28]: Server: Linksys/SPA941-5.1.8 [Jun 22 19:17:47] DEBUG[4682] chan_sip.c: Header 7 [ 17]: Content-Length: 0 [Jun 22 19:17:47] DEBUG[4682] chan_sip.c: Header 8 [ 61]: Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER [Jun 22 19:17:47] DEBUG[4682] chan_sip.c: Header 9 [ 19]: Supported: replaces [Jun 22 19:17:47] DEBUG[4682] chan_sip.c: Header 10 [ 0]: [Jun 22 19:17:47] VERBOSE[4682] chan_sip.c: --- (10 headers 0 lines) --- [Jun 22 19:17:47] DEBUG[4682] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #138219 [Jun 22 19:17:47] DEBUG[4682] chan_sip.c: Stopping retransmission on '64e70cd635e3839511bf167b6a6436ac@10.10.5.16' of Request 102: Match Found [Jun 22 19:17:47] DEBUG[4682] chan_sip.c: Destroying SIP dialog 64e70cd635e3839511bf167b6a6436ac@10.10.5.16 [Jun 22 19:17:47] VERBOSE[4682] chan_sip.c: Really destroying SIP dialog '64e70cd635e3839511bf167b6a6436ac@10.10.5.16' Method: OPTIONS [Jun 22 19:17:49] DEBUG[4682] chan_sip.c: Auto destroying SIP dialog '707b1a1-d4a71eb5-6207f@216.115.69.131' [Jun 22 19:17:49] DEBUG[4682] chan_sip.c: Destroying SIP dialog 707b1a1-d4a71eb5-6207f@216.115.69.131 [Jun 22 19:17:49] VERBOSE[4682] chan_sip.c: Really destroying SIP dialog '707b1a1-d4a71eb5-6207f@216.115.69.131' Method: OPTIONS [Jun 22 19:17:49] VERBOSE[4682] chan_sip.c: <--- SIP read from UDP:10.10.5.128:5060 ---> NOTIFY sip:10.10.5.16 SIP/2.0 Via: SIP/2.0/UDP 10.10.5.128:5060;branch=z9hG4bK-dd6264b5 From: ;tag=7f58383bbdd660f4o0 To: Call-ID: eba68027-8734d9f8@10.10.5.128 CSeq: 7 NOTIFY Max-Forwards: 70 Event: keep-alive User-Agent: Linksys/SPA941-5.1.8 Content-Length: 0 <-------------> [Jun 22 19:17:49] DEBUG[4682] chan_sip.c: Header 0 [ 29]: NOTIFY sip:10.10.5.16 SIP/2.0 [Jun 22 19:17:49] DEBUG[4682] chan_sip.c: Header 1 [ 57]: Via: SIP/2.0/UDP 10.10.5.128:5060;branch=z9hG4bK-dd6264b5 [Jun 22 19:17:49] DEBUG[4682] chan_sip.c: Header 2 [ 49]: From: ;tag=7f58383bbdd660f4o0 [Jun 22 19:17:49] DEBUG[4682] chan_sip.c: Header 3 [ 20]: To: [Jun 22 19:17:49] DEBUG[4682] chan_sip.c: Header 4 [ 38]: Call-ID: eba68027-8734d9f8@10.10.5.128 [Jun 22 19:17:49] DEBUG[4682] chan_sip.c: Header 5 [ 14]: CSeq: 7 NOTIFY [Jun 22 19:17:49] DEBUG[4682] chan_sip.c: Header 6 [ 16]: Max-Forwards: 70 [Jun 22 19:17:49] DEBUG[4682] chan_sip.c: Header 7 [ 17]: Event: keep-alive [Jun 22 19:17:49] DEBUG[4682] chan_sip.c: Header 8 [ 32]: User-Agent: Linksys/SPA941-5.1.8 [Jun 22 19:17:49] DEBUG[4682] chan_sip.c: Header 9 [ 17]: Content-Length: 0 [Jun 22 19:17:49] DEBUG[4682] chan_sip.c: Header 10 [ 0]: [Jun 22 19:17:49] VERBOSE[4682] chan_sip.c: --- (10 headers 0 lines) --- [Jun 22 19:17:49] DEBUG[4682] acl.c: Found IP address for this socket [Jun 22 19:17:49] DEBUG[4682] chan_sip.c: Setting SIP_TRANSPORT_UDP with address 10.10.5.16:5060 [Jun 22 19:17:49] VERBOSE[4682] chan_sip.c: <--- Transmitting (no NAT) to 10.10.5.128:5060 ---> SIP/2.0 489 Bad event Via: SIP/2.0/UDP 10.10.5.128:5060;branch=z9hG4bK-dd6264b5;received=10.10.5.128 From: ;tag=7f58383bbdd660f4o0 To: ;tag=as2296491f Call-ID: eba68027-8734d9f8@10.10.5.128 CSeq: 7 NOTIFY Server: Asterisk PBX 1.6.2.9 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO Supported: replaces, timer Content-Length: 0 <------------> [Jun 22 19:17:49] DEBUG[4682] chan_sip.c: Trying to put 'SIP/2.0 489' onto UDP socket destined for 10.10.5.128:5060 [Jun 22 19:17:49] DEBUG[4682] chan_sip.c: Invalid SIP message - rejected , no callid, len 311 [Jun 22 19:17:50] VERBOSE[4682] chan_sip.c: <--- SIP read from UDP:70.167.153.130:5060 ---> OPTIONS sip:74.92.147.25:5060 SIP/2.0 Max-Forwards: 10 Record-Route: Via: SIP/2.0/UDP 70.167.153.130;branch=z9hG4bK223.e4173738612acb52c9b08c630ecdeb8a.0 Via: SIP/2.0/UDP 70.167.153.136:5060;branch=0 Route: From: sip:ping@invalid;tag=748c63c3 To: sip:74.92.147.25:5060 Call-ID: 1616fdc1-e3680745-ef49244@70.167.153.136 CSeq: 1 OPTIONS Content-Length: 0 <-------------> [Jun 22 19:17:50] DEBUG[4682] chan_sip.c: Header 0 [ 37]: OPTIONS sip:74.92.147.25:5060 SIP/2.0 [Jun 22 19:17:50] DEBUG[4682] chan_sip.c: Header 1 [ 16]: Max-Forwards: 10 [Jun 22 19:17:50] DEBUG[4682] chan_sip.c: Header 2 [ 37]: Record-Route: [Jun 22 19:17:50] DEBUG[4682] chan_sip.c: Header 3 [ 84]: Via: SIP/2.0/UDP 70.167.153.130;branch=z9hG4bK223.e4173738612acb52c9b08c630ecdeb8a.0 [Jun 22 19:17:50] DEBUG[4682] chan_sip.c: Header 4 [ 45]: Via: SIP/2.0/UDP 70.167.153.136:5060;branch=0 [Jun 22 19:17:50] DEBUG[4682] chan_sip.c: Header 5 [ 63]: Route: [Jun 22 19:17:50] DEBUG[4682] chan_sip.c: Header 6 [ 35]: From: sip:ping@invalid;tag=748c63c3 [Jun 22 19:17:50] DEBUG[4682] chan_sip.c: Header 7 [ 25]: To: sip:74.92.147.25:5060 [Jun 22 19:17:50] DEBUG[4682] chan_sip.c: Header 8 [ 49]: Call-ID: 1616fdc1-e3680745-ef49244@70.167.153.136 [Jun 22 19:17:50] DEBUG[4682] chan_sip.c: Header 9 [ 15]: CSeq: 1 OPTIONS [Jun 22 19:17:50] DEBUG[4682] chan_sip.c: Header 10 [ 17]: Content-Length: 0 [Jun 22 19:17:50] DEBUG[4682] chan_sip.c: Header 11 [ 0]: [Jun 22 19:17:50] VERBOSE[4682] chan_sip.c: --- (11 headers 0 lines) --- [Jun 22 19:17:50] DEBUG[4682] acl.c: Found IP address for this socket [Jun 22 19:17:50] DEBUG[4682] chan_sip.c: Target address 70.167.153.130 is not local, substituting externip [Jun 22 19:17:50] DEBUG[4682] chan_sip.c: Setting SIP_TRANSPORT_UDP with address 74.92.147.25:5060 [Jun 22 19:17:50] DEBUG[4682] chan_sip.c: Allocating new SIP dialog for 1616fdc1-e3680745-ef49244@70.167.153.136 - OPTIONS (No RTP) [Jun 22 19:17:50] DEBUG[4682] chan_sip.c: **** Received OPTIONS (3) - Command in SIP OPTIONS [Jun 22 19:17:50] VERBOSE[4682] chan_sip.c: Looking for s in from-sip-external (domain 74.92.147.25) [Jun 22 19:17:50] VERBOSE[4682] chan_sip.c: <--- Transmitting (no NAT) to 70.167.153.130:5060 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 70.167.153.130;branch=z9hG4bK223.e4173738612acb52c9b08c630ecdeb8a.0;received=70.167.153.130 Via: SIP/2.0/UDP 70.167.153.136:5060;branch=0 Record-Route: From: sip:ping@invalid;tag=748c63c3 To: sip:74.92.147.25:5060;tag=as6fb43db0 Call-ID: 1616fdc1-e3680745-ef49244@70.167.153.136 CSeq: 1 OPTIONS Server: Asterisk PBX 1.6.2.9 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO Supported: replaces, timer Contact: Accept: application/sdp Content-Length: 0 <------------> [Jun 22 19:17:50] DEBUG[4682] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 70.167.153.130:5060