Dec 24 20:54:29 VERBOSE[6232] logger.c: <-- SIP read from 192.168.254.1:1025: REGISTER sip:cojensen.net SIP/2.0 Via: SIP/2.0/UDP 192.168.254.60:8917;branch=z9hG4bK-d87543-5f0d3d09dd4a5346-1--d87543-;rport Max-Forwards: 70 Contact: To: "Kai-Uwe Jensen" From: "Kai-Uwe Jensen";tag=4079e139 Call-ID: a339b172f412297d@TUFETUFOMi5hbWVyaWNhcy5ocHFjb3JwLm5ldA.. CSeq: 1 REGISTER Expires: 3600 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO Supported: eventlist User-Agent: eyeBeam release 3010n stamp 19039 Content-Length: 0 Dec 24 20:54:29 DEBUG[6232] chan_sip.c: Header 0: REGISTER sip:cojensen.net SIP/2.0 (33) Dec 24 20:54:29 DEBUG[6232] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.254.60:8917;branch=z9hG4bK-d87543-5f0d3d09dd4a5346-1--d87543-;rport (92) Dec 24 20:54:29 DEBUG[6232] chan_sip.c: Header 2: Max-Forwards: 70 (16) Dec 24 20:54:29 DEBUG[6232] chan_sip.c: Header 3: Contact: (42) Dec 24 20:54:29 DEBUG[6232] chan_sip.c: Header 4: To: "Kai-Uwe Jensen" (46) Dec 24 20:54:29 DEBUG[6232] chan_sip.c: Header 5: From: "Kai-Uwe Jensen";tag=4079e139 (61) Dec 24 20:54:29 DEBUG[6232] chan_sip.c: Header 6: Call-ID: a339b172f412297d@TUFETUFOMi5hbWVyaWNhcy5ocHFjb3JwLm5ldA.. (66) Dec 24 20:54:29 DEBUG[6232] chan_sip.c: Header 7: CSeq: 1 REGISTER (16) Dec 24 20:54:29 DEBUG[6232] chan_sip.c: Header 8: Expires: 3600 (13) Dec 24 20:54:29 DEBUG[6232] chan_sip.c: Header 9: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO (81) Dec 24 20:54:29 DEBUG[6232] chan_sip.c: Header 10: Supported: eventlist (20) Dec 24 20:54:29 DEBUG[6232] chan_sip.c: Header 11: User-Agent: eyeBeam release 3010n stamp 19039 (45) Dec 24 20:54:29 DEBUG[6232] chan_sip.c: Header 12: Content-Length: 0 (17) Dec 24 20:54:29 DEBUG[6232] chan_sip.c: Header 13: (0) Dec 24 20:54:29 VERBOSE[6232] logger.c: --- (13 headers 0 lines)Dec 24 20:54:29 VERBOSE[6232] logger.c: --- (13 headers 0 lines)--- Dec 24 20:54:29 DEBUG[6232] chan_sip.c: = No match Their Call ID: 60141c1353515cf1225f849b7cf32888@192.168.254.250 Their Tag Our tag: as0b80e997 Dec 24 20:54:29 DEBUG[6232] chan_sip.c: = No match Their Call ID: 35f9466f5b095827185a808e11d72cea@192.168.254.250 Their Tag cb2000b247d89723001a836145f3b053.7741 Our tag: as7d46ad2f Dec 24 20:54:29 DEBUG[6232] chan_sip.c: = No match Their Call ID: 2e1e831466d84eb86c546e420428f5b6@192.168.254.250 Their Tag b11cb9bb270104b49a99a995b8c68544.9807 Our tag: as36bbba51 Dec 24 20:54:29 DEBUG[6232] chan_sip.c: = No match Their Call ID: 285a42d236568ba7795d33706eaa785b@192.168.254.250 Their Tag 21a483426c2cd5d9b85bffe6bba40a2e.cc08 Our tag: as304b6485 Dec 24 20:54:29 DEBUG[6232] acl.c: ##### Testing 192.168.254.1 with 192.168.254.0 Dec 24 20:54:29 DEBUG[6232] chan_sip.c: Allocating new SIP dialog for a339b172f412297d@TUFETUFOMi5hbWVyaWNhcy5ocHFjb3JwLm5ldA.. - REGISTER (No RTP) Dec 24 20:54:29 DEBUG[6232] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER Dec 24 20:54:29 VERBOSE[6232] logger.c: Using latest REGISTER request as basis request Dec 24 20:54:29 VERBOSE[6232] logger.c: Sending to 192.168.254.60 : 8917 (non-NAT) Dec 24 20:54:29 VERBOSE[6232] logger.c: Transmitting (NAT) to 192.168.254.1:1025: SIP/2.0 100 Trying Via: SIP/2.0/UDP 192.168.254.60:8917;branch=z9hG4bK-d87543-5f0d3d09dd4a5346-1--d87543-;received=192.168.254.1;rport=1025 From: "Kai-Uwe Jensen";tag=4079e139 To: "Kai-Uwe Jensen" Call-ID: a339b172f412297d@TUFETUFOMi5hbWVyaWNhcy5ocHFjb3JwLm5ldA.. CSeq: 1 REGISTER User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Contact: Content-Length: 0 --- Dec 24 20:54:29 VERBOSE[6232] logger.c: Transmitting (NAT) to 192.168.254.1:1025: SIP/2.0 401 Unauthorized Via: SIP/2.0/UDP 192.168.254.60:8917;branch=z9hG4bK-d87543-5f0d3d09dd4a5346-1--d87543-;received=192.168.254.1;rport=1025 From: "Kai-Uwe Jensen";tag=4079e139 To: "Kai-Uwe Jensen";tag=as33aee2d9 Call-ID: a339b172f412297d@TUFETUFOMi5hbWVyaWNhcy5ocHFjb3JwLm5ldA.. CSeq: 1 REGISTER User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Contact: WWW-Authenticate: Digest realm="asterisk", nonce="105fbe7b" Content-Length: 0 --- Dec 24 20:54:29 VERBOSE[6232] logger.c: Scheduling destruction of call 'a339b172f412297d@TUFETUFOMi5hbWVyaWNhcy5ocHFjb3JwLm5ldA..' in 15000 ms Dec 24 20:54:30 VERBOSE[6232] logger.c: <-- SIP read from 192.168.254.1:1025: REGISTER sip:cojensen.net SIP/2.0 Via: SIP/2.0/UDP 192.168.254.60:8917;branch=z9hG4bK-d87543-e877e520f128f85f-1--d87543-;rport Max-Forwards: 70 Contact: To: "Kai-Uwe Jensen" From: "Kai-Uwe Jensen";tag=4079e139 Call-ID: a339b172f412297d@TUFETUFOMi5hbWVyaWNhcy5ocHFjb3JwLm5ldA.. CSeq: 2 REGISTER Expires: 3600 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO Supported: eventlist User-Agent: eyeBeam release 3010n stamp 19039 Authorization: Digest username="ext2003",realm="asterisk",nonce="105fbe7b",uri="sip:cojensen.net",response="942742e970719e847515b2edd99e7a99",algorithm=MD5 Content-Length: 0 Dec 24 20:54:30 DEBUG[6232] chan_sip.c: Header 0: REGISTER sip:cojensen.net SIP/2.0 (33) Dec 24 20:54:30 DEBUG[6232] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.254.60:8917;branch=z9hG4bK-d87543-e877e520f128f85f-1--d87543-;rport (92) Dec 24 20:54:30 DEBUG[6232] chan_sip.c: Header 2: Max-Forwards: 70 (16) Dec 24 20:54:30 DEBUG[6232] chan_sip.c: Header 3: Contact: (42) Dec 24 20:54:30 DEBUG[6232] chan_sip.c: Header 4: To: "Kai-Uwe Jensen" (46) Dec 24 20:54:30 DEBUG[6232] chan_sip.c: Header 5: From: "Kai-Uwe Jensen";tag=4079e139 (61) Dec 24 20:54:30 DEBUG[6232] chan_sip.c: Header 6: Call-ID: a339b172f412297d@TUFETUFOMi5hbWVyaWNhcy5ocHFjb3JwLm5ldA.. (66) Dec 24 20:54:30 DEBUG[6232] chan_sip.c: Header 7: CSeq: 2 REGISTER (16) Dec 24 20:54:30 DEBUG[6232] chan_sip.c: Header 8: Expires: 3600 (13) Dec 24 20:54:30 DEBUG[6232] chan_sip.c: Header 9: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO (81) Dec 24 20:54:30 DEBUG[6232] chan_sip.c: Header 10: Supported: eventlist (20) Dec 24 20:54:30 DEBUG[6232] chan_sip.c: Header 11: User-Agent: eyeBeam release 3010n stamp 19039 (45) Dec 24 20:54:30 DEBUG[6232] chan_sip.c: Header 12: Authorization: Digest username="ext2003",realm="asterisk",nonce="105fbe7b",uri="sip:cojensen.net",response="942742e970719e847515b2edd99e7a99",algorithm=MD5 (155) Dec 24 20:54:30 DEBUG[6232] chan_sip.c: Header 13: Content-Length: 0 (17) Dec 24 20:54:30 DEBUG[6232] chan_sip.c: Header 14: (0) Dec 24 20:54:30 VERBOSE[6232] logger.c: --- (14 headers 0 lines)Dec 24 20:54:30 VERBOSE[6232] logger.c: --- (14 headers 0 lines)--- Dec 24 20:54:30 DEBUG[6232] chan_sip.c: = Found Their Call ID: a339b172f412297d@TUFETUFOMi5hbWVyaWNhcy5ocHFjb3JwLm5ldA.. Their Tag 4079e139 Our tag: as33aee2d9 Dec 24 20:54:30 DEBUG[6232] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER Dec 24 20:54:30 VERBOSE[6232] logger.c: Using latest REGISTER request as basis request Dec 24 20:54:30 VERBOSE[6232] logger.c: Sending to 192.168.254.60 : 8917 (NAT) Dec 24 20:54:30 VERBOSE[6232] logger.c: Transmitting (NAT) to 192.168.254.1:1025: SIP/2.0 100 Trying Via: SIP/2.0/UDP 192.168.254.60:8917;branch=z9hG4bK-d87543-e877e520f128f85f-1--d87543-;received=192.168.254.1;rport=1025 From: "Kai-Uwe Jensen";tag=4079e139 To: "Kai-Uwe Jensen" Call-ID: a339b172f412297d@TUFETUFOMi5hbWVyaWNhcy5ocHFjb3JwLm5ldA.. CSeq: 2 REGISTER User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Contact: Content-Length: 0 --- Dec 24 20:54:30 NOTICE[6232] chan_sip.c: Still have a QUALIFY dialog active, deleting Dec 24 20:54:30 VERBOSE[6232] logger.c: Destroying call '60141c1353515cf1225f849b7cf32888@192.168.254.250' Dec 24 20:54:30 DEBUG[6232] chan_sip.c: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP) Dec 24 20:54:30 DEBUG[6232] acl.c: ##### Testing 192.168.254.1 with 192.168.254.0 Dec 24 20:54:30 DEBUG[6232] chan_sip.c: Header 0: OPTIONS sip:ext2003@192.168.254.60:8917 SIP/2.0 (47) Dec 24 20:54:30 DEBUG[6232] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.254.250:5060;branch=z9hG4bK42ddfa10;rport (66) Dec 24 20:54:30 DEBUG[6232] chan_sip.c: Header 2: From: "Asterisk" ;tag=as30f59008 (62) Dec 24 20:54:30 DEBUG[6232] chan_sip.c: Header 3: To: (37) Dec 24 20:54:30 DEBUG[6232] chan_sip.c: Header 4: Contact: (39) Dec 24 20:54:30 DEBUG[6232] chan_sip.c: Header 5: Call-ID: 149417c46ae1e86656eb847f171e91b1@192.168.254.250 (57) Dec 24 20:54:30 DEBUG[6232] chan_sip.c: Header 6: CSeq: 102 OPTIONS (17) Dec 24 20:54:30 DEBUG[6232] chan_sip.c: Header 7: User-Agent: Asterisk PBX (24) Dec 24 20:54:30 DEBUG[6232] chan_sip.c: Header 8: Max-Forwards: 70 (16) Dec 24 20:54:30 DEBUG[6232] chan_sip.c: Header 9: Date: Sun, 25 Dec 2005 03:54:30 GMT (35) Dec 24 20:54:30 DEBUG[6232] chan_sip.c: Header 10: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY (66) Dec 24 20:54:30 DEBUG[6232] chan_sip.c: Header 11: Content-Length: 0 (17) Dec 24 20:54:30 DEBUG[6232] chan_sip.c: Header 12: (0) Dec 24 20:54:30 VERBOSE[6232] logger.c: 12 headers, 0 lines Dec 24 20:54:30 VERBOSE[6232] logger.c: Reliably Transmitting (no NAT) to 192.168.254.1:1025: OPTIONS sip:ext2003@192.168.254.60:8917 SIP/2.0 Via: SIP/2.0/UDP 192.168.254.250:5060;branch=z9hG4bK42ddfa10;rport From: "Asterisk" ;tag=as30f59008 To: Contact: Call-ID: 149417c46ae1e86656eb847f171e91b1@192.168.254.250 CSeq: 102 OPTIONS User-Agent: Asterisk PBX Max-Forwards: 70 Date: Sun, 25 Dec 2005 03:54:30 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Content-Length: 0 --- Dec 24 20:54:30 DEBUG[6232] chan_sip.c: *** SIP TIMER: Initalizing retransmit timer on packet: Id #57 Dec 24 20:54:30 VERBOSE[6232] logger.c: -- Registered SIP 'ext2003' at 192.168.254.1 port 1025 expires 3600 Dec 24 20:54:30 VERBOSE[6232] logger.c: -- Saved useragent "eyeBeam release 3010n stamp 19039" for peer ext2003 Dec 24 20:54:30 VERBOSE[6232] logger.c: Transmitting (NAT) to 192.168.254.1:1025: SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.254.60:8917;branch=z9hG4bK-d87543-e877e520f128f85f-1--d87543-;received=192.168.254.1;rport=1025 From: "Kai-Uwe Jensen";tag=4079e139 To: "Kai-Uwe Jensen";tag=as33aee2d9 Call-ID: a339b172f412297d@TUFETUFOMi5hbWVyaWNhcy5ocHFjb3JwLm5ldA.. CSeq: 2 REGISTER User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Expires: 3600 Contact: ;expires=3600 Date: Sun, 25 Dec 2005 03:54:30 GMT Content-Length: 0 --- Dec 24 20:54:30 VERBOSE[6232] logger.c: Scheduling destruction of call 'a339b172f412297d@TUFETUFOMi5hbWVyaWNhcy5ocHFjb3JwLm5ldA..' in 15000 ms Dec 24 20:54:30 DEBUG[6229] chan_sip.c: Checking device state for peer ext2003 Dec 24 20:54:30 DEBUG[6229] devicestate.c: Changing state for SIP/ext2003 - state 1 (Not in use) Dec 24 20:54:30 DEBUG[6229] chan_sip.c: Checking device state for peer ext2003 Dec 24 20:54:30 DEBUG[6229] chan_sip.c: Checking device state for peer ext2003 Dec 24 20:54:30 DEBUG[6240] app_queue.c: Device 'SIP/ext2003' changed to state '1' (Not in use) Dec 24 20:54:30 VERBOSE[6232] logger.c: <-- SIP read from 192.168.254.1:1025: SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.254.250:5060;branch=z9hG4bK42ddfa10;rport=5060;received=67.174.106.30 Contact: To: ;tag=d251a16e From: "Asterisk";tag=as30f59008 Call-ID: 149417c46ae1e86656eb847f171e91b1@192.168.254.250 CSeq: 102 OPTIONS Accept: application/sdp Accept-Language: en Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO Supported: eventlist User-Agent: eyeBeam release 3010n stamp 19039 Content-Length: 0 Dec 24 20:54:30 DEBUG[6232] chan_sip.c: Header 0: SIP/2.0 200 OK (14) Dec 24 20:54:30 DEBUG[6232] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.254.250:5060;branch=z9hG4bK42ddfa10;rport=5060;received=67.174.106.30 (94) Dec 24 20:54:30 DEBUG[6232] chan_sip.c: Header 2: Contact: (34) Dec 24 20:54:30 DEBUG[6232] chan_sip.c: Header 3: To: ;tag=d251a16e (50) Dec 24 20:54:30 DEBUG[6232] chan_sip.c: Header 4: From: "Asterisk";tag=as30f59008 (61) Dec 24 20:54:30 DEBUG[6232] chan_sip.c: Header 5: Call-ID: 149417c46ae1e86656eb847f171e91b1@192.168.254.250 (57) Dec 24 20:54:30 DEBUG[6232] chan_sip.c: Header 6: CSeq: 102 OPTIONS (17) Dec 24 20:54:30 DEBUG[6232] chan_sip.c: Header 7: Accept: application/sdp (23) Dec 24 20:54:30 DEBUG[6232] chan_sip.c: Header 8: Accept-Language: en (19) Dec 24 20:54:30 DEBUG[6232] chan_sip.c: Header 9: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO (81) Dec 24 20:54:30 DEBUG[6232] chan_sip.c: Header 10: Supported: eventlist (20) Dec 24 20:54:30 DEBUG[6232] chan_sip.c: Header 11: User-Agent: eyeBeam release 3010n stamp 19039 (45) Dec 24 20:54:30 DEBUG[6232] chan_sip.c: Header 12: Content-Length: 0 (17) Dec 24 20:54:30 DEBUG[6232] chan_sip.c: Header 13: (0) Dec 24 20:54:30 VERBOSE[6232] logger.c: --- (13 headers 0 lines)Dec 24 20:54:30 VERBOSE[6232] logger.c: --- (13 headers 0 lines)--- Dec 24 20:54:30 DEBUG[6232] chan_sip.c: = Found Their Call ID: 149417c46ae1e86656eb847f171e91b1@192.168.254.250 Their Tag Our tag: as30f59008 Dec 24 20:54:30 DEBUG[6232] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #57 Dec 24 20:54:30 DEBUG[6232] chan_sip.c: Stopping retransmission on '149417c46ae1e86656eb847f171e91b1@192.168.254.250' of Request 102: Match Found Dec 24 20:54:30 NOTICE[6232] chan_sip.c: Peer 'ext2003' is now REACHABLE! (64ms / 2000ms) Dec 24 20:54:30 VERBOSE[6232] logger.c: Destroying call '149417c46ae1e86656eb847f171e91b1@192.168.254.250' Dec 24 20:54:30 DEBUG[6229] chan_sip.c: Checking device state for peer ext2003 Dec 24 20:54:30 DEBUG[6229] devicestate.c: Changing state for SIP/ext2003 - state 1 (Not in use) Dec 24 20:54:30 DEBUG[6229] chan_sip.c: Checking device state for peer ext2003 Dec 24 20:54:30 DEBUG[6229] chan_sip.c: Checking device state for peer ext2003 Dec 24 20:54:30 DEBUG[6241] app_queue.c: Device 'SIP/ext2003' changed to state '1' (Not in use) Dec 24 20:54:32 DEBUG[6232] chan_sip.c: Auto destroying call '285a42d236568ba7795d33706eaa785b@192.168.254.250' Dec 24 20:54:32 VERBOSE[6232] logger.c: Destroying call '285a42d236568ba7795d33706eaa785b@192.168.254.250' Dec 24 20:54:33 DEBUG[6232] chan_sip.c: Auto destroying call '35f9466f5b095827185a808e11d72cea@192.168.254.250' Dec 24 20:54:33 VERBOSE[6232] logger.c: Destroying call '35f9466f5b095827185a808e11d72cea@192.168.254.250' Dec 24 20:54:33 DEBUG[6232] chan_sip.c: Allocating new SIP dialog for (No Call-ID) - NOTIFY (No RTP) Dec 24 20:54:33 DEBUG[6232] acl.c: ##### Testing 192.168.254.1 with 192.168.254.0 Dec 24 20:54:33 DEBUG[6232] chan_sip.c: Header 0: NOTIFY sip:ext2003@192.168.254.60:8917 SIP/2.0 (46) Dec 24 20:54:33 DEBUG[6232] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.254.250:5060;branch=z9hG4bK51922610;rport (66) Dec 24 20:54:33 DEBUG[6232] chan_sip.c: Header 2: From: "Asterisk" ;tag=as49f47023 (62) Dec 24 20:54:33 DEBUG[6232] chan_sip.c: Header 3: To: (37) Dec 24 20:54:33 DEBUG[6232] chan_sip.c: Header 4: Contact: (39) Dec 24 20:54:33 DEBUG[6232] chan_sip.c: Header 5: Call-ID: 274565126f2386e400793ab46eff0097@192.168.254.250 (57) Dec 24 20:54:33 DEBUG[6232] chan_sip.c: Header 6: CSeq: 102 NOTIFY (16) Dec 24 20:54:33 DEBUG[6232] chan_sip.c: Header 7: User-Agent: Asterisk PBX (24) Dec 24 20:54:33 DEBUG[6232] chan_sip.c: Header 8: Max-Forwards: 70 (16) Dec 24 20:54:33 DEBUG[6232] chan_sip.c: Header 9: Event: message-summary (22) Dec 24 20:54:33 DEBUG[6232] chan_sip.c: Header 10: Content-Type: application/simple-message-summary (48) Dec 24 20:54:33 DEBUG[6232] chan_sip.c: Header 11: Content-Length: 80 (18) Dec 24 20:54:33 DEBUG[6232] chan_sip.c: Header 12: (0) Dec 24 20:54:33 DEBUG[6232] chan_sip.c: Line: Messages-Waiting: no (20) Dec 24 20:54:33 DEBUG[6232] chan_sip.c: Line: Message-Account: sip:asterisk@ (30) Dec 24 20:54:33 DEBUG[6232] chan_sip.c: Line: Voice-Message: 0/0 (0/0) (24) Dec 24 20:54:33 VERBOSE[6232] logger.c: 12 headers, 3 lines Dec 24 20:54:33 VERBOSE[6232] logger.c: Reliably Transmitting (NAT) to 192.168.254.1:1025: NOTIFY sip:ext2003@192.168.254.60:8917 SIP/2.0 Via: SIP/2.0/UDP 192.168.254.250:5060;branch=z9hG4bK51922610;rport From: "Asterisk" ;tag=as49f47023 To: Contact: Call-ID: 274565126f2386e400793ab46eff0097@192.168.254.250 CSeq: 102 NOTIFY User-Agent: Asterisk PBX Max-Forwards: 70 Event: message-summary Content-Type: application/simple-message-summary Content-Length: 80 Messages-Waiting: no Message-Account: sip:asterisk@ Voice-Message: 0/0 (0/0) --- Dec 24 20:54:33 DEBUG[6232] chan_sip.c: *** SIP TIMER: Initalizing retransmit timer on packet: Id #61 Dec 24 20:54:33 VERBOSE[6232] logger.c: Scheduling destruction of call '274565126f2386e400793ab46eff0097@192.168.254.250' in 15000 ms Dec 24 20:54:33 DEBUG[6232] chan_sip.c: Auto destroying call '2e1e831466d84eb86c546e420428f5b6@192.168.254.250' Dec 24 20:54:33 VERBOSE[6232] logger.c: Destroying call '2e1e831466d84eb86c546e420428f5b6@192.168.254.250' Dec 24 20:54:33 VERBOSE[6232] logger.c: <-- SIP read from 192.168.254.1:1025: SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.254.250:5060;branch=z9hG4bK51922610;rport=5060;received=67.174.106.30 Contact: To: ;tag=103f897c From: "Asterisk";tag=as49f47023 Call-ID: 274565126f2386e400793ab46eff0097@192.168.254.250 CSeq: 102 NOTIFY User-Agent: eyeBeam release 3010n stamp 19039 Content-Length: 0 Dec 24 20:54:33 DEBUG[6232] chan_sip.c: Header 0: SIP/2.0 200 OK (14) Dec 24 20:54:33 DEBUG[6232] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.254.250:5060;branch=z9hG4bK51922610;rport=5060;received=67.174.106.30 (94) Dec 24 20:54:33 DEBUG[6232] chan_sip.c: Header 2: Contact: (34) Dec 24 20:54:33 DEBUG[6232] chan_sip.c: Header 3: To: ;tag=103f897c (50) Dec 24 20:54:33 DEBUG[6232] chan_sip.c: Header 4: From: "Asterisk";tag=as49f47023 (61) Dec 24 20:54:33 DEBUG[6232] chan_sip.c: Header 5: Call-ID: 274565126f2386e400793ab46eff0097@192.168.254.250 (57) Dec 24 20:54:33 DEBUG[6232] chan_sip.c: Header 6: CSeq: 102 NOTIFY (16) Dec 24 20:54:33 DEBUG[6232] chan_sip.c: Header 7: User-Agent: eyeBeam release 3010n stamp 19039 (45) Dec 24 20:54:33 DEBUG[6232] chan_sip.c: Header 8: Content-Length: 0 (17) Dec 24 20:54:33 DEBUG[6232] chan_sip.c: Header 9: (0) Dec 24 20:54:33 VERBOSE[6232] logger.c: --- (9 headers 0 lines)Dec 24 20:54:33 VERBOSE[6232] logger.c: --- (9 headers 0 lines)--- Dec 24 20:54:33 DEBUG[6232] chan_sip.c: = Found Their Call ID: 274565126f2386e400793ab46eff0097@192.168.254.250 Their Tag Our tag: as49f47023 Dec 24 20:54:33 DEBUG[6232] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #61 Dec 24 20:54:33 DEBUG[6232] chan_sip.c: Stopping retransmission on '274565126f2386e400793ab46eff0097@192.168.254.250' of Request 102: Match Found Dec 24 20:54:33 VERBOSE[6232] logger.c: Destroying call '274565126f2386e400793ab46eff0097@192.168.254.250' Dec 24 20:54:38 VERBOSE[6232] logger.c: <-- SIP read from 192.168.254.1:1025: Dec 24 20:54:38 DEBUG[6232] chan_sip.c: Header 0: (0) Dec 24 20:54:38 VERBOSE[6232] logger.c: --- (0 headers 0 lines)Dec 24 20:54:38 VERBOSE[6232] logger.c: --- (0 headers 0 lines) Nat keepalive Dec 24 20:54:38 VERBOSE[6232] logger.c: --- (0 headers 0 lines) Nat keepalive --- Dec 24 20:54:43 VERBOSE[6232] logger.c: <-- SIP read from 192.168.254.251:5061: INVITE sip:2600@192.168.254.250 SIP/2.0 Via: SIP/2.0/UDP 192.168.254.251:5061;branch=z9hG4bK-16340e97 From: Kai-Uwe Jensen ;tag=3c72c92613678cbfo0 To: Call-ID: 3c7fc272-308803e3@192.168.254.251 CSeq: 101 INVITE Max-Forwards: 70 Contact: Kai-Uwe Jensen Expires: 240 User-Agent: Linksys/PAP2-3.1.6(LS) Content-Length: 409 Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER Supported: x-sipura Content-Type: application/sdp v=0 o=- 468058414 468058414 IN IP4 192.168.254.251 s=- c=IN IP4 192.168.254.251 t=0 0 m=audio 9086 RTP/AVP 0 2 8 18 96 97 98 100 101 a=rtpmap:0 PCMU/8000 a=rtpmap:2 G726-32/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:18 G729a/8000 a=rtpmap:96 G726-40/8000 a=rtpmap:97 G726-24/8000 a=rtpmap:98 G726-16/8000 a=rtpmap:100 NSE/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=ptime:20 a=sendrecv Dec 24 20:54:43 DEBUG[6232] chan_sip.c: Header 0: INVITE sip:2600@192.168.254.250 SIP/2.0 (39) Dec 24 20:54:43 DEBUG[6232] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.254.251:5061;branch=z9hG4bK-16340e97 (61) Dec 24 20:54:43 DEBUG[6232] chan_sip.c: Header 2: From: Kai-Uwe Jensen ;tag=3c72c92613678cbfo0 (73) Dec 24 20:54:43 DEBUG[6232] chan_sip.c: Header 3: To: (30) Dec 24 20:54:43 DEBUG[6232] chan_sip.c: Header 4: Call-ID: 3c7fc272-308803e3@192.168.254.251 (42) Dec 24 20:54:43 DEBUG[6232] chan_sip.c: Header 5: CSeq: 101 INVITE (16) Dec 24 20:54:43 DEBUG[6232] chan_sip.c: Header 6: Max-Forwards: 70 (16) Dec 24 20:54:43 DEBUG[6232] chan_sip.c: Header 7: Contact: Kai-Uwe Jensen (58) Dec 24 20:54:43 DEBUG[6232] chan_sip.c: Header 8: Expires: 240 (12) Dec 24 20:54:43 DEBUG[6232] chan_sip.c: Header 9: User-Agent: Linksys/PAP2-3.1.6(LS) (34) Dec 24 20:54:43 DEBUG[6232] chan_sip.c: Header 10: Content-Length: 409 (19) Dec 24 20:54:43 DEBUG[6232] chan_sip.c: Header 11: Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER (61) Dec 24 20:54:43 DEBUG[6232] chan_sip.c: Header 12: Supported: x-sipura (19) Dec 24 20:54:43 DEBUG[6232] chan_sip.c: Header 13: Content-Type: application/sdp (29) Dec 24 20:54:43 DEBUG[6232] chan_sip.c: Header 14: (0) Dec 24 20:54:43 DEBUG[6232] chan_sip.c: Line: v=0 (3) Dec 24 20:54:43 DEBUG[6232] chan_sip.c: Line: o=- 468058414 468058414 IN IP4 192.168.254.251 (46) Dec 24 20:54:43 DEBUG[6232] chan_sip.c: Line: s=- (3) Dec 24 20:54:43 DEBUG[6232] chan_sip.c: Line: c=IN IP4 192.168.254.251 (24) Dec 24 20:54:43 DEBUG[6232] chan_sip.c: Line: t=0 0 (5) Dec 24 20:54:43 DEBUG[6232] chan_sip.c: Line: m=audio 9086 RTP/AVP 0 2 8 18 96 97 98 100 101 (46) Dec 24 20:54:43 DEBUG[6232] chan_sip.c: Line: a=rtpmap:0 PCMU/8000 (20) Dec 24 20:54:43 DEBUG[6232] chan_sip.c: Line: a=rtpmap:2 G726-32/8000 (23) Dec 24 20:54:43 DEBUG[6232] chan_sip.c: Line: a=rtpmap:8 PCMA/8000 (20) Dec 24 20:54:43 DEBUG[6232] chan_sip.c: Line: a=rtpmap:18 G729a/8000 (22) Dec 24 20:54:43 DEBUG[6232] chan_sip.c: Line: a=rtpmap:96 G726-40/8000 (24) Dec 24 20:54:43 DEBUG[6232] chan_sip.c: Line: a=rtpmap:97 G726-24/8000 (24) Dec 24 20:54:43 DEBUG[6232] chan_sip.c: Line: a=rtpmap:98 G726-16/8000 (24) Dec 24 20:54:43 DEBUG[6232] chan_sip.c: Line: a=rtpmap:100 NSE/8000 (21) Dec 24 20:54:43 DEBUG[6232] chan_sip.c: Line: a=rtpmap:101 telephone-event/8000 (33) Dec 24 20:54:43 DEBUG[6232] chan_sip.c: Line: a=fmtp:101 0-15 (15) Dec 24 20:54:43 DEBUG[6232] chan_sip.c: Line: a=ptime:20 (10) Dec 24 20:54:43 DEBUG[6232] chan_sip.c: Line: a=sendrecv (10) Dec 24 20:54:43 VERBOSE[6232] logger.c: --- (14 headers 18 lines)Dec 24 20:54:43 VERBOSE[6232] logger.c: --- (14 headers 18 lines)--- Dec 24 20:54:43 DEBUG[6232] chan_sip.c: = No match Their Call ID: a339b172f412297d@TUFETUFOMi5hbWVyaWNhcy5ocHFjb3JwLm5ldA.. Their Tag 4079e139 Our tag: as33aee2d9 Dec 24 20:54:43 DEBUG[6232] acl.c: ##### Testing 192.168.254.251 with 192.168.254.0 Dec 24 20:54:43 DEBUG[6232] chan_sip.c: Allocating new SIP dialog for 3c7fc272-308803e3@192.168.254.251 - INVITE (With RTP) Dec 24 20:54:43 DEBUG[6232] chan_sip.c: **** Received INVITE (5) - Command in SIP INVITE Dec 24 20:54:43 DEBUG[6232] chan_sip.c: Begin: parsing SIP "Supported: x-sipura" Dec 24 20:54:43 DEBUG[6232] chan_sip.c: Found SIP option: -x-sipura- Dec 24 20:54:43 DEBUG[6232] chan_sip.c: Found no match for SIP option: x-sipura (Please file bug report!) Dec 24 20:54:43 DEBUG[6232] chan_sip.c: * SIP extension value: 0 for call 3c7fc272-308803e3@192.168.254.251 Dec 24 20:54:43 VERBOSE[6232] logger.c: Using INVITE request as basis request - 3c7fc272-308803e3@192.168.254.251 Dec 24 20:54:43 VERBOSE[6232] logger.c: Sending to 192.168.254.251 : 5061 (non-NAT) Dec 24 20:54:43 DEBUG[6232] chan_sip.c: Setting NAT on RTP to 0 Dec 24 20:54:43 DEBUG[6232] chan_sip.c: Setting NAT on VRTP to 0 Dec 24 20:54:43 VERBOSE[6232] logger.c: Reliably Transmitting (no NAT) to 192.168.254.251:5061: SIP/2.0 407 Proxy Authentication Required Via: SIP/2.0/UDP 192.168.254.251:5061;branch=z9hG4bK-16340e97;received=192.168.254.251 From: Kai-Uwe Jensen ;tag=3c72c92613678cbfo0 To: ;tag=as57085e68 Call-ID: 3c7fc272-308803e3@192.168.254.251 CSeq: 101 INVITE User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Contact: Proxy-Authenticate: Digest realm="asterisk", nonce="7fb5280b" Content-Length: 0 --- Dec 24 20:54:43 DEBUG[6232] chan_sip.c: *** SIP TIMER: Initalizing retransmit timer on packet: Id #63 Dec 24 20:54:43 VERBOSE[6232] logger.c: Scheduling destruction of call '3c7fc272-308803e3@192.168.254.251' in 15000 ms Dec 24 20:54:43 VERBOSE[6232] logger.c: Found user 'ext2001' Dec 24 20:54:43 VERBOSE[6232] logger.c: <-- SIP read from 192.168.254.251:5061: ACK sip:2600@192.168.254.250 SIP/2.0 Via: SIP/2.0/UDP 192.168.254.251:5061;branch=z9hG4bK-16340e97 From: Kai-Uwe Jensen ;tag=3c72c92613678cbfo0 To: ;tag=as57085e68 Call-ID: 3c7fc272-308803e3@192.168.254.251 CSeq: 101 ACK Max-Forwards: 70 Contact: Kai-Uwe Jensen User-Agent: Linksys/PAP2-3.1.6(LS) Content-Length: 0 Dec 24 20:54:43 DEBUG[6232] chan_sip.c: Header 0: ACK sip:2600@192.168.254.250 SIP/2.0 (36) Dec 24 20:54:43 DEBUG[6232] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.254.251:5061;branch=z9hG4bK-16340e97 (61) Dec 24 20:54:43 DEBUG[6232] chan_sip.c: Header 2: From: Kai-Uwe Jensen ;tag=3c72c92613678cbfo0 (73) Dec 24 20:54:43 DEBUG[6232] chan_sip.c: Header 3: To: ;tag=as57085e68 (45) Dec 24 20:54:43 DEBUG[6232] chan_sip.c: Header 4: Call-ID: 3c7fc272-308803e3@192.168.254.251 (42) Dec 24 20:54:43 DEBUG[6232] chan_sip.c: Header 5: CSeq: 101 ACK (13) Dec 24 20:54:43 DEBUG[6232] chan_sip.c: Header 6: Max-Forwards: 70 (16) Dec 24 20:54:43 DEBUG[6232] chan_sip.c: Header 7: Contact: Kai-Uwe Jensen (58) Dec 24 20:54:43 DEBUG[6232] chan_sip.c: Header 8: User-Agent: Linksys/PAP2-3.1.6(LS) (34) Dec 24 20:54:43 DEBUG[6232] chan_sip.c: Header 9: Content-Length: 0 (17) Dec 24 20:54:43 DEBUG[6232] chan_sip.c: Header 10: (0) Dec 24 20:54:43 VERBOSE[6232] logger.c: --- (10 headers 0 lines)Dec 24 20:54:43 VERBOSE[6232] logger.c: --- (10 headers 0 lines)--- Dec 24 20:54:43 DEBUG[6232] chan_sip.c: = Found Their Call ID: 3c7fc272-308803e3@192.168.254.251 Their Tag 3c72c92613678cbfo0 Our tag: as57085e68 Dec 24 20:54:43 DEBUG[6232] chan_sip.c: **** Received ACK (6) - Command in SIP ACK Dec 24 20:54:43 DEBUG[6232] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #63 Dec 24 20:54:43 DEBUG[6232] chan_sip.c: Stopping retransmission on '3c7fc272-308803e3@192.168.254.251' of Response 101: Match Found Dec 24 20:54:43 VERBOSE[6232] logger.c: <-- SIP read from 192.168.254.251:5061: INVITE sip:2600@192.168.254.250 SIP/2.0 Via: SIP/2.0/UDP 192.168.254.251:5061;branch=z9hG4bK-4eff0d7b From: Kai-Uwe Jensen ;tag=3c72c92613678cbfo0 To: Call-ID: 3c7fc272-308803e3@192.168.254.251 CSeq: 102 INVITE Max-Forwards: 70 Proxy-Authorization: Digest username="ext2001",realm="asterisk",nonce="7fb5280b",uri="sip:2600@192.168.254.250",algorithm=MD5,response="3142412d22d7e6ca2da8c29f44db8275" Contact: Kai-Uwe Jensen Expires: 240 User-Agent: Linksys/PAP2-3.1.6(LS) Content-Length: 409 Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER Supported: x-sipura Content-Type: application/sdp v=0 o=- 468058414 468058414 IN IP4 192.168.254.251 s=- c=IN IP4 192.168.254.251 t=0 0 m=audio 9086 RTP/AVP 0 2 8 18 96 97 98 100 101 a=rtpmap:0 PCMU/8000 a=rtpmap:2 G726-32/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:18 G729a/8000 a=rtpmap:96 G726-40/8000 a=rtpmap:97 G726-24/8000 a=rtpmap:98 G726-16/8000 a=rtpmap:100 NSE/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=ptime:20 a=sendrecv Dec 24 20:54:43 DEBUG[6232] chan_sip.c: Header 0: INVITE sip:2600@192.168.254.250 SIP/2.0 (39) Dec 24 20:54:43 DEBUG[6232] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.254.251:5061;branch=z9hG4bK-4eff0d7b (61) Dec 24 20:54:43 DEBUG[6232] chan_sip.c: Header 2: From: Kai-Uwe Jensen ;tag=3c72c92613678cbfo0 (73) Dec 24 20:54:43 DEBUG[6232] chan_sip.c: Header 3: To: (30) Dec 24 20:54:43 DEBUG[6232] chan_sip.c: Header 4: Call-ID: 3c7fc272-308803e3@192.168.254.251 (42) Dec 24 20:54:43 DEBUG[6232] chan_sip.c: Header 5: CSeq: 102 INVITE (16) Dec 24 20:54:43 DEBUG[6232] chan_sip.c: Header 6: Max-Forwards: 70 (16) Dec 24 20:54:43 DEBUG[6232] chan_sip.c: Header 7: Proxy-Authorization: Digest username="ext2001",realm="asterisk",nonce="7fb5280b",uri="sip:2600@192.168.254.250",algorithm=MD5,response="3142412d22d7e6ca2da8c29f44db8275" (169) Dec 24 20:54:43 DEBUG[6232] chan_sip.c: Header 8: Contact: Kai-Uwe Jensen (58) Dec 24 20:54:43 DEBUG[6232] chan_sip.c: Header 9: Expires: 240 (12) Dec 24 20:54:43 DEBUG[6232] chan_sip.c: Header 10: User-Agent: Linksys/PAP2-3.1.6(LS) (34) Dec 24 20:54:43 DEBUG[6232] chan_sip.c: Header 11: Content-Length: 409 (19) Dec 24 20:54:43 DEBUG[6232] chan_sip.c: Header 12: Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER (61) Dec 24 20:54:43 DEBUG[6232] chan_sip.c: Header 13: Supported: x-sipura (19) Dec 24 20:54:43 DEBUG[6232] chan_sip.c: Header 14: Content-Type: application/sdp (29) Dec 24 20:54:43 DEBUG[6232] chan_sip.c: Header 15: (0) Dec 24 20:54:43 DEBUG[6232] chan_sip.c: Line: v=0 (3) Dec 24 20:54:43 DEBUG[6232] chan_sip.c: Line: o=- 468058414 468058414 IN IP4 192.168.254.251 (46) Dec 24 20:54:43 DEBUG[6232] chan_sip.c: Line: s=- (3) Dec 24 20:54:43 DEBUG[6232] chan_sip.c: Line: c=IN IP4 192.168.254.251 (24) Dec 24 20:54:43 DEBUG[6232] chan_sip.c: Line: t=0 0 (5) Dec 24 20:54:43 DEBUG[6232] chan_sip.c: Line: m=audio 9086 RTP/AVP 0 2 8 18 96 97 98 100 101 (46) Dec 24 20:54:43 DEBUG[6232] chan_sip.c: Line: a=rtpmap:0 PCMU/8000 (20) Dec 24 20:54:43 DEBUG[6232] chan_sip.c: Line: a=rtpmap:2 G726-32/8000 (23) Dec 24 20:54:43 DEBUG[6232] chan_sip.c: Line: a=rtpmap:8 PCMA/8000 (20) Dec 24 20:54:43 DEBUG[6232] chan_sip.c: Line: a=rtpmap:18 G729a/8000 (22) Dec 24 20:54:43 DEBUG[6232] chan_sip.c: Line: a=rtpmap:96 G726-40/8000 (24) Dec 24 20:54:43 DEBUG[6232] chan_sip.c: Line: a=rtpmap:97 G726-24/8000 (24) Dec 24 20:54:43 DEBUG[6232] chan_sip.c: Line: a=rtpmap:98 G726-16/8000 (24) Dec 24 20:54:43 DEBUG[6232] chan_sip.c: Line: a=rtpmap:100 NSE/8000 (21) Dec 24 20:54:43 DEBUG[6232] chan_sip.c: Line: a=rtpmap:101 telephone-event/8000 (33) Dec 24 20:54:43 DEBUG[6232] chan_sip.c: Line: a=fmtp:101 0-15 (15) Dec 24 20:54:43 DEBUG[6232] chan_sip.c: Line: a=ptime:20 (10) Dec 24 20:54:43 DEBUG[6232] chan_sip.c: Line: a=sendrecv (10) Dec 24 20:54:43 VERBOSE[6232] logger.c: --- (15 headers 18 lines)Dec 24 20:54:43 VERBOSE[6232] logger.c: --- (15 headers 18 lines)--- Dec 24 20:54:43 DEBUG[6232] chan_sip.c: = Found Their Call ID: 3c7fc272-308803e3@192.168.254.251 Their Tag 3c72c92613678cbfo0 Our tag: as57085e68 Dec 24 20:54:43 DEBUG[6232] chan_sip.c: **** Received INVITE (5) - Command in SIP INVITE Dec 24 20:54:43 DEBUG[6232] chan_sip.c: Begin: parsing SIP "Supported: x-sipura" Dec 24 20:54:43 DEBUG[6232] chan_sip.c: Found SIP option: -x-sipura- Dec 24 20:54:43 DEBUG[6232] chan_sip.c: Found no match for SIP option: x-sipura (Please file bug report!) Dec 24 20:54:43 DEBUG[6232] chan_sip.c: * SIP extension value: 0 for call 3c7fc272-308803e3@192.168.254.251 Dec 24 20:54:43 VERBOSE[6232] logger.c: Using INVITE request as basis request - 3c7fc272-308803e3@192.168.254.251 Dec 24 20:54:43 VERBOSE[6232] logger.c: Sending to 192.168.254.251 : 5061 (non-NAT) Dec 24 20:54:43 DEBUG[6232] chan_sip.c: Setting NAT on RTP to 0 Dec 24 20:54:43 DEBUG[6232] chan_sip.c: Setting NAT on VRTP to 0 Dec 24 20:54:43 VERBOSE[6232] logger.c: Found user 'ext2001' Dec 24 20:54:43 VERBOSE[6232] logger.c: Found RTP audio format 0 Dec 24 20:54:43 VERBOSE[6232] logger.c: Found RTP audio format 2 Dec 24 20:54:43 VERBOSE[6232] logger.c: Found RTP audio format 8 Dec 24 20:54:43 VERBOSE[6232] logger.c: Found RTP audio format 18 Dec 24 20:54:43 VERBOSE[6232] logger.c: Found RTP audio format 96 Dec 24 20:54:43 VERBOSE[6232] logger.c: Found RTP audio format 97 Dec 24 20:54:43 VERBOSE[6232] logger.c: Found RTP audio format 98 Dec 24 20:54:43 VERBOSE[6232] logger.c: Found RTP audio format 100 Dec 24 20:54:43 VERBOSE[6232] logger.c: Found RTP audio format 101 Dec 24 20:54:43 VERBOSE[6232] logger.c: Peer audio RTP is at port 192.168.254.251:9086 Dec 24 20:54:43 DEBUG[6232] chan_sip.c: Peer audio RTP is at port 192.168.254.251:9086 Dec 24 20:54:43 VERBOSE[6232] logger.c: Peer video RTP is at port 192.168.254.251:65535 Dec 24 20:54:43 DEBUG[6232] chan_sip.c: Peer video RTP is at port 192.168.254.251:65535 Dec 24 20:54:43 VERBOSE[6232] logger.c: Found description format PCMU Dec 24 20:54:43 VERBOSE[6232] logger.c: Found description format G726-32 Dec 24 20:54:43 VERBOSE[6232] logger.c: Found description format PCMA Dec 24 20:54:43 VERBOSE[6232] logger.c: Found description format G729a Dec 24 20:54:43 VERBOSE[6232] logger.c: Found description format G726-40 Dec 24 20:54:43 VERBOSE[6232] logger.c: Found description format G726-24 Dec 24 20:54:43 VERBOSE[6232] logger.c: Found description format G726-16 Dec 24 20:54:43 VERBOSE[6232] logger.c: Found description format NSE Dec 24 20:54:43 VERBOSE[6232] logger.c: Found description format telephone-event Dec 24 20:54:43 VERBOSE[6232] logger.c: Capabilities: us - 0x105 (g723|ulaw|g729), peer - audio=0x51c (ulaw|alaw|g726|g729|ilbc)/video=0x0 (nothing), combined - 0x104 (ulaw|g729) Dec 24 20:54:43 VERBOSE[6232] logger.c: Non-codec capabilities: us - 0x1 (telephone-event), peer - 0x1 (telephone-event), combined - 0x1 (telephone-event) Dec 24 20:54:43 DEBUG[6232] chan_sip.c: Checking SIP call limits for device ext2001 Dec 24 20:54:43 DEBUG[6232] chan_sip.c: Updating call counter for incoming call Dec 24 20:54:43 VERBOSE[6232] logger.c: Looking for 2600 in home (domain 192.168.254.250) Dec 24 20:54:43 DEBUG[6232] chan_sip.c: build_route: Contact hop: Kai-Uwe Jensen Dec 24 20:54:43 VERBOSE[6232] logger.c: list_route: hop: Dec 24 20:54:43 VERBOSE[6232] logger.c: Transmitting (no NAT) to 192.168.254.251:5061: SIP/2.0 100 Trying Via: SIP/2.0/UDP 192.168.254.251:5061;branch=z9hG4bK-4eff0d7b;received=192.168.254.251 From: Kai-Uwe Jensen ;tag=3c72c92613678cbfo0 To: Call-ID: 3c7fc272-308803e3@192.168.254.251 CSeq: 102 INVITE User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Contact: Content-Length: 0 --- Dec 24 20:54:43 DEBUG[6229] chan_sip.c: Checking device state for peer ext2001 Dec 24 20:54:43 DEBUG[6229] devicestate.c: Changing state for SIP/ext2001 - state 2 (In use) Dec 24 20:54:43 DEBUG[6229] chan_sip.c: Checking device state for peer ext2001 Dec 24 20:54:43 DEBUG[6229] chan_sip.c: Checking device state for peer ext2001 Dec 24 20:54:43 DEBUG[6242] pbx.c: Launching 'Goto' Dec 24 20:54:43 VERBOSE[6242] logger.c: -- Executing Goto("SIP/ext2001-5f1d", "confline|s|1") in new stack Dec 24 20:54:43 VERBOSE[6242] logger.c: -- Goto (confline,s,1) Dec 24 20:54:43 DEBUG[6242] pbx.c: Launching 'Answer' Dec 24 20:54:43 VERBOSE[6242] logger.c: -- Executing Answer("SIP/ext2001-5f1d", "") in new stack Dec 24 20:54:43 DEBUG[6229] chan_sip.c: Checking device state for peer ext2001 Dec 24 20:54:43 DEBUG[6229] channel.c: Avoiding initial deadlock for 'SIP/ext2001-5f1d' Dec 24 20:54:43 DEBUG[6242] chan_sip.c: sip_answer(SIP/ext2001-5f1d) Dec 24 20:54:43 VERBOSE[6242] logger.c: We're at 192.168.254.250 port 9006 Dec 24 20:54:43 VERBOSE[6242] logger.c: Video is at 192.168.254.250 port 9040 Dec 24 20:54:43 DEBUG[6243] app_queue.c: Device 'SIP/ext2001' changed to state '2' (In use) Dec 24 20:54:43 VERBOSE[6242] logger.c: Adding codec 0x4 (ulaw) to SDP Dec 24 20:54:43 VERBOSE[6242] logger.c: Adding codec 0x100 (g729) to SDP Dec 24 20:54:43 VERBOSE[6242] logger.c: Adding codec 0x1 (g723) to SDP Dec 24 20:54:43 VERBOSE[6242] logger.c: Adding non-codec 0x1 (telephone-event) to SDP Dec 24 20:54:43 DEBUG[6229] channel.c: Avoiding initial deadlock for 'SIP/ext2001-5f1d' Dec 24 20:54:43 VERBOSE[6242] logger.c: Reliably Transmitting (no NAT) to 192.168.254.251:5061: SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.254.251:5061;branch=z9hG4bK-4eff0d7b;received=192.168.254.251 From: Kai-Uwe Jensen ;tag=3c72c92613678cbfo0 To: ;tag=as175d0445 Call-ID: 3c7fc272-308803e3@192.168.254.251 CSeq: 102 INVITE User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Contact: Content-Type: application/sdp Content-Length: 290 v=0 o=root 6178 6178 IN IP4 192.168.254.250 s=session c=IN IP4 192.168.254.250 t=0 0 m=audio 9006 RTP/AVP 0 18 4 101 a=rtpmap:0 PCMU/8000 a=rtpmap:18 G729/8000 a=fmtp:18 annexb=no a=rtpmap:4 G723/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=silenceSupp:off - - - - --- Dec 24 20:54:43 DEBUG[6242] chan_sip.c: *** SIP TIMER: Initalizing retransmit timer on packet: Id #65 Dec 24 20:54:43 DEBUG[6242] pbx.c: Launching 'Wait' Dec 24 20:54:43 VERBOSE[6242] logger.c: -- Executing Wait("SIP/ext2001-5f1d", "1") in new stack Dec 24 20:54:43 DEBUG[6229] devicestate.c: Changing state for SIP/ext2001 - state 2 (In use) Dec 24 20:54:43 DEBUG[6229] chan_sip.c: Checking device state for peer ext2001 Dec 24 20:54:43 DEBUG[6229] chan_sip.c: Checking device state for peer ext2001 Dec 24 20:54:43 DEBUG[6244] app_queue.c: Device 'SIP/ext2001' changed to state '2' (In use) Dec 24 20:54:43 VERBOSE[6232] logger.c: <-- SIP read from 192.168.254.251:5061: ACK sip:2600@192.168.254.250 SIP/2.0 Via: SIP/2.0/UDP 192.168.254.251:5061;branch=z9hG4bK-8ea18c72 From: Kai-Uwe Jensen ;tag=3c72c92613678cbfo0 To: ;tag=as175d0445 Call-ID: 3c7fc272-308803e3@192.168.254.251 CSeq: 102 ACK Max-Forwards: 70 Proxy-Authorization: Digest username="ext2001",realm="asterisk",nonce="7fb5280b",uri="sip:2600@192.168.254.250",algorithm=MD5,response="17b24523299297cb36dee8329aa931c7" Contact: Kai-Uwe Jensen User-Agent: Linksys/PAP2-3.1.6(LS) Content-Length: 0 Dec 24 20:54:43 DEBUG[6232] chan_sip.c: Header 0: ACK sip:2600@192.168.254.250 SIP/2.0 (36) Dec 24 20:54:43 DEBUG[6232] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.254.251:5061;branch=z9hG4bK-8ea18c72 (61) Dec 24 20:54:43 DEBUG[6232] chan_sip.c: Header 2: From: Kai-Uwe Jensen ;tag=3c72c92613678cbfo0 (73) Dec 24 20:54:43 DEBUG[6232] chan_sip.c: Header 3: To: ;tag=as175d0445 (45) Dec 24 20:54:43 DEBUG[6232] chan_sip.c: Header 4: Call-ID: 3c7fc272-308803e3@192.168.254.251 (42) Dec 24 20:54:43 DEBUG[6232] chan_sip.c: Header 5: CSeq: 102 ACK (13) Dec 24 20:54:43 DEBUG[6232] chan_sip.c: Header 6: Max-Forwards: 70 (16) Dec 24 20:54:43 DEBUG[6232] chan_sip.c: Header 7: Proxy-Authorization: Digest username="ext2001",realm="asterisk",nonce="7fb5280b",uri="sip:2600@192.168.254.250",algorithm=MD5,response="17b24523299297cb36dee8329aa931c7" (169) Dec 24 20:54:43 DEBUG[6232] chan_sip.c: Header 8: Contact: Kai-Uwe Jensen (58) Dec 24 20:54:43 DEBUG[6232] chan_sip.c: Header 9: User-Agent: Linksys/PAP2-3.1.6(LS) (34) Dec 24 20:54:43 DEBUG[6232] chan_sip.c: Header 10: Content-Length: 0 (17) Dec 24 20:54:43 DEBUG[6232] chan_sip.c: Header 11: (0) Dec 24 20:54:43 VERBOSE[6232] logger.c: --- (11 headers 0 lines)Dec 24 20:54:43 VERBOSE[6232] logger.c: --- (11 headers 0 lines)--- Dec 24 20:54:43 DEBUG[6232] chan_sip.c: = Found Their Call ID: 3c7fc272-308803e3@192.168.254.251 Their Tag 3c72c92613678cbfo0 Our tag: as175d0445 Dec 24 20:54:43 DEBUG[6232] chan_sip.c: **** Received ACK (6) - Command in SIP ACK Dec 24 20:54:43 DEBUG[6232] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #65 Dec 24 20:54:43 DEBUG[6232] chan_sip.c: Stopping retransmission on '3c7fc272-308803e3@192.168.254.251' of Response 102: Match Found Dec 24 20:54:44 DEBUG[6242] pbx.c: Launching 'BackGround' Dec 24 20:54:44 VERBOSE[6242] logger.c: -- Executing BackGround("SIP/ext2001-5f1d", "enter-conf-call-number") in new stack Dec 24 20:54:44 DEBUG[6242] channel.c: Set channel SIP/ext2001-5f1d to write format gsm Dec 24 20:54:44 DEBUG[6242] rtp.c: Ooh, format changed from unknown to ulaw Dec 24 20:54:44 DEBUG[6242] channel.c: Scheduling timer at 160 sample intervals Dec 24 20:54:44 VERBOSE[6242] logger.c: -- Playing 'enter-conf-call-number' (language 'en') Dec 24 20:54:45 DEBUG[6232] chan_sip.c: Auto destroying call 'a339b172f412297d@TUFETUFOMi5hbWVyaWNhcy5ocHFjb3JwLm5ldA..' Dec 24 20:54:45 VERBOSE[6232] logger.c: Destroying call 'a339b172f412297d@TUFETUFOMi5hbWVyaWNhcy5ocHFjb3JwLm5ldA..' Dec 24 20:54:47 VERBOSE[6232] logger.c: <-- SIP read from 192.168.254.251:5061: INFO sip:2600@192.168.254.250 SIP/2.0 Via: SIP/2.0/UDP 192.168.254.251:5061;branch=z9hG4bK-2c0c392 From: Kai-Uwe Jensen ;tag=3c72c92613678cbfo0 To: ;tag=as175d0445 Call-ID: 3c7fc272-308803e3@192.168.254.251 CSeq: 103 INFO Max-Forwards: 70 Proxy-Authorization: Digest username="ext2001",realm="asterisk",nonce="7fb5280b",uri="sip:2600@192.168.254.250",algorithm=MD5,response="2ef20226cd9a9d9ceef779b41c2e78bb" User-Agent: Linksys/PAP2-3.1.6(LS) Content-Length: 24 Content-Type: application/dtmf-relay Signal=8 Duration=100 Dec 24 20:54:47 DEBUG[6232] chan_sip.c: Header 0: INFO sip:2600@192.168.254.250 SIP/2.0 (37) Dec 24 20:54:47 DEBUG[6232] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.254.251:5061;branch=z9hG4bK-2c0c392 (60) Dec 24 20:54:47 DEBUG[6232] chan_sip.c: Header 2: From: Kai-Uwe Jensen ;tag=3c72c92613678cbfo0 (73) Dec 24 20:54:47 DEBUG[6232] chan_sip.c: Header 3: To: ;tag=as175d0445 (45) Dec 24 20:54:47 DEBUG[6232] chan_sip.c: Header 4: Call-ID: 3c7fc272-308803e3@192.168.254.251 (42) Dec 24 20:54:47 DEBUG[6232] chan_sip.c: Header 5: CSeq: 103 INFO (14) Dec 24 20:54:47 DEBUG[6232] chan_sip.c: Header 6: Max-Forwards: 70 (16) Dec 24 20:54:47 DEBUG[6232] chan_sip.c: Header 7: Proxy-Authorization: Digest username="ext2001",realm="asterisk",nonce="7fb5280b",uri="sip:2600@192.168.254.250",algorithm=MD5,response="2ef20226cd9a9d9ceef779b41c2e78bb" (169) Dec 24 20:54:47 DEBUG[6232] chan_sip.c: Header 8: User-Agent: Linksys/PAP2-3.1.6(LS) (34) Dec 24 20:54:47 DEBUG[6232] chan_sip.c: Header 9: Content-Length: 24 (18) Dec 24 20:54:47 DEBUG[6232] chan_sip.c: Header 10: Content-Type: application/dtmf-relay (36) Dec 24 20:54:47 DEBUG[6232] chan_sip.c: Header 11: (0) Dec 24 20:54:47 DEBUG[6232] chan_sip.c: Line: Signal=8 (8) Dec 24 20:54:47 DEBUG[6232] chan_sip.c: Line: Duration=100 (12) Dec 24 20:54:47 VERBOSE[6232] logger.c: --- (11 headers 2 lines)Dec 24 20:54:47 VERBOSE[6232] logger.c: --- (11 headers 2 lines)--- Dec 24 20:54:47 DEBUG[6232] chan_sip.c: = Found Their Call ID: 3c7fc272-308803e3@192.168.254.251 Their Tag 3c72c92613678cbfo0 Our tag: as175d0445 Dec 24 20:54:47 DEBUG[6232] chan_sip.c: **** Received INFO (13) - Command in SIP INFO Dec 24 20:54:47 VERBOSE[6232] logger.c: Receiving INFO! Dec 24 20:54:47 VERBOSE[6232] logger.c: * DTMF-relay event received: 8 Dec 24 20:54:47 VERBOSE[6232] logger.c: Transmitting (no NAT) to 192.168.254.251:5061: SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.254.251:5061;branch=z9hG4bK-2c0c392;received=192.168.254.251 From: Kai-Uwe Jensen ;tag=3c72c92613678cbfo0 To: ;tag=as175d0445 Call-ID: 3c7fc272-308803e3@192.168.254.251 CSeq: 103 INFO User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Contact: Content-Length: 0 --- Dec 24 20:54:47 DEBUG[6242] channel.c: Scheduling timer at 0 sample intervals Dec 24 20:54:47 DEBUG[6242] channel.c: Set channel SIP/ext2001-5f1d to write format ulaw Dec 24 20:54:47 DEBUG[6242] pbx.c: Oooh, got something to jump out with ('8')! Dec 24 20:54:47 VERBOSE[6232] logger.c: <-- SIP read from 192.168.254.1:1025: Dec 24 20:54:47 DEBUG[6232] chan_sip.c: Header 0: (0) Dec 24 20:54:47 VERBOSE[6232] logger.c: --- (0 headers 0 lines)Dec 24 20:54:47 VERBOSE[6232] logger.c: --- (0 headers 0 lines) Nat keepalive Dec 24 20:54:47 VERBOSE[6232] logger.c: --- (0 headers 0 lines) Nat keepalive --- Dec 24 20:54:48 VERBOSE[6232] logger.c: <-- SIP read from 192.168.254.251:5061: INFO sip:2600@192.168.254.250 SIP/2.0 Via: SIP/2.0/UDP 192.168.254.251:5061;branch=z9hG4bK-d845f71e From: Kai-Uwe Jensen ;tag=3c72c92613678cbfo0 To: ;tag=as175d0445 Call-ID: 3c7fc272-308803e3@192.168.254.251 CSeq: 104 INFO Max-Forwards: 70 Proxy-Authorization: Digest username="ext2001",realm="asterisk",nonce="7fb5280b",uri="sip:2600@192.168.254.250",algorithm=MD5,response="2ef20226cd9a9d9ceef779b41c2e78bb" User-Agent: Linksys/PAP2-3.1.6(LS) Content-Length: 24 Content-Type: application/dtmf-relay Signal=9 Duration=100 Dec 24 20:54:48 DEBUG[6232] chan_sip.c: Header 0: INFO sip:2600@192.168.254.250 SIP/2.0 (37) Dec 24 20:54:48 DEBUG[6232] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.254.251:5061;branch=z9hG4bK-d845f71e (61) Dec 24 20:54:48 DEBUG[6232] chan_sip.c: Header 2: From: Kai-Uwe Jensen ;tag=3c72c92613678cbfo0 (73) Dec 24 20:54:48 DEBUG[6232] chan_sip.c: Header 3: To: ;tag=as175d0445 (45) Dec 24 20:54:48 DEBUG[6232] chan_sip.c: Header 4: Call-ID: 3c7fc272-308803e3@192.168.254.251 (42) Dec 24 20:54:48 DEBUG[6232] chan_sip.c: Header 5: CSeq: 104 INFO (14) Dec 24 20:54:48 DEBUG[6232] chan_sip.c: Header 6: Max-Forwards: 70 (16) Dec 24 20:54:48 DEBUG[6232] chan_sip.c: Header 7: Proxy-Authorization: Digest username="ext2001",realm="asterisk",nonce="7fb5280b",uri="sip:2600@192.168.254.250",algorithm=MD5,response="2ef20226cd9a9d9ceef779b41c2e78bb" (169) Dec 24 20:54:48 DEBUG[6232] chan_sip.c: Header 8: User-Agent: Linksys/PAP2-3.1.6(LS) (34) Dec 24 20:54:48 DEBUG[6232] chan_sip.c: Header 9: Content-Length: 24 (18) Dec 24 20:54:48 DEBUG[6232] chan_sip.c: Header 10: Content-Type: application/dtmf-relay (36) Dec 24 20:54:48 DEBUG[6232] chan_sip.c: Header 11: (0) Dec 24 20:54:48 DEBUG[6232] chan_sip.c: Line: Signal=9 (8) Dec 24 20:54:48 DEBUG[6232] chan_sip.c: Line: Duration=100 (12) Dec 24 20:54:48 VERBOSE[6232] logger.c: --- (11 headers 2 lines)Dec 24 20:54:48 VERBOSE[6232] logger.c: --- (11 headers 2 lines)--- Dec 24 20:54:48 DEBUG[6232] chan_sip.c: = Found Their Call ID: 3c7fc272-308803e3@192.168.254.251 Their Tag 3c72c92613678cbfo0 Our tag: as175d0445 Dec 24 20:54:48 DEBUG[6232] chan_sip.c: **** Received INFO (13) - Command in SIP INFO Dec 24 20:54:48 VERBOSE[6232] logger.c: Receiving INFO! Dec 24 20:54:48 VERBOSE[6232] logger.c: * DTMF-relay event received: 9 Dec 24 20:54:48 VERBOSE[6232] logger.c: Transmitting (no NAT) to 192.168.254.251:5061: SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.254.251:5061;branch=z9hG4bK-d845f71e;received=192.168.254.251 From: Kai-Uwe Jensen ;tag=3c72c92613678cbfo0 To: ;tag=as175d0445 Call-ID: 3c7fc272-308803e3@192.168.254.251 CSeq: 104 INFO User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Contact: Content-Length: 0 --- Dec 24 20:54:48 VERBOSE[6232] logger.c: <-- SIP read from 192.168.254.251:5061: INFO sip:2600@192.168.254.250 SIP/2.0 Via: SIP/2.0/UDP 192.168.254.251:5061;branch=z9hG4bK-29a1791b From: Kai-Uwe Jensen ;tag=3c72c92613678cbfo0 To: ;tag=as175d0445 Call-ID: 3c7fc272-308803e3@192.168.254.251 CSeq: 105 INFO Max-Forwards: 70 Proxy-Authorization: Digest username="ext2001",realm="asterisk",nonce="7fb5280b",uri="sip:2600@192.168.254.250",algorithm=MD5,response="2ef20226cd9a9d9ceef779b41c2e78bb" User-Agent: Linksys/PAP2-3.1.6(LS) Content-Length: 24 Content-Type: application/dtmf-relay Signal=8 Duration=100 Dec 24 20:54:48 DEBUG[6232] chan_sip.c: Header 0: INFO sip:2600@192.168.254.250 SIP/2.0 (37) Dec 24 20:54:48 DEBUG[6232] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.254.251:5061;branch=z9hG4bK-29a1791b (61) Dec 24 20:54:48 DEBUG[6232] chan_sip.c: Header 2: From: Kai-Uwe Jensen ;tag=3c72c92613678cbfo0 (73) Dec 24 20:54:48 DEBUG[6232] chan_sip.c: Header 3: To: ;tag=as175d0445 (45) Dec 24 20:54:48 DEBUG[6232] chan_sip.c: Header 4: Call-ID: 3c7fc272-308803e3@192.168.254.251 (42) Dec 24 20:54:48 DEBUG[6232] chan_sip.c: Header 5: CSeq: 105 INFO (14) Dec 24 20:54:48 DEBUG[6232] chan_sip.c: Header 6: Max-Forwards: 70 (16) Dec 24 20:54:48 DEBUG[6232] chan_sip.c: Header 7: Proxy-Authorization: Digest username="ext2001",realm="asterisk",nonce="7fb5280b",uri="sip:2600@192.168.254.250",algorithm=MD5,response="2ef20226cd9a9d9ceef779b41c2e78bb" (169) Dec 24 20:54:48 DEBUG[6232] chan_sip.c: Header 8: User-Agent: Linksys/PAP2-3.1.6(LS) (34) Dec 24 20:54:48 DEBUG[6232] chan_sip.c: Header 9: Content-Length: 24 (18) Dec 24 20:54:48 DEBUG[6232] chan_sip.c: Header 10: Content-Type: application/dtmf-relay (36) Dec 24 20:54:48 DEBUG[6232] chan_sip.c: Header 11: (0) Dec 24 20:54:48 DEBUG[6232] chan_sip.c: Line: Signal=8 (8) Dec 24 20:54:48 DEBUG[6232] chan_sip.c: Line: Duration=100 (12) Dec 24 20:54:48 VERBOSE[6232] logger.c: --- (11 headers 2 lines)Dec 24 20:54:48 VERBOSE[6232] logger.c: --- (11 headers 2 lines)--- Dec 24 20:54:48 DEBUG[6232] chan_sip.c: = Found Their Call ID: 3c7fc272-308803e3@192.168.254.251 Their Tag 3c72c92613678cbfo0 Our tag: as175d0445 Dec 24 20:54:48 DEBUG[6232] chan_sip.c: **** Received INFO (13) - Command in SIP INFO Dec 24 20:54:48 VERBOSE[6232] logger.c: Receiving INFO! Dec 24 20:54:48 VERBOSE[6232] logger.c: * DTMF-relay event received: 8 Dec 24 20:54:48 VERBOSE[6232] logger.c: Transmitting (no NAT) to 192.168.254.251:5061: SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.254.251:5061;branch=z9hG4bK-29a1791b;received=192.168.254.251 From: Kai-Uwe Jensen ;tag=3c72c92613678cbfo0 To: ;tag=as175d0445 Call-ID: 3c7fc272-308803e3@192.168.254.251 CSeq: 105 INFO User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Contact: Content-Length: 0 --- Dec 24 20:54:48 VERBOSE[6232] logger.c: <-- SIP read from 192.168.254.251:5061: INFO sip:2600@192.168.254.250 SIP/2.0 Via: SIP/2.0/UDP 192.168.254.251:5061;branch=z9hG4bK-a829177d From: Kai-Uwe Jensen ;tag=3c72c92613678cbfo0 To: ;tag=as175d0445 Call-ID: 3c7fc272-308803e3@192.168.254.251 CSeq: 106 INFO Max-Forwards: 70 Proxy-Authorization: Digest username="ext2001",realm="asterisk",nonce="7fb5280b",uri="sip:2600@192.168.254.250",algorithm=MD5,response="2ef20226cd9a9d9ceef779b41c2e78bb" User-Agent: Linksys/PAP2-3.1.6(LS) Content-Length: 24 Content-Type: application/dtmf-relay Signal=7 Duration=100 Dec 24 20:54:48 DEBUG[6232] chan_sip.c: Header 0: INFO sip:2600@192.168.254.250 SIP/2.0 (37) Dec 24 20:54:48 DEBUG[6232] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.254.251:5061;branch=z9hG4bK-a829177d (61) Dec 24 20:54:48 DEBUG[6232] chan_sip.c: Header 2: From: Kai-Uwe Jensen ;tag=3c72c92613678cbfo0 (73) Dec 24 20:54:48 DEBUG[6232] chan_sip.c: Header 3: To: ;tag=as175d0445 (45) Dec 24 20:54:48 DEBUG[6232] chan_sip.c: Header 4: Call-ID: 3c7fc272-308803e3@192.168.254.251 (42) Dec 24 20:54:48 DEBUG[6232] chan_sip.c: Header 5: CSeq: 106 INFO (14) Dec 24 20:54:48 DEBUG[6232] chan_sip.c: Header 6: Max-Forwards: 70 (16) Dec 24 20:54:48 DEBUG[6232] chan_sip.c: Header 7: Proxy-Authorization: Digest username="ext2001",realm="asterisk",nonce="7fb5280b",uri="sip:2600@192.168.254.250",algorithm=MD5,response="2ef20226cd9a9d9ceef779b41c2e78bb" (169) Dec 24 20:54:48 DEBUG[6232] chan_sip.c: Header 8: User-Agent: Linksys/PAP2-3.1.6(LS) (34) Dec 24 20:54:48 DEBUG[6232] chan_sip.c: Header 9: Content-Length: 24 (18) Dec 24 20:54:48 DEBUG[6232] chan_sip.c: Header 10: Content-Type: application/dtmf-relay (36) Dec 24 20:54:48 DEBUG[6232] chan_sip.c: Header 11: (0) Dec 24 20:54:48 DEBUG[6232] chan_sip.c: Line: Signal=7 (8) Dec 24 20:54:48 DEBUG[6232] chan_sip.c: Line: Duration=100 (12) Dec 24 20:54:48 VERBOSE[6232] logger.c: --- (11 headers 2 lines)Dec 24 20:54:48 VERBOSE[6232] logger.c: --- (11 headers 2 lines)--- Dec 24 20:54:48 DEBUG[6232] chan_sip.c: = Found Their Call ID: 3c7fc272-308803e3@192.168.254.251 Their Tag 3c72c92613678cbfo0 Our tag: as175d0445 Dec 24 20:54:48 DEBUG[6232] chan_sip.c: **** Received INFO (13) - Command in SIP INFO Dec 24 20:54:48 VERBOSE[6232] logger.c: Receiving INFO! Dec 24 20:54:48 VERBOSE[6232] logger.c: * DTMF-relay event received: 7 Dec 24 20:54:48 VERBOSE[6232] logger.c: Transmitting (no NAT) to 192.168.254.251:5061: SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.254.251:5061;branch=z9hG4bK-a829177d;received=192.168.254.251 From: Kai-Uwe Jensen ;tag=3c72c92613678cbfo0 To: ;tag=as175d0445 Call-ID: 3c7fc272-308803e3@192.168.254.251 CSeq: 106 INFO User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Contact: Content-Length: 0 --- Dec 24 20:54:49 VERBOSE[6232] logger.c: <-- SIP read from 192.168.254.251:5061: INFO sip:2600@192.168.254.250 SIP/2.0 Via: SIP/2.0/UDP 192.168.254.251:5061;branch=z9hG4bK-b2c63cab From: Kai-Uwe Jensen ;tag=3c72c92613678cbfo0 To: ;tag=as175d0445 Call-ID: 3c7fc272-308803e3@192.168.254.251 CSeq: 107 INFO Max-Forwards: 70 Proxy-Authorization: Digest username="ext2001",realm="asterisk",nonce="7fb5280b",uri="sip:2600@192.168.254.250",algorithm=MD5,response="2ef20226cd9a9d9ceef779b41c2e78bb" User-Agent: Linksys/PAP2-3.1.6(LS) Content-Length: 24 Content-Type: application/dtmf-relay Signal=7 Duration=100 Dec 24 20:54:49 DEBUG[6232] chan_sip.c: Header 0: INFO sip:2600@192.168.254.250 SIP/2.0 (37) Dec 24 20:54:49 DEBUG[6232] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.254.251:5061;branch=z9hG4bK-b2c63cab (61) Dec 24 20:54:49 DEBUG[6232] chan_sip.c: Header 2: From: Kai-Uwe Jensen ;tag=3c72c92613678cbfo0 (73) Dec 24 20:54:49 DEBUG[6232] chan_sip.c: Header 3: To: ;tag=as175d0445 (45) Dec 24 20:54:49 DEBUG[6232] chan_sip.c: Header 4: Call-ID: 3c7fc272-308803e3@192.168.254.251 (42) Dec 24 20:54:49 DEBUG[6232] chan_sip.c: Header 5: CSeq: 107 INFO (14) Dec 24 20:54:49 DEBUG[6232] chan_sip.c: Header 6: Max-Forwards: 70 (16) Dec 24 20:54:49 DEBUG[6232] chan_sip.c: Header 7: Proxy-Authorization: Digest username="ext2001",realm="asterisk",nonce="7fb5280b",uri="sip:2600@192.168.254.250",algorithm=MD5,response="2ef20226cd9a9d9ceef779b41c2e78bb" (169) Dec 24 20:54:49 DEBUG[6232] chan_sip.c: Header 8: User-Agent: Linksys/PAP2-3.1.6(LS) (34) Dec 24 20:54:49 DEBUG[6232] chan_sip.c: Header 9: Content-Length: 24 (18) Dec 24 20:54:49 DEBUG[6232] chan_sip.c: Header 10: Content-Type: application/dtmf-relay (36) Dec 24 20:54:49 DEBUG[6232] chan_sip.c: Header 11: (0) Dec 24 20:54:49 DEBUG[6232] chan_sip.c: Line: Signal=7 (8) Dec 24 20:54:49 DEBUG[6232] chan_sip.c: Line: Duration=100 (12) Dec 24 20:54:49 VERBOSE[6232] logger.c: --- (11 headers 2 lines)Dec 24 20:54:49 VERBOSE[6232] logger.c: --- (11 headers 2 lines)--- Dec 24 20:54:49 DEBUG[6232] chan_sip.c: = Found Their Call ID: 3c7fc272-308803e3@192.168.254.251 Their Tag 3c72c92613678cbfo0 Our tag: as175d0445 Dec 24 20:54:49 DEBUG[6232] chan_sip.c: **** Received INFO (13) - Command in SIP INFO Dec 24 20:54:49 VERBOSE[6232] logger.c: Receiving INFO! Dec 24 20:54:49 VERBOSE[6232] logger.c: * DTMF-relay event received: 7 Dec 24 20:54:49 VERBOSE[6232] logger.c: Transmitting (no NAT) to 192.168.254.251:5061: SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.254.251:5061;branch=z9hG4bK-b2c63cab;received=192.168.254.251 From: Kai-Uwe Jensen ;tag=3c72c92613678cbfo0 To: ;tag=as175d0445 Call-ID: 3c7fc272-308803e3@192.168.254.251 CSeq: 107 INFO User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Contact: Content-Length: 0 --- Dec 24 20:54:50 VERBOSE[6232] logger.c: <-- SIP read from 192.168.254.251:5061: INFO sip:2600@192.168.254.250 SIP/2.0 Via: SIP/2.0/UDP 192.168.254.251:5061;branch=z9hG4bK-d698a99b From: Kai-Uwe Jensen ;tag=3c72c92613678cbfo0 To: ;tag=as175d0445 Call-ID: 3c7fc272-308803e3@192.168.254.251 CSeq: 108 INFO Max-Forwards: 70 Proxy-Authorization: Digest username="ext2001",realm="asterisk",nonce="7fb5280b",uri="sip:2600@192.168.254.250",algorithm=MD5,response="2ef20226cd9a9d9ceef779b41c2e78bb" User-Agent: Linksys/PAP2-3.1.6(LS) Content-Length: 24 Content-Type: application/dtmf-relay Signal=6 Duration=100 Dec 24 20:54:50 DEBUG[6232] chan_sip.c: Header 0: INFO sip:2600@192.168.254.250 SIP/2.0 (37) Dec 24 20:54:50 DEBUG[6232] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.254.251:5061;branch=z9hG4bK-d698a99b (61) Dec 24 20:54:50 DEBUG[6232] chan_sip.c: Header 2: From: Kai-Uwe Jensen ;tag=3c72c92613678cbfo0 (73) Dec 24 20:54:50 DEBUG[6232] chan_sip.c: Header 3: To: ;tag=as175d0445 (45) Dec 24 20:54:50 DEBUG[6232] chan_sip.c: Header 4: Call-ID: 3c7fc272-308803e3@192.168.254.251 (42) Dec 24 20:54:50 DEBUG[6232] chan_sip.c: Header 5: CSeq: 108 INFO (14) Dec 24 20:54:50 DEBUG[6232] chan_sip.c: Header 6: Max-Forwards: 70 (16) Dec 24 20:54:50 DEBUG[6232] chan_sip.c: Header 7: Proxy-Authorization: Digest username="ext2001",realm="asterisk",nonce="7fb5280b",uri="sip:2600@192.168.254.250",algorithm=MD5,response="2ef20226cd9a9d9ceef779b41c2e78bb" (169) Dec 24 20:54:50 DEBUG[6232] chan_sip.c: Header 8: User-Agent: Linksys/PAP2-3.1.6(LS) (34) Dec 24 20:54:50 DEBUG[6232] chan_sip.c: Header 9: Content-Length: 24 (18) Dec 24 20:54:50 DEBUG[6232] chan_sip.c: Header 10: Content-Type: application/dtmf-relay (36) Dec 24 20:54:50 DEBUG[6232] chan_sip.c: Header 11: (0) Dec 24 20:54:50 DEBUG[6232] chan_sip.c: Line: Signal=6 (8) Dec 24 20:54:50 DEBUG[6232] chan_sip.c: Line: Duration=100 (12) Dec 24 20:54:50 VERBOSE[6232] logger.c: --- (11 headers 2 lines)Dec 24 20:54:50 VERBOSE[6232] logger.c: --- (11 headers 2 lines)--- Dec 24 20:54:50 DEBUG[6232] chan_sip.c: = Found Their Call ID: 3c7fc272-308803e3@192.168.254.251 Their Tag 3c72c92613678cbfo0 Our tag: as175d0445 Dec 24 20:54:50 DEBUG[6232] chan_sip.c: **** Received INFO (13) - Command in SIP INFO Dec 24 20:54:50 VERBOSE[6232] logger.c: Receiving INFO! Dec 24 20:54:50 VERBOSE[6232] logger.c: * DTMF-relay event received: 6 Dec 24 20:54:50 VERBOSE[6232] logger.c: Transmitting (no NAT) to 192.168.254.251:5061: SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.254.251:5061;branch=z9hG4bK-d698a99b;received=192.168.254.251 From: Kai-Uwe Jensen ;tag=3c72c92613678cbfo0 To: ;tag=as175d0445 Call-ID: 3c7fc272-308803e3@192.168.254.251 CSeq: 108 INFO User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Contact: Content-Length: 0 --- Dec 24 20:54:50 DEBUG[6234] chan_iax2.c: Allocate call number Dec 24 20:54:50 DEBUG[6234] chan_iax2.c: Registration created on call 2 Dec 24 20:54:50 VERBOSE[6232] logger.c: <-- SIP read from 192.168.254.251:5061: INFO sip:2600@192.168.254.250 SIP/2.0 Via: SIP/2.0/UDP 192.168.254.251:5061;branch=z9hG4bK-89e7a1bb From: Kai-Uwe Jensen ;tag=3c72c92613678cbfo0 To: ;tag=as175d0445 Call-ID: 3c7fc272-308803e3@192.168.254.251 CSeq: 109 INFO Max-Forwards: 70 Proxy-Authorization: Digest username="ext2001",realm="asterisk",nonce="7fb5280b",uri="sip:2600@192.168.254.250",algorithm=MD5,response="2ef20226cd9a9d9ceef779b41c2e78bb" User-Agent: Linksys/PAP2-3.1.6(LS) Content-Length: 24 Content-Type: application/dtmf-relay Signal=2 Duration=100 Dec 24 20:54:50 DEBUG[6232] chan_sip.c: Header 0: INFO sip:2600@192.168.254.250 SIP/2.0 (37) Dec 24 20:54:50 DEBUG[6232] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.254.251:5061;branch=z9hG4bK-89e7a1bb (61) Dec 24 20:54:50 DEBUG[6232] chan_sip.c: Header 2: From: Kai-Uwe Jensen ;tag=3c72c92613678cbfo0 (73) Dec 24 20:54:50 DEBUG[6232] chan_sip.c: Header 3: To: ;tag=as175d0445 (45) Dec 24 20:54:50 DEBUG[6232] chan_sip.c: Header 4: Call-ID: 3c7fc272-308803e3@192.168.254.251 (42) Dec 24 20:54:50 DEBUG[6232] chan_sip.c: Header 5: CSeq: 109 INFO (14) Dec 24 20:54:50 DEBUG[6232] chan_sip.c: Header 6: Max-Forwards: 70 (16) Dec 24 20:54:50 DEBUG[6232] chan_sip.c: Header 7: Proxy-Authorization: Digest username="ext2001",realm="asterisk",nonce="7fb5280b",uri="sip:2600@192.168.254.250",algorithm=MD5,response="2ef20226cd9a9d9ceef779b41c2e78bb" (169) Dec 24 20:54:50 DEBUG[6232] chan_sip.c: Header 8: User-Agent: Linksys/PAP2-3.1.6(LS) (34) Dec 24 20:54:50 DEBUG[6232] chan_sip.c: Header 9: Content-Length: 24 (18) Dec 24 20:54:50 DEBUG[6232] chan_sip.c: Header 10: Content-Type: application/dtmf-relay (36) Dec 24 20:54:50 DEBUG[6232] chan_sip.c: Header 11: (0) Dec 24 20:54:50 DEBUG[6232] chan_sip.c: Line: Signal=2 (8) Dec 24 20:54:50 DEBUG[6232] chan_sip.c: Line: Duration=100 (12) Dec 24 20:54:50 VERBOSE[6232] logger.c: --- (11 headers 2 lines)Dec 24 20:54:50 VERBOSE[6232] logger.c: --- (11 headers 2 lines)--- Dec 24 20:54:50 DEBUG[6232] chan_sip.c: = Found Their Call ID: 3c7fc272-308803e3@192.168.254.251 Their Tag 3c72c92613678cbfo0 Our tag: as175d0445 Dec 24 20:54:50 DEBUG[6232] chan_sip.c: **** Received INFO (13) - Command in SIP INFO Dec 24 20:54:50 VERBOSE[6232] logger.c: Receiving INFO! Dec 24 20:54:50 VERBOSE[6232] logger.c: * DTMF-relay event received: 2 Dec 24 20:54:50 VERBOSE[6232] logger.c: Transmitting (no NAT) to 192.168.254.251:5061: SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.254.251:5061;branch=z9hG4bK-89e7a1bb;received=192.168.254.251 From: Kai-Uwe Jensen ;tag=3c72c92613678cbfo0 To: ;tag=as175d0445 Call-ID: 3c7fc272-308803e3@192.168.254.251 CSeq: 109 INFO User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Contact: Content-Length: 0 --- Dec 24 20:54:51 VERBOSE[6232] logger.c: <-- SIP read from 192.168.254.251:5061: INFO sip:2600@192.168.254.250 SIP/2.0 Via: SIP/2.0/UDP 192.168.254.251:5061;branch=z9hG4bK-6f98086a From: Kai-Uwe Jensen ;tag=3c72c92613678cbfo0 To: ;tag=as175d0445 Call-ID: 3c7fc272-308803e3@192.168.254.251 CSeq: 110 INFO Max-Forwards: 70 Proxy-Authorization: Digest username="ext2001",realm="asterisk",nonce="7fb5280b",uri="sip:2600@192.168.254.250",algorithm=MD5,response="2ef20226cd9a9d9ceef779b41c2e78bb" User-Agent: Linksys/PAP2-3.1.6(LS) Content-Length: 24 Content-Type: application/dtmf-relay Signal=# Duration=100 Dec 24 20:54:51 DEBUG[6232] chan_sip.c: Header 0: INFO sip:2600@192.168.254.250 SIP/2.0 (37) Dec 24 20:54:51 DEBUG[6232] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.254.251:5061;branch=z9hG4bK-6f98086a (61) Dec 24 20:54:51 DEBUG[6232] chan_sip.c: Header 2: From: Kai-Uwe Jensen ;tag=3c72c92613678cbfo0 (73) Dec 24 20:54:51 DEBUG[6232] chan_sip.c: Header 3: To: ;tag=as175d0445 (45) Dec 24 20:54:51 DEBUG[6232] chan_sip.c: Header 4: Call-ID: 3c7fc272-308803e3@192.168.254.251 (42) Dec 24 20:54:51 DEBUG[6232] chan_sip.c: Header 5: CSeq: 110 INFO (14) Dec 24 20:54:51 DEBUG[6232] chan_sip.c: Header 6: Max-Forwards: 70 (16) Dec 24 20:54:51 DEBUG[6232] chan_sip.c: Header 7: Proxy-Authorization: Digest username="ext2001",realm="asterisk",nonce="7fb5280b",uri="sip:2600@192.168.254.250",algorithm=MD5,response="2ef20226cd9a9d9ceef779b41c2e78bb" (169) Dec 24 20:54:51 DEBUG[6232] chan_sip.c: Header 8: User-Agent: Linksys/PAP2-3.1.6(LS) (34) Dec 24 20:54:51 DEBUG[6232] chan_sip.c: Header 9: Content-Length: 24 (18) Dec 24 20:54:51 DEBUG[6232] chan_sip.c: Header 10: Content-Type: application/dtmf-relay (36) Dec 24 20:54:51 DEBUG[6232] chan_sip.c: Header 11: (0) Dec 24 20:54:51 DEBUG[6232] chan_sip.c: Line: Signal=# (8) Dec 24 20:54:51 DEBUG[6232] chan_sip.c: Line: Duration=100 (12) Dec 24 20:54:51 VERBOSE[6232] logger.c: --- (11 headers 2 lines)Dec 24 20:54:51 VERBOSE[6232] logger.c: --- (11 headers 2 lines)--- Dec 24 20:54:51 DEBUG[6232] chan_sip.c: = Found Their Call ID: 3c7fc272-308803e3@192.168.254.251 Their Tag 3c72c92613678cbfo0 Our tag: as175d0445 Dec 24 20:54:51 DEBUG[6232] chan_sip.c: **** Received INFO (13) - Command in SIP INFO Dec 24 20:54:51 VERBOSE[6232] logger.c: Receiving INFO! Dec 24 20:54:51 VERBOSE[6232] logger.c: * DTMF-relay event received: # Dec 24 20:54:51 VERBOSE[6232] logger.c: Transmitting (no NAT) to 192.168.254.251:5061: SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.254.251:5061;branch=z9hG4bK-6f98086a;received=192.168.254.251 From: Kai-Uwe Jensen ;tag=3c72c92613678cbfo0 To: ;tag=as175d0445 Call-ID: 3c7fc272-308803e3@192.168.254.251 CSeq: 110 INFO User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Contact: Content-Length: 0 --- Dec 24 20:54:51 VERBOSE[6242] logger.c: == CDR updated on SIP/ext2001-5f1d Dec 24 20:54:51 DEBUG[6242] pbx.c: Launching 'Goto' Dec 24 20:54:51 VERBOSE[6242] logger.c: -- Executing Goto("SIP/ext2001-5f1d", "8987762|1") in new stack Dec 24 20:54:51 VERBOSE[6242] logger.c: -- Goto (confline,8987762,1) Dec 24 20:54:51 DEBUG[6242] pbx.c: Launching 'Wait' Dec 24 20:54:51 VERBOSE[6242] logger.c: -- Executing Wait("SIP/ext2001-5f1d", "1") in new stack Dec 24 20:54:52 DEBUG[6242] pbx.c: Launching 'MeetMe' Dec 24 20:54:52 VERBOSE[6242] logger.c: -- Executing MeetMe("SIP/ext2001-5f1d", "8987762|MPxwsvn") in new stack Dec 24 20:54:52 DEBUG[6242] config.c: Parsing /etc/asterisk/meetme.conf Dec 24 20:54:52 DEBUG[6242] chan_zap.c: Using channel -2 Dec 24 20:54:52 DEBUG[6242] channel.c: Set channel Zap/pseudo-816494611 to read format slin Dec 24 20:54:52 DEBUG[6242] channel.c: Set channel Zap/pseudo-816494611 to write format slin Dec 24 20:54:52 VERBOSE[6242] logger.c: -- Created MeetMe conference 1023 for conference '8987762' Dec 24 20:54:52 DEBUG[6242] channel.c: Set channel SIP/ext2001-5f1d to write format gsm Dec 24 20:54:52 DEBUG[6242] rtp.c: Difference is 39736, ms is 4987 Dec 24 20:54:52 DEBUG[6242] channel.c: Scheduling timer at 160 sample intervals Dec 24 20:54:52 VERBOSE[6242] logger.c: -- Playing 'conf-waitforleader' (language 'en') Dec 24 20:54:52 DEBUG[6229] devicestate.c: Changing state for Zap/pseudo - state 2 (In use) Dec 24 20:54:52 DEBUG[6246] app_queue.c: Device 'Zap/pseudo' changed to state '2' (In use) Dec 24 20:54:55 DEBUG[6242] channel.c: Scheduling timer at 0 sample intervals Dec 24 20:54:55 DEBUG[6242] channel.c: Scheduling timer at 0 sample intervals Dec 24 20:54:55 DEBUG[6242] channel.c: Set channel SIP/ext2001-5f1d to write format ulaw Dec 24 20:54:55 DEBUG[6242] channel.c: Set channel SIP/ext2001-5f1d to write format slin Dec 24 20:54:55 DEBUG[6242] channel.c: Set channel SIP/ext2001-5f1d to read format slin Dec 24 20:54:55 DEBUG[6242] app_meetme.c: Placed channel SIP/ext2001-5f1d in ZAP conf 1023 Dec 24 20:54:55 DEBUG[6242] channel.c: Set channel SIP/ext2001-5f1d to write format slin Dec 24 20:54:55 VERBOSE[6242] logger.c: -- Started music on hold, class 'default', on SIP/ext2001-5f1d Dec 24 20:54:55 DEBUG[6242] channel.c: Scheduling timer at 160 sample intervals Dec 24 20:54:55 DEBUG[6242] channel.c: Generator got voice, switching to phase locked mode Dec 24 20:54:55 DEBUG[6242] channel.c: Scheduling timer at 0 sample intervals Dec 24 20:54:55 DEBUG[6242] channel.c: Set channel SIP/ext2001-5f1d to write format slin Dec 24 20:54:55 DEBUG[6242] channel.c: Set channel SIP/ext2001-5f1d to write format slin Dec 24 20:54:55 DEBUG[6242] res_musiconhold.c: SIP/ext2001-5f1d Opened file 4 '/var/lib/asterisk/mohmp3/01_Crossroads' Dec 24 20:54:56 VERBOSE[6232] logger.c: <-- SIP read from 192.168.254.1:1025: Dec 24 20:54:56 DEBUG[6232] chan_sip.c: Header 0: (0) Dec 24 20:54:56 VERBOSE[6232] logger.c: --- (0 headers 0 lines)Dec 24 20:54:56 VERBOSE[6232] logger.c: --- (0 headers 0 lines) Nat keepalive Dec 24 20:54:56 VERBOSE[6232] logger.c: --- (0 headers 0 lines) Nat keepalive --- Dec 24 20:55:00 DEBUG[6232] chan_sip.c: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP) Dec 24 20:55:00 DEBUG[6232] acl.c: ##### Testing 192.168.254.251 with 192.168.254.0 Dec 24 20:55:00 DEBUG[6232] chan_sip.c: Header 0: OPTIONS sip:ext2001@192.168.254.251:5061 SIP/2.0 (48) Dec 24 20:55:00 DEBUG[6232] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.254.250:5060;branch=z9hG4bK2cfcb95c;rport (66) Dec 24 20:55:00 DEBUG[6232] chan_sip.c: Header 2: From: "Asterisk" ;tag=as0741381d (62) Dec 24 20:55:00 DEBUG[6232] chan_sip.c: Header 3: To: (38) Dec 24 20:55:00 DEBUG[6232] chan_sip.c: Header 4: Contact: (39) Dec 24 20:55:00 DEBUG[6232] chan_sip.c: Header 5: Call-ID: 6ec04eca6877729005a6ba3d38b4beed@192.168.254.250 (57) Dec 24 20:55:00 DEBUG[6232] chan_sip.c: Header 6: CSeq: 102 OPTIONS (17) Dec 24 20:55:00 DEBUG[6232] chan_sip.c: Header 7: User-Agent: Asterisk PBX (24) Dec 24 20:55:00 DEBUG[6232] chan_sip.c: Header 8: Max-Forwards: 70 (16) Dec 24 20:55:00 DEBUG[6232] chan_sip.c: Header 9: Date: Sun, 25 Dec 2005 03:55:00 GMT (35) Dec 24 20:55:00 DEBUG[6232] chan_sip.c: Header 10: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY (66) Dec 24 20:55:00 DEBUG[6232] chan_sip.c: Header 11: Content-Length: 0 (17) Dec 24 20:55:00 DEBUG[6232] chan_sip.c: Header 12: (0) Dec 24 20:55:00 VERBOSE[6232] logger.c: 12 headers, 0 lines Dec 24 20:55:00 VERBOSE[6232] logger.c: Reliably Transmitting (no NAT) to 192.168.254.251:5061: OPTIONS sip:ext2001@192.168.254.251:5061 SIP/2.0 Via: SIP/2.0/UDP 192.168.254.250:5060;branch=z9hG4bK2cfcb95c;rport From: "Asterisk" ;tag=as0741381d To: Contact: Call-ID: 6ec04eca6877729005a6ba3d38b4beed@192.168.254.250 CSeq: 102 OPTIONS User-Agent: Asterisk PBX Max-Forwards: 70 Date: Sun, 25 Dec 2005 03:55:00 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Content-Length: 0 --- Dec 24 20:55:00 DEBUG[6232] chan_sip.c: *** SIP TIMER: Initalizing retransmit timer on packet: Id #66 Dec 24 20:55:00 DEBUG[6232] chan_sip.c: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP) Dec 24 20:55:00 DEBUG[6232] acl.c: ##### Testing 192.168.254.251 with 192.168.254.0 Dec 24 20:55:00 DEBUG[6232] chan_sip.c: Header 0: OPTIONS sip:ext2002@192.168.254.251:5062 SIP/2.0 (48) Dec 24 20:55:00 DEBUG[6232] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.254.250:5060;branch=z9hG4bK3ff9a914;rport (66) Dec 24 20:55:00 DEBUG[6232] chan_sip.c: Header 2: From: "Asterisk" ;tag=as1c575c3a (62) Dec 24 20:55:00 DEBUG[6232] chan_sip.c: Header 3: To: (38) Dec 24 20:55:00 DEBUG[6232] chan_sip.c: Header 4: Contact: (39) Dec 24 20:55:00 DEBUG[6232] chan_sip.c: Header 5: Call-ID: 16426a9d3c189a0a4c66244d65558747@192.168.254.250 (57) Dec 24 20:55:00 DEBUG[6232] chan_sip.c: Header 6: CSeq: 102 OPTIONS (17) Dec 24 20:55:00 DEBUG[6232] chan_sip.c: Header 7: User-Agent: Asterisk PBX (24) Dec 24 20:55:00 DEBUG[6232] chan_sip.c: Header 8: Max-Forwards: 70 (16) Dec 24 20:55:00 DEBUG[6232] chan_sip.c: Header 9: Date: Sun, 25 Dec 2005 03:55:00 GMT (35) Dec 24 20:55:00 DEBUG[6232] chan_sip.c: Header 10: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY (66) Dec 24 20:55:00 DEBUG[6232] chan_sip.c: Header 11: Content-Length: 0 (17) Dec 24 20:55:00 DEBUG[6232] chan_sip.c: Header 12: (0) Dec 24 20:55:00 VERBOSE[6232] logger.c: 12 headers, 0 lines Dec 24 20:55:00 VERBOSE[6232] logger.c: Reliably Transmitting (no NAT) to 192.168.254.251:5062: OPTIONS sip:ext2002@192.168.254.251:5062 SIP/2.0 Via: SIP/2.0/UDP 192.168.254.250:5060;branch=z9hG4bK3ff9a914;rport From: "Asterisk" ;tag=as1c575c3a To: Contact: Call-ID: 16426a9d3c189a0a4c66244d65558747@192.168.254.250 CSeq: 102 OPTIONS User-Agent: Asterisk PBX Max-Forwards: 70 Date: Sun, 25 Dec 2005 03:55:00 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Content-Length: 0 --- Dec 24 20:55:00 DEBUG[6232] chan_sip.c: *** SIP TIMER: Initalizing retransmit timer on packet: Id #68 Dec 24 20:55:00 VERBOSE[6232] logger.c: <-- SIP read from 192.168.254.251:5061: SIP/2.0 200 OK To: ;tag=f81c8eb560b46b85i0 From: "Asterisk" ;tag=as0741381d Call-ID: 6ec04eca6877729005a6ba3d38b4beed@192.168.254.250 CSeq: 102 OPTIONS Via: SIP/2.0/UDP 192.168.254.250:5060;branch=z9hG4bK2cfcb95c;rport=5060 Server: Linksys/PAP2-3.1.6(LS) Content-Length: 0 Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER Supported: x-sipura Dec 24 20:55:00 DEBUG[6232] chan_sip.c: Header 0: SIP/2.0 200 OK (14) Dec 24 20:55:00 DEBUG[6232] chan_sip.c: Header 1: To: ;tag=f81c8eb560b46b85i0 (61) Dec 24 20:55:00 DEBUG[6232] chan_sip.c: Header 2: From: "Asterisk" ;tag=as0741381d (62) Dec 24 20:55:00 DEBUG[6232] chan_sip.c: Header 3: Call-ID: 6ec04eca6877729005a6ba3d38b4beed@192.168.254.250 (57) Dec 24 20:55:00 DEBUG[6232] chan_sip.c: Header 4: CSeq: 102 OPTIONS (17) Dec 24 20:55:00 DEBUG[6232] chan_sip.c: Header 5: Via: SIP/2.0/UDP 192.168.254.250:5060;branch=z9hG4bK2cfcb95c;rport=5060 (71) Dec 24 20:55:00 DEBUG[6232] chan_sip.c: Header 6: Server: Linksys/PAP2-3.1.6(LS) (30) Dec 24 20:55:00 DEBUG[6232] chan_sip.c: Header 7: Content-Length: 0 (17) Dec 24 20:55:00 DEBUG[6232] chan_sip.c: Header 8: Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER (61) Dec 24 20:55:00 DEBUG[6232] chan_sip.c: Header 9: Supported: x-sipura (19) Dec 24 20:55:00 DEBUG[6232] chan_sip.c: Header 10: (0) Dec 24 20:55:00 VERBOSE[6232] logger.c: --- (10 headers 0 lines)Dec 24 20:55:00 VERBOSE[6232] logger.c: --- (10 headers 0 lines)--- Dec 24 20:55:00 DEBUG[6232] chan_sip.c: = No match Their Call ID: 16426a9d3c189a0a4c66244d65558747@192.168.254.250 Their Tag Our tag: as1c575c3a Dec 24 20:55:00 DEBUG[6232] chan_sip.c: = Found Their Call ID: 6ec04eca6877729005a6ba3d38b4beed@192.168.254.250 Their Tag Our tag: as0741381d Dec 24 20:55:00 DEBUG[6232] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #66 Dec 24 20:55:00 DEBUG[6232] chan_sip.c: Stopping retransmission on '6ec04eca6877729005a6ba3d38b4beed@192.168.254.250' of Request 102: Match Found Dec 24 20:55:00 VERBOSE[6232] logger.c: Destroying call '6ec04eca6877729005a6ba3d38b4beed@192.168.254.250' Dec 24 20:55:00 VERBOSE[6232] logger.c: <-- SIP read from 192.168.254.251:5062: SIP/2.0 200 OK To: ;tag=f9bf535cb983705i1 From: "Asterisk" ;tag=as1c575c3a Call-ID: 16426a9d3c189a0a4c66244d65558747@192.168.254.250 CSeq: 102 OPTIONS Via: SIP/2.0/UDP 192.168.254.250:5060;branch=z9hG4bK3ff9a914;rport=5060 Server: Linksys/PAP2-3.1.6(LS) Content-Length: 0 Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER Supported: x-sipura Dec 24 20:55:00 DEBUG[6232] chan_sip.c: Header 0: SIP/2.0 200 OK (14) Dec 24 20:55:00 DEBUG[6232] chan_sip.c: Header 1: To: ;tag=f9bf535cb983705i1 (60) Dec 24 20:55:00 DEBUG[6232] chan_sip.c: Header 2: From: "Asterisk" ;tag=as1c575c3a (62) Dec 24 20:55:00 DEBUG[6232] chan_sip.c: Header 3: Call-ID: 16426a9d3c189a0a4c66244d65558747@192.168.254.250 (57) Dec 24 20:55:00 DEBUG[6232] chan_sip.c: Header 4: CSeq: 102 OPTIONS (17) Dec 24 20:55:00 DEBUG[6232] chan_sip.c: Header 5: Via: SIP/2.0/UDP 192.168.254.250:5060;branch=z9hG4bK3ff9a914;rport=5060 (71) Dec 24 20:55:00 DEBUG[6232] chan_sip.c: Header 6: Server: Linksys/PAP2-3.1.6(LS) (30) Dec 24 20:55:00 DEBUG[6232] chan_sip.c: Header 7: Content-Length: 0 (17) Dec 24 20:55:00 DEBUG[6232] chan_sip.c: Header 8: Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER (61) Dec 24 20:55:00 DEBUG[6232] chan_sip.c: Header 9: Supported: x-sipura (19) Dec 24 20:55:00 DEBUG[6232] chan_sip.c: Header 10: (0) Dec 24 20:55:00 VERBOSE[6232] logger.c: --- (10 headers 0 lines)Dec 24 20:55:00 VERBOSE[6232] logger.c: --- (10 headers 0 lines)--- Dec 24 20:55:00 DEBUG[6232] chan_sip.c: = Found Their Call ID: 16426a9d3c189a0a4c66244d65558747@192.168.254.250 Their Tag Our tag: as1c575c3a Dec 24 20:55:00 DEBUG[6232] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #68 Dec 24 20:55:00 DEBUG[6232] chan_sip.c: Stopping retransmission on '16426a9d3c189a0a4c66244d65558747@192.168.254.250' of Request 102: Match Found Dec 24 20:55:00 VERBOSE[6232] logger.c: Destroying call '16426a9d3c189a0a4c66244d65558747@192.168.254.250' Dec 24 20:55:00 DEBUG[6232] chan_sip.c: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP) Dec 24 20:55:00 DEBUG[6232] acl.c: ##### Testing 192.168.254.252 with 192.168.254.0 Dec 24 20:55:00 DEBUG[6232] chan_sip.c: Header 0: OPTIONS sip:ext2006@192.168.254.252:5060 SIP/2.0 (48) Dec 24 20:55:00 DEBUG[6232] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.254.250:5060;branch=z9hG4bK2d32e3e5;rport (66) Dec 24 20:55:00 DEBUG[6232] chan_sip.c: Header 2: From: "Asterisk" ;tag=as315bcc79 (62) Dec 24 20:55:00 DEBUG[6232] chan_sip.c: Header 3: To: (38) Dec 24 20:55:00 DEBUG[6232] chan_sip.c: Header 4: Contact: (39) Dec 24 20:55:00 DEBUG[6232] chan_sip.c: Header 5: Call-ID: 479f1d6f61bbac972b0610fb0aacc2c5@192.168.254.250 (57) Dec 24 20:55:00 DEBUG[6232] chan_sip.c: Header 6: CSeq: 102 OPTIONS (17) Dec 24 20:55:00 DEBUG[6232] chan_sip.c: Header 7: User-Agent: Asterisk PBX (24) Dec 24 20:55:00 DEBUG[6232] chan_sip.c: Header 8: Max-Forwards: 70 (16) Dec 24 20:55:00 DEBUG[6232] chan_sip.c: Header 9: Date: Sun, 25 Dec 2005 03:55:00 GMT (35) Dec 24 20:55:00 DEBUG[6232] chan_sip.c: Header 10: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY (66) Dec 24 20:55:00 DEBUG[6232] chan_sip.c: Header 11: Content-Length: 0 (17) Dec 24 20:55:00 DEBUG[6232] chan_sip.c: Header 12: (0) Dec 24 20:55:00 VERBOSE[6232] logger.c: 12 headers, 0 lines Dec 24 20:55:00 VERBOSE[6232] logger.c: Reliably Transmitting (no NAT) to 192.168.254.252:5060: OPTIONS sip:ext2006@192.168.254.252:5060 SIP/2.0 Via: SIP/2.0/UDP 192.168.254.250:5060;branch=z9hG4bK2d32e3e5;rport From: "Asterisk" ;tag=as315bcc79 To: Contact: Call-ID: 479f1d6f61bbac972b0610fb0aacc2c5@192.168.254.250 CSeq: 102 OPTIONS User-Agent: Asterisk PBX Max-Forwards: 70 Date: Sun, 25 Dec 2005 03:55:00 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Content-Length: 0 --- Dec 24 20:55:00 DEBUG[6232] chan_sip.c: *** SIP TIMER: Initalizing retransmit timer on packet: Id #72 Dec 24 20:55:00 VERBOSE[6232] logger.c: <-- SIP read from 192.168.254.252:5060: SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.254.250:5060;branch=z9hG4bK2d32e3e5;rport From: "Asterisk" ;tag=as315bcc79 To: ;tag=BD8A72F6-3BFC52E7 CSeq: 102 OPTIONS Call-ID: 479f1d6f61bbac972b0610fb0aacc2c5@192.168.254.250 Contact: Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER User-Agent: PolycomSoundPointIP-SPIP_500-UA/1.6.2.0041 Content-Length: 0 Dec 24 20:55:00 DEBUG[6232] chan_sip.c: Header 0: SIP/2.0 200 OK (14) Dec 24 20:55:00 DEBUG[6232] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.254.250:5060;branch=z9hG4bK2d32e3e5;rport (66) Dec 24 20:55:00 DEBUG[6232] chan_sip.c: Header 2: From: "Asterisk" ;tag=as315bcc79 (62) Dec 24 20:55:00 DEBUG[6232] chan_sip.c: Header 3: To: ;tag=BD8A72F6-3BFC52E7 (60) Dec 24 20:55:00 DEBUG[6232] chan_sip.c: Header 4: CSeq: 102 OPTIONS (17) Dec 24 20:55:00 DEBUG[6232] chan_sip.c: Header 5: Call-ID: 479f1d6f61bbac972b0610fb0aacc2c5@192.168.254.250 (57) Dec 24 20:55:00 DEBUG[6232] chan_sip.c: Header 6: Contact: (43) Dec 24 20:55:00 DEBUG[6232] chan_sip.c: Header 7: Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER (96) Dec 24 20:55:00 DEBUG[6232] chan_sip.c: Header 8: User-Agent: PolycomSoundPointIP-SPIP_500-UA/1.6.2.0041 (54) Dec 24 20:55:00 DEBUG[6232] chan_sip.c: Header 9: Content-Length: 0 (17) Dec 24 20:55:00 DEBUG[6232] chan_sip.c: Header 10: (0) Dec 24 20:55:00 VERBOSE[6232] logger.c: --- (10 headers 0 lines)Dec 24 20:55:00 VERBOSE[6232] logger.c: --- (10 headers 0 lines)--- Dec 24 20:55:00 DEBUG[6232] chan_sip.c: = Found Their Call ID: 479f1d6f61bbac972b0610fb0aacc2c5@192.168.254.250 Their Tag Our tag: as315bcc79 Dec 24 20:55:00 DEBUG[6232] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #72 Dec 24 20:55:00 DEBUG[6232] chan_sip.c: Stopping retransmission on '479f1d6f61bbac972b0610fb0aacc2c5@192.168.254.250' of Request 102: Match Found Dec 24 20:55:00 VERBOSE[6232] logger.c: Destroying call '479f1d6f61bbac972b0610fb0aacc2c5@192.168.254.250' Dec 24 20:55:01 DEBUG[6245] chan_zap.c: DTMF digit: 0 on Zap/pseudo-816494611 ############################################################### Crash and restart ############################################################### Dec 24 20:55:05 VERBOSE[6290] logger.c: Asterisk Event Logger Started /var/log/asterisk/event_log Dec 24 20:55:05 VERBOSE[6290] logger.c: == Parsing '/etc/asterisk/dnsmgr.conf': Dec 24 20:55:05 VERBOSE[6290] logger.c: == Parsing '/etc/asterisk/dnsmgr.conf': Found