Dec 24 20:20:33 DEBUG[29856] chan_sip.c: Header 0: REGISTER sip:cojensen.net SIP/2.0 (33) Dec 24 20:20:33 DEBUG[29856] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.254.60:8917;branch=z9hG4bK-d87543-bf21f708f045ca18-1--d87543-;rport (92) Dec 24 20:20:33 DEBUG[29856] chan_sip.c: Header 2: Max-Forwards: 70 (16) Dec 24 20:20:33 DEBUG[29856] chan_sip.c: Header 3: Contact: (42) Dec 24 20:20:33 DEBUG[29856] chan_sip.c: Header 4: To: "Kai-Uwe Jensen" (46) Dec 24 20:20:33 DEBUG[29856] chan_sip.c: Header 5: From: "Kai-Uwe Jensen";tag=3a750662 (61) Dec 24 20:20:33 DEBUG[29856] chan_sip.c: Header 6: Call-ID: b922c0413c215c61@TUFETUFOMi5hbWVyaWNhcy5ocHFjb3JwLm5ldA.. (66) Dec 24 20:20:33 DEBUG[29856] chan_sip.c: Header 7: CSeq: 1 REGISTER (16) Dec 24 20:20:33 DEBUG[29856] chan_sip.c: Header 8: Expires: 3600 (13) Dec 24 20:20:33 DEBUG[29856] chan_sip.c: Header 9: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO (81) Dec 24 20:20:33 DEBUG[29856] chan_sip.c: Header 10: Supported: eventlist (20) Dec 24 20:20:33 DEBUG[29856] chan_sip.c: Header 11: User-Agent: eyeBeam release 3010n stamp 19039 (45) Dec 24 20:20:33 DEBUG[29856] chan_sip.c: Header 12: Content-Length: 0 (17) Dec 24 20:20:33 DEBUG[29856] chan_sip.c: Header 13: (0) Dec 24 20:20:33 DEBUG[29856] acl.c: ##### Testing 192.168.254.1 with 192.168.254.0 Dec 24 20:20:33 DEBUG[29856] chan_sip.c: Allocating new SIP dialog for b922c0413c215c61@TUFETUFOMi5hbWVyaWNhcy5ocHFjb3JwLm5ldA.. - REGISTER (No RTP) Dec 24 20:20:33 DEBUG[29856] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER Dec 24 20:20:34 DEBUG[29856] chan_sip.c: Header 0: REGISTER sip:cojensen.net SIP/2.0 (33) Dec 24 20:20:34 DEBUG[29856] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.254.60:8917;branch=z9hG4bK-d87543-1d5afb69bf163c27-1--d87543-;rport (92) Dec 24 20:20:34 DEBUG[29856] chan_sip.c: Header 2: Max-Forwards: 70 (16) Dec 24 20:20:34 DEBUG[29856] chan_sip.c: Header 3: Contact: (42) Dec 24 20:20:34 DEBUG[29856] chan_sip.c: Header 4: To: "Kai-Uwe Jensen" (46) Dec 24 20:20:34 DEBUG[29856] chan_sip.c: Header 5: From: "Kai-Uwe Jensen";tag=3a750662 (61) Dec 24 20:20:34 DEBUG[29856] chan_sip.c: Header 6: Call-ID: b922c0413c215c61@TUFETUFOMi5hbWVyaWNhcy5ocHFjb3JwLm5ldA.. (66) Dec 24 20:20:34 DEBUG[29856] chan_sip.c: Header 7: CSeq: 2 REGISTER (16) Dec 24 20:20:34 DEBUG[29856] chan_sip.c: Header 8: Expires: 3600 (13) Dec 24 20:20:34 DEBUG[29856] chan_sip.c: Header 9: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO (81) Dec 24 20:20:34 DEBUG[29856] chan_sip.c: Header 10: Supported: eventlist (20) Dec 24 20:20:34 DEBUG[29856] chan_sip.c: Header 11: User-Agent: eyeBeam release 3010n stamp 19039 (45) Dec 24 20:20:34 DEBUG[29856] chan_sip.c: Header 12: Authorization: Digest username="ext2003",realm="asterisk",nonce="387607bb",uri="sip:cojensen.net",response="c5e1e9b18a3d693f6473ee083f7d0b8c",algorithm=MD5 (155) Dec 24 20:20:34 DEBUG[29856] chan_sip.c: Header 13: Content-Length: 0 (17) Dec 24 20:20:34 DEBUG[29856] chan_sip.c: Header 14: (0) Dec 24 20:20:34 DEBUG[29856] chan_sip.c: = Found Their Call ID: b922c0413c215c61@TUFETUFOMi5hbWVyaWNhcy5ocHFjb3JwLm5ldA.. Their Tag 3a750662 Our tag: as5f4cd33b Dec 24 20:20:34 DEBUG[29856] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER Dec 24 20:20:34 DEBUG[29856] chan_sip.c: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP) Dec 24 20:20:34 DEBUG[29856] acl.c: ##### Testing 192.168.254.1 with 192.168.254.0 Dec 24 20:20:34 DEBUG[29856] chan_sip.c: Header 0: OPTIONS sip:ext2003@192.168.254.60:8917 SIP/2.0 (47) Dec 24 20:20:34 DEBUG[29856] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.254.250:5060;branch=z9hG4bK217e1aae;rport (66) Dec 24 20:20:34 DEBUG[29856] chan_sip.c: Header 2: From: "Asterisk" ;tag=as68318ee3 (62) Dec 24 20:20:34 DEBUG[29856] chan_sip.c: Header 3: To: (37) Dec 24 20:20:34 DEBUG[29856] chan_sip.c: Header 4: Contact: (39) Dec 24 20:20:34 DEBUG[29856] chan_sip.c: Header 5: Call-ID: 5eb5ce544bbd6a17761aca7c59669b23@192.168.254.250 (57) Dec 24 20:20:34 DEBUG[29856] chan_sip.c: Header 6: CSeq: 102 OPTIONS (17) Dec 24 20:20:34 DEBUG[29856] chan_sip.c: Header 7: User-Agent: Asterisk PBX (24) Dec 24 20:20:34 DEBUG[29856] chan_sip.c: Header 8: Max-Forwards: 70 (16) Dec 24 20:20:34 DEBUG[29856] chan_sip.c: Header 9: Date: Sun, 25 Dec 2005 03:20:34 GMT (35) Dec 24 20:20:34 DEBUG[29856] chan_sip.c: Header 10: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY (66) Dec 24 20:20:34 DEBUG[29856] chan_sip.c: Header 11: Content-Length: 0 (17) Dec 24 20:20:34 DEBUG[29856] chan_sip.c: Header 12: (0) Dec 24 20:20:34 DEBUG[29856] chan_sip.c: *** SIP TIMER: Initalizing retransmit timer on packet: Id #45 Dec 24 20:20:34 DEBUG[29853] chan_sip.c: Checking device state for peer ext2003 Dec 24 20:20:34 DEBUG[29853] devicestate.c: Changing state for SIP/ext2003 - state 1 (Not in use) Dec 24 20:20:34 DEBUG[29853] chan_sip.c: Checking device state for peer ext2003 Dec 24 20:20:34 DEBUG[29853] chan_sip.c: Checking device state for peer ext2003 Dec 24 20:20:34 DEBUG[29865] app_queue.c: Device 'SIP/ext2003' changed to state '1' (Not in use) Dec 24 20:20:34 DEBUG[29856] chan_sip.c: Header 0: SIP/2.0 200 OK (14) Dec 24 20:20:34 DEBUG[29856] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.254.250:5060;branch=z9hG4bK217e1aae;rport=5060;received=67.174.106.30 (94) Dec 24 20:20:34 DEBUG[29856] chan_sip.c: Header 2: Contact: (34) Dec 24 20:20:34 DEBUG[29856] chan_sip.c: Header 3: To: ;tag=e648eb79 (50) Dec 24 20:20:34 DEBUG[29856] chan_sip.c: Header 4: From: "Asterisk";tag=as68318ee3 (61) Dec 24 20:20:34 DEBUG[29856] chan_sip.c: Header 5: Call-ID: 5eb5ce544bbd6a17761aca7c59669b23@192.168.254.250 (57) Dec 24 20:20:34 DEBUG[29856] chan_sip.c: Header 6: CSeq: 102 OPTIONS (17) Dec 24 20:20:34 DEBUG[29856] chan_sip.c: Header 7: Accept: application/sdp (23) Dec 24 20:20:34 DEBUG[29856] chan_sip.c: Header 8: Accept-Language: en (19) Dec 24 20:20:34 DEBUG[29856] chan_sip.c: Header 9: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO (81) Dec 24 20:20:34 DEBUG[29856] chan_sip.c: Header 10: Supported: eventlist (20) Dec 24 20:20:34 DEBUG[29856] chan_sip.c: Header 11: User-Agent: eyeBeam release 3010n stamp 19039 (45) Dec 24 20:20:34 DEBUG[29856] chan_sip.c: Header 12: Content-Length: 0 (17) Dec 24 20:20:34 DEBUG[29856] chan_sip.c: Header 13: (0) Dec 24 20:20:34 DEBUG[29856] chan_sip.c: = Found Their Call ID: 5eb5ce544bbd6a17761aca7c59669b23@192.168.254.250 Their Tag Our tag: as68318ee3 Dec 24 20:20:34 DEBUG[29856] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #45 Dec 24 20:20:34 DEBUG[29856] chan_sip.c: Stopping retransmission on '5eb5ce544bbd6a17761aca7c59669b23@192.168.254.250' of Request 102: Match Found Dec 24 20:20:34 DEBUG[29853] chan_sip.c: Checking device state for peer ext2003 Dec 24 20:20:34 DEBUG[29853] devicestate.c: Changing state for SIP/ext2003 - state 1 (Not in use) Dec 24 20:20:34 DEBUG[29853] chan_sip.c: Checking device state for peer ext2003 Dec 24 20:20:34 DEBUG[29853] chan_sip.c: Checking device state for peer ext2003 Dec 24 20:20:34 DEBUG[29866] app_queue.c: Device 'SIP/ext2003' changed to state '1' (Not in use) Dec 24 20:20:39 DEBUG[29858] chan_iax2.c: Allocate call number Dec 24 20:20:39 DEBUG[29858] chan_iax2.c: Registration created on call 2 Dec 24 20:20:42 DEBUG[29856] chan_sip.c: Header 0: (0) Dec 24 20:20:44 DEBUG[29856] chan_sip.c: Allocating new SIP dialog for (No Call-ID) - NOTIFY (No RTP) Dec 24 20:20:44 DEBUG[29856] acl.c: ##### Testing 192.168.254.1 with 192.168.254.0 Dec 24 20:20:44 DEBUG[29856] chan_sip.c: Header 0: NOTIFY sip:ext2003@192.168.254.60:8917 SIP/2.0 (46) Dec 24 20:20:44 DEBUG[29856] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.254.250:5060;branch=z9hG4bK7361dadf;rport (66) Dec 24 20:20:44 DEBUG[29856] chan_sip.c: Header 2: From: "Asterisk" ;tag=as192d20ff (62) Dec 24 20:20:44 DEBUG[29856] chan_sip.c: Header 3: To: (37) Dec 24 20:20:44 DEBUG[29856] chan_sip.c: Header 4: Contact: (39) Dec 24 20:20:44 DEBUG[29856] chan_sip.c: Header 5: Call-ID: 42d0f878778cae49749d1a0540f7c6a2@192.168.254.250 (57) Dec 24 20:20:44 DEBUG[29856] chan_sip.c: Header 6: CSeq: 102 NOTIFY (16) Dec 24 20:20:44 DEBUG[29856] chan_sip.c: Header 7: User-Agent: Asterisk PBX (24) Dec 24 20:20:44 DEBUG[29856] chan_sip.c: Header 8: Max-Forwards: 70 (16) Dec 24 20:20:44 DEBUG[29856] chan_sip.c: Header 9: Event: message-summary (22) Dec 24 20:20:44 DEBUG[29856] chan_sip.c: Header 10: Content-Type: application/simple-message-summary (48) Dec 24 20:20:44 DEBUG[29856] chan_sip.c: Header 11: Content-Length: 80 (18) Dec 24 20:20:44 DEBUG[29856] chan_sip.c: Header 12: (0) Dec 24 20:20:44 DEBUG[29856] chan_sip.c: Line: Messages-Waiting: no (20) Dec 24 20:20:44 DEBUG[29856] chan_sip.c: Line: Message-Account: sip:asterisk@ (30) Dec 24 20:20:44 DEBUG[29856] chan_sip.c: Line: Voice-Message: 0/0 (0/0) (24) Dec 24 20:20:44 DEBUG[29856] chan_sip.c: *** SIP TIMER: Initalizing retransmit timer on packet: Id #49 Dec 24 20:20:44 DEBUG[29856] chan_sip.c: SIP TIMER: Rescheduling retransmission #49 (1) NOTIFY - 4 Dec 24 20:20:44 DEBUG[29856] chan_sip.c: ** SIP timers: Rescheduling retransmission 2 to 124 ms (t1 62 ms (Retrans id #49)) Dec 24 20:20:44 DEBUG[29856] chan_sip.c: Header 0: SIP/2.0 200 OK (14) Dec 24 20:20:44 DEBUG[29856] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.254.250:5060;branch=z9hG4bK7361dadf;rport=5060;received=67.174.106.30 (94) Dec 24 20:20:44 DEBUG[29856] chan_sip.c: Header 2: Contact: (34) Dec 24 20:20:44 DEBUG[29856] chan_sip.c: Header 3: To: ;tag=b9087b31 (50) Dec 24 20:20:44 DEBUG[29856] chan_sip.c: Header 4: From: "Asterisk";tag=as192d20ff (61) Dec 24 20:20:44 DEBUG[29856] chan_sip.c: Header 5: Call-ID: 42d0f878778cae49749d1a0540f7c6a2@192.168.254.250 (57) Dec 24 20:20:44 DEBUG[29856] chan_sip.c: Header 6: CSeq: 102 NOTIFY (16) Dec 24 20:20:44 DEBUG[29856] chan_sip.c: Header 7: User-Agent: eyeBeam release 3010n stamp 19039 (45) Dec 24 20:20:44 DEBUG[29856] chan_sip.c: Header 8: Content-Length: 0 (17) Dec 24 20:20:44 DEBUG[29856] chan_sip.c: Header 9: (0) Dec 24 20:20:44 DEBUG[29856] chan_sip.c: = Found Their Call ID: 42d0f878778cae49749d1a0540f7c6a2@192.168.254.250 Their Tag Our tag: as192d20ff Dec 24 20:20:44 DEBUG[29856] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #49 Dec 24 20:20:44 DEBUG[29856] chan_sip.c: Stopping retransmission on '42d0f878778cae49749d1a0540f7c6a2@192.168.254.250' of Request 102: Match Found Dec 24 20:20:47 DEBUG[29856] chan_sip.c: Header 0: INVITE sip:2600@192.168.254.250 SIP/2.0 (39) Dec 24 20:20:47 DEBUG[29856] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.254.251:5061;branch=z9hG4bK-3252387d (61) Dec 24 20:20:47 DEBUG[29856] chan_sip.c: Header 2: From: Kai-Uwe Jensen ;tag=ba73a2acd02f5a5o0 (72) Dec 24 20:20:47 DEBUG[29856] chan_sip.c: Header 3: To: (30) Dec 24 20:20:47 DEBUG[29856] chan_sip.c: Header 4: Call-ID: e8556078-ec817249@192.168.254.251 (42) Dec 24 20:20:47 DEBUG[29856] chan_sip.c: Header 5: CSeq: 101 INVITE (16) Dec 24 20:20:47 DEBUG[29856] chan_sip.c: Header 6: Max-Forwards: 70 (16) Dec 24 20:20:47 DEBUG[29856] chan_sip.c: Header 7: Contact: Kai-Uwe Jensen (58) Dec 24 20:20:47 DEBUG[29856] chan_sip.c: Header 8: Expires: 240 (12) Dec 24 20:20:47 DEBUG[29856] chan_sip.c: Header 9: User-Agent: Linksys/PAP2-3.1.6(LS) (34) Dec 24 20:20:47 DEBUG[29856] chan_sip.c: Header 10: Content-Length: 409 (19) Dec 24 20:20:47 DEBUG[29856] chan_sip.c: Header 11: Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER (61) Dec 24 20:20:47 DEBUG[29856] chan_sip.c: Header 12: Supported: x-sipura (19) Dec 24 20:20:47 DEBUG[29856] chan_sip.c: Header 13: Content-Type: application/sdp (29) Dec 24 20:20:47 DEBUG[29856] chan_sip.c: Header 14: (0) Dec 24 20:20:47 DEBUG[29856] chan_sip.c: Line: v=0 (3) Dec 24 20:20:47 DEBUG[29856] chan_sip.c: Line: o=- 467854818 467854818 IN IP4 192.168.254.251 (46) Dec 24 20:20:47 DEBUG[29856] chan_sip.c: Line: s=- (3) Dec 24 20:20:47 DEBUG[29856] chan_sip.c: Line: c=IN IP4 192.168.254.251 (24) Dec 24 20:20:47 DEBUG[29856] chan_sip.c: Line: t=0 0 (5) Dec 24 20:20:47 DEBUG[29856] chan_sip.c: Line: m=audio 9082 RTP/AVP 0 2 8 18 96 97 98 100 101 (46) Dec 24 20:20:47 DEBUG[29856] chan_sip.c: Line: a=rtpmap:0 PCMU/8000 (20) Dec 24 20:20:47 DEBUG[29856] chan_sip.c: Line: a=rtpmap:2 G726-32/8000 (23) Dec 24 20:20:47 DEBUG[29856] chan_sip.c: Line: a=rtpmap:8 PCMA/8000 (20) Dec 24 20:20:47 DEBUG[29856] chan_sip.c: Line: a=rtpmap:18 G729a/8000 (22) Dec 24 20:20:47 DEBUG[29856] chan_sip.c: Line: a=rtpmap:96 G726-40/8000 (24) Dec 24 20:20:47 DEBUG[29856] chan_sip.c: Line: a=rtpmap:97 G726-24/8000 (24) Dec 24 20:20:47 DEBUG[29856] chan_sip.c: Line: a=rtpmap:98 G726-16/8000 (24) Dec 24 20:20:47 DEBUG[29856] chan_sip.c: Line: a=rtpmap:100 NSE/8000 (21) Dec 24 20:20:47 DEBUG[29856] chan_sip.c: Line: a=rtpmap:101 telephone-event/8000 (33) Dec 24 20:20:47 DEBUG[29856] chan_sip.c: Line: a=fmtp:101 0-15 (15) Dec 24 20:20:47 DEBUG[29856] chan_sip.c: Line: a=ptime:20 (10) Dec 24 20:20:47 DEBUG[29856] chan_sip.c: Line: a=sendrecv (10) Dec 24 20:20:47 DEBUG[29856] chan_sip.c: = No match Their Call ID: b922c0413c215c61@TUFETUFOMi5hbWVyaWNhcy5ocHFjb3JwLm5ldA.. Their Tag 3a750662 Our tag: as5f4cd33b Dec 24 20:20:47 DEBUG[29856] acl.c: ##### Testing 192.168.254.251 with 192.168.254.0 Dec 24 20:20:47 DEBUG[29856] chan_sip.c: Allocating new SIP dialog for e8556078-ec817249@192.168.254.251 - INVITE (With RTP) Dec 24 20:20:47 DEBUG[29856] chan_sip.c: **** Received INVITE (5) - Command in SIP INVITE Dec 24 20:20:47 DEBUG[29856] chan_sip.c: Begin: parsing SIP "Supported: x-sipura" Dec 24 20:20:47 DEBUG[29856] chan_sip.c: Found SIP option: -x-sipura- Dec 24 20:20:47 DEBUG[29856] chan_sip.c: Found no match for SIP option: x-sipura (Please file bug report!) Dec 24 20:20:47 DEBUG[29856] chan_sip.c: * SIP extension value: 0 for call e8556078-ec817249@192.168.254.251 Dec 24 20:20:47 DEBUG[29856] chan_sip.c: Setting NAT on RTP to 0 Dec 24 20:20:47 DEBUG[29856] chan_sip.c: Setting NAT on VRTP to 0 Dec 24 20:20:47 DEBUG[29856] chan_sip.c: *** SIP TIMER: Initalizing retransmit timer on packet: Id #51 Dec 24 20:20:47 DEBUG[29856] chan_sip.c: Header 0: ACK sip:2600@192.168.254.250 SIP/2.0 (36) Dec 24 20:20:47 DEBUG[29856] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.254.251:5061;branch=z9hG4bK-3252387d (61) Dec 24 20:20:47 DEBUG[29856] chan_sip.c: Header 2: From: Kai-Uwe Jensen ;tag=ba73a2acd02f5a5o0 (72) Dec 24 20:20:47 DEBUG[29856] chan_sip.c: Header 3: To: ;tag=as7d3c5fdd (45) Dec 24 20:20:47 DEBUG[29856] chan_sip.c: Header 4: Call-ID: e8556078-ec817249@192.168.254.251 (42) Dec 24 20:20:47 DEBUG[29856] chan_sip.c: Header 5: CSeq: 101 ACK (13) Dec 24 20:20:47 DEBUG[29856] chan_sip.c: Header 6: Max-Forwards: 70 (16) Dec 24 20:20:47 DEBUG[29856] chan_sip.c: Header 7: Contact: Kai-Uwe Jensen (58) Dec 24 20:20:47 DEBUG[29856] chan_sip.c: Header 8: User-Agent: Linksys/PAP2-3.1.6(LS) (34) Dec 24 20:20:47 DEBUG[29856] chan_sip.c: Header 9: Content-Length: 0 (17) Dec 24 20:20:47 DEBUG[29856] chan_sip.c: Header 10: (0) Dec 24 20:20:47 DEBUG[29856] chan_sip.c: = Found Their Call ID: e8556078-ec817249@192.168.254.251 Their Tag ba73a2acd02f5a5o0 Our tag: as7d3c5fdd Dec 24 20:20:47 DEBUG[29856] chan_sip.c: **** Received ACK (6) - Command in SIP ACK Dec 24 20:20:47 DEBUG[29856] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #51 Dec 24 20:20:47 DEBUG[29856] chan_sip.c: Stopping retransmission on 'e8556078-ec817249@192.168.254.251' of Response 101: Match Found Dec 24 20:20:47 DEBUG[29856] chan_sip.c: Header 0: INVITE sip:2600@192.168.254.250 SIP/2.0 (39) Dec 24 20:20:47 DEBUG[29856] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.254.251:5061;branch=z9hG4bK-39523de1 (61) Dec 24 20:20:47 DEBUG[29856] chan_sip.c: Header 2: From: Kai-Uwe Jensen ;tag=ba73a2acd02f5a5o0 (72) Dec 24 20:20:47 DEBUG[29856] chan_sip.c: Header 3: To: (30) Dec 24 20:20:47 DEBUG[29856] chan_sip.c: Header 4: Call-ID: e8556078-ec817249@192.168.254.251 (42) Dec 24 20:20:47 DEBUG[29856] chan_sip.c: Header 5: CSeq: 102 INVITE (16) Dec 24 20:20:47 DEBUG[29856] chan_sip.c: Header 6: Max-Forwards: 70 (16) Dec 24 20:20:47 DEBUG[29856] chan_sip.c: Header 7: Proxy-Authorization: Digest username="ext2001",realm="asterisk",nonce="21b57201",uri="sip:2600@192.168.254.250",algorithm=MD5,response="01d78599e7515cb4b9d322c73ac21f96" (169) Dec 24 20:20:47 DEBUG[29856] chan_sip.c: Header 8: Contact: Kai-Uwe Jensen (58) Dec 24 20:20:47 DEBUG[29856] chan_sip.c: Header 9: Expires: 240 (12) Dec 24 20:20:47 DEBUG[29856] chan_sip.c: Header 10: User-Agent: Linksys/PAP2-3.1.6(LS) (34) Dec 24 20:20:47 DEBUG[29856] chan_sip.c: Header 11: Content-Length: 409 (19) Dec 24 20:20:47 DEBUG[29856] chan_sip.c: Header 12: Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER (61) Dec 24 20:20:47 DEBUG[29856] chan_sip.c: Header 13: Supported: x-sipura (19) Dec 24 20:20:47 DEBUG[29856] chan_sip.c: Header 14: Content-Type: application/sdp (29) Dec 24 20:20:47 DEBUG[29856] chan_sip.c: Header 15: (0) Dec 24 20:20:47 DEBUG[29856] chan_sip.c: Line: v=0 (3) Dec 24 20:20:47 DEBUG[29856] chan_sip.c: Line: o=- 467854818 467854818 IN IP4 192.168.254.251 (46) Dec 24 20:20:47 DEBUG[29856] chan_sip.c: Line: s=- (3) Dec 24 20:20:47 DEBUG[29856] chan_sip.c: Line: c=IN IP4 192.168.254.251 (24) Dec 24 20:20:47 DEBUG[29856] chan_sip.c: Line: t=0 0 (5) Dec 24 20:20:47 DEBUG[29856] chan_sip.c: Line: m=audio 9082 RTP/AVP 0 2 8 18 96 97 98 100 101 (46) Dec 24 20:20:47 DEBUG[29856] chan_sip.c: Line: a=rtpmap:0 PCMU/8000 (20) Dec 24 20:20:47 DEBUG[29856] chan_sip.c: Line: a=rtpmap:2 G726-32/8000 (23) Dec 24 20:20:47 DEBUG[29856] chan_sip.c: Line: a=rtpmap:8 PCMA/8000 (20) Dec 24 20:20:47 DEBUG[29856] chan_sip.c: Line: a=rtpmap:18 G729a/8000 (22) Dec 24 20:20:47 DEBUG[29856] chan_sip.c: Line: a=rtpmap:96 G726-40/8000 (24) Dec 24 20:20:47 DEBUG[29856] chan_sip.c: Line: a=rtpmap:97 G726-24/8000 (24) Dec 24 20:20:47 DEBUG[29856] chan_sip.c: Line: a=rtpmap:98 G726-16/8000 (24) Dec 24 20:20:47 DEBUG[29856] chan_sip.c: Line: a=rtpmap:100 NSE/8000 (21) Dec 24 20:20:47 DEBUG[29856] chan_sip.c: Line: a=rtpmap:101 telephone-event/8000 (33) Dec 24 20:20:47 DEBUG[29856] chan_sip.c: Line: a=fmtp:101 0-15 (15) Dec 24 20:20:47 DEBUG[29856] chan_sip.c: Line: a=ptime:20 (10) Dec 24 20:20:47 DEBUG[29856] chan_sip.c: Line: a=sendrecv (10) Dec 24 20:20:47 DEBUG[29856] chan_sip.c: = Found Their Call ID: e8556078-ec817249@192.168.254.251 Their Tag ba73a2acd02f5a5o0 Our tag: as7d3c5fdd Dec 24 20:20:47 DEBUG[29856] chan_sip.c: **** Received INVITE (5) - Command in SIP INVITE Dec 24 20:20:47 DEBUG[29856] chan_sip.c: Begin: parsing SIP "Supported: x-sipura" Dec 24 20:20:47 DEBUG[29856] chan_sip.c: Found SIP option: -x-sipura- Dec 24 20:20:47 DEBUG[29856] chan_sip.c: Found no match for SIP option: x-sipura (Please file bug report!) Dec 24 20:20:47 DEBUG[29856] chan_sip.c: * SIP extension value: 0 for call e8556078-ec817249@192.168.254.251 Dec 24 20:20:47 DEBUG[29856] chan_sip.c: Setting NAT on RTP to 0 Dec 24 20:20:47 DEBUG[29856] chan_sip.c: Setting NAT on VRTP to 0 Dec 24 20:20:47 DEBUG[29856] chan_sip.c: Peer audio RTP is at port 192.168.254.251:9082 Dec 24 20:20:47 DEBUG[29856] chan_sip.c: Peer video RTP is at port 192.168.254.251:65535 Dec 24 20:20:47 DEBUG[29856] chan_sip.c: Checking SIP call limits for device ext2001 Dec 24 20:20:47 DEBUG[29856] chan_sip.c: Updating call counter for incoming call Dec 24 20:20:47 DEBUG[29856] chan_sip.c: build_route: Contact hop: Kai-Uwe Jensen Dec 24 20:20:47 DEBUG[29853] chan_sip.c: Checking device state for peer ext2001 Dec 24 20:20:47 DEBUG[29853] devicestate.c: Changing state for SIP/ext2001 - state 2 (In use) Dec 24 20:20:47 DEBUG[29853] chan_sip.c: Checking device state for peer ext2001 Dec 24 20:20:47 DEBUG[29853] chan_sip.c: Checking device state for peer ext2001 Dec 24 20:20:47 DEBUG[29867] pbx.c: Launching 'Goto' Dec 24 20:20:47 DEBUG[29867] pbx.c: Launching 'Answer' Dec 24 20:20:47 DEBUG[29853] chan_sip.c: Checking device state for peer ext2001 Dec 24 20:20:47 DEBUG[29853] channel.c: Avoiding initial deadlock for 'SIP/ext2001-f732' Dec 24 20:20:47 DEBUG[29867] chan_sip.c: sip_answer(SIP/ext2001-f732) Dec 24 20:20:47 DEBUG[29867] chan_sip.c: *** SIP TIMER: Initalizing retransmit timer on packet: Id #53 Dec 24 20:20:47 DEBUG[29867] pbx.c: Launching 'Wait' Dec 24 20:20:47 DEBUG[29853] devicestate.c: Changing state for SIP/ext2001 - state 2 (In use) Dec 24 20:20:47 DEBUG[29853] chan_sip.c: Checking device state for peer ext2001 Dec 24 20:20:47 DEBUG[29853] chan_sip.c: Checking device state for peer ext2001 Dec 24 20:20:47 DEBUG[29868] app_queue.c: Device 'SIP/ext2001' changed to state '2' (In use) Dec 24 20:20:47 DEBUG[29869] app_queue.c: Device 'SIP/ext2001' changed to state '2' (In use) Dec 24 20:20:47 DEBUG[29856] chan_sip.c: Header 0: ACK sip:2600@192.168.254.250 SIP/2.0 (36) Dec 24 20:20:47 DEBUG[29856] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.254.251:5061;branch=z9hG4bK-93438bf8 (61) Dec 24 20:20:47 DEBUG[29856] chan_sip.c: Header 2: From: Kai-Uwe Jensen ;tag=ba73a2acd02f5a5o0 (72) Dec 24 20:20:47 DEBUG[29856] chan_sip.c: Header 3: To: ;tag=as1cb89302 (45) Dec 24 20:20:47 DEBUG[29856] chan_sip.c: Header 4: Call-ID: e8556078-ec817249@192.168.254.251 (42) Dec 24 20:20:47 DEBUG[29856] chan_sip.c: Header 5: CSeq: 102 ACK (13) Dec 24 20:20:47 DEBUG[29856] chan_sip.c: Header 6: Max-Forwards: 70 (16) Dec 24 20:20:47 DEBUG[29856] chan_sip.c: Header 7: Proxy-Authorization: Digest username="ext2001",realm="asterisk",nonce="21b57201",uri="sip:2600@192.168.254.250",algorithm=MD5,response="4ef40922977c5b86c9a81447eded1c56" (169) Dec 24 20:20:47 DEBUG[29856] chan_sip.c: Header 8: Contact: Kai-Uwe Jensen (58) Dec 24 20:20:47 DEBUG[29856] chan_sip.c: Header 9: User-Agent: Linksys/PAP2-3.1.6(LS) (34) Dec 24 20:20:47 DEBUG[29856] chan_sip.c: Header 10: Content-Length: 0 (17) Dec 24 20:20:47 DEBUG[29856] chan_sip.c: Header 11: (0) Dec 24 20:20:47 DEBUG[29856] chan_sip.c: = Found Their Call ID: e8556078-ec817249@192.168.254.251 Their Tag ba73a2acd02f5a5o0 Our tag: as1cb89302 Dec 24 20:20:47 DEBUG[29856] chan_sip.c: **** Received ACK (6) - Command in SIP ACK Dec 24 20:20:47 DEBUG[29856] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #53 Dec 24 20:20:47 DEBUG[29856] chan_sip.c: Stopping retransmission on 'e8556078-ec817249@192.168.254.251' of Response 102: Match Found Dec 24 20:20:48 DEBUG[29867] pbx.c: Launching 'BackGround' Dec 24 20:20:48 DEBUG[29867] channel.c: Set channel SIP/ext2001-f732 to write format gsm Dec 24 20:20:48 DEBUG[29867] rtp.c: Ooh, format changed from unknown to ulaw Dec 24 20:20:48 DEBUG[29867] channel.c: Scheduling timer at 160 sample intervals Dec 24 20:20:49 DEBUG[29856] chan_sip.c: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP) Dec 24 20:20:49 DEBUG[29856] acl.c: ##### Testing 192.168.254.251 with 192.168.254.0 Dec 24 20:20:49 DEBUG[29856] chan_sip.c: Header 0: OPTIONS sip:ext2001@192.168.254.251:5061 SIP/2.0 (48) Dec 24 20:20:49 DEBUG[29856] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.254.250:5060;branch=z9hG4bK5a821ce0;rport (66) Dec 24 20:20:49 DEBUG[29856] chan_sip.c: Header 2: From: "Asterisk" ;tag=as39fe13ef (62) Dec 24 20:20:49 DEBUG[29856] chan_sip.c: Header 3: To: (38) Dec 24 20:20:49 DEBUG[29856] chan_sip.c: Header 4: Contact: (39) Dec 24 20:20:49 DEBUG[29856] chan_sip.c: Header 5: Call-ID: 505727df600341243507c21d4d4361e1@192.168.254.250 (57) Dec 24 20:20:49 DEBUG[29856] chan_sip.c: Header 6: CSeq: 102 OPTIONS (17) Dec 24 20:20:49 DEBUG[29856] chan_sip.c: Header 7: User-Agent: Asterisk PBX (24) Dec 24 20:20:49 DEBUG[29856] chan_sip.c: Header 8: Max-Forwards: 70 (16) Dec 24 20:20:49 DEBUG[29856] chan_sip.c: Header 9: Date: Sun, 25 Dec 2005 03:20:49 GMT (35) Dec 24 20:20:49 DEBUG[29856] chan_sip.c: Header 10: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY (66) Dec 24 20:20:49 DEBUG[29856] chan_sip.c: Header 11: Content-Length: 0 (17) Dec 24 20:20:49 DEBUG[29856] chan_sip.c: Header 12: (0) Dec 24 20:20:49 DEBUG[29856] chan_sip.c: *** SIP TIMER: Initalizing retransmit timer on packet: Id #54 Dec 24 20:20:49 DEBUG[29856] chan_sip.c: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP) Dec 24 20:20:49 DEBUG[29856] acl.c: ##### Testing 192.168.254.251 with 192.168.254.0 Dec 24 20:20:49 DEBUG[29856] chan_sip.c: Header 0: OPTIONS sip:ext2002@192.168.254.251:5062 SIP/2.0 (48) Dec 24 20:20:49 DEBUG[29856] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.254.250:5060;branch=z9hG4bK798702f4;rport (66) Dec 24 20:20:49 DEBUG[29856] chan_sip.c: Header 2: From: "Asterisk" ;tag=as70a76736 (62) Dec 24 20:20:49 DEBUG[29856] chan_sip.c: Header 3: To: (38) Dec 24 20:20:49 DEBUG[29856] chan_sip.c: Header 4: Contact: (39) Dec 24 20:20:49 DEBUG[29856] chan_sip.c: Header 5: Call-ID: 26fcad8e3a77820a0b0706777f533c11@192.168.254.250 (57) Dec 24 20:20:49 DEBUG[29856] chan_sip.c: Header 6: CSeq: 102 OPTIONS (17) Dec 24 20:20:49 DEBUG[29856] chan_sip.c: Header 7: User-Agent: Asterisk PBX (24) Dec 24 20:20:49 DEBUG[29856] chan_sip.c: Header 8: Max-Forwards: 70 (16) Dec 24 20:20:49 DEBUG[29856] chan_sip.c: Header 9: Date: Sun, 25 Dec 2005 03:20:49 GMT (35) Dec 24 20:20:49 DEBUG[29856] chan_sip.c: Header 10: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY (66) Dec 24 20:20:49 DEBUG[29856] chan_sip.c: Header 11: Content-Length: 0 (17) Dec 24 20:20:49 DEBUG[29856] chan_sip.c: Header 12: (0) Dec 24 20:20:49 DEBUG[29856] chan_sip.c: *** SIP TIMER: Initalizing retransmit timer on packet: Id #56 Dec 24 20:20:49 DEBUG[29856] chan_sip.c: Auto destroying call 'b922c0413c215c61@TUFETUFOMi5hbWVyaWNhcy5ocHFjb3JwLm5ldA..' Dec 24 20:20:49 DEBUG[29856] chan_sip.c: Header 0: SIP/2.0 200 OK (14) Dec 24 20:20:49 DEBUG[29856] chan_sip.c: Header 1: To: ;tag=f81c8eb560b46b85i0 (61) Dec 24 20:20:49 DEBUG[29856] chan_sip.c: Header 2: From: "Asterisk" ;tag=as39fe13ef (62) Dec 24 20:20:49 DEBUG[29856] chan_sip.c: Header 3: Call-ID: 505727df600341243507c21d4d4361e1@192.168.254.250 (57) Dec 24 20:20:49 DEBUG[29856] chan_sip.c: Header 4: CSeq: 102 OPTIONS (17) Dec 24 20:20:49 DEBUG[29856] chan_sip.c: Header 5: Via: SIP/2.0/UDP 192.168.254.250:5060;branch=z9hG4bK5a821ce0;rport=5060 (71) Dec 24 20:20:49 DEBUG[29856] chan_sip.c: Header 6: Server: Linksys/PAP2-3.1.6(LS) (30) Dec 24 20:20:49 DEBUG[29856] chan_sip.c: Header 7: Content-Length: 0 (17) Dec 24 20:20:49 DEBUG[29856] chan_sip.c: Header 8: Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER (61) Dec 24 20:20:49 DEBUG[29856] chan_sip.c: Header 9: Supported: x-sipura (19) Dec 24 20:20:49 DEBUG[29856] chan_sip.c: Header 10: (0) Dec 24 20:20:49 DEBUG[29856] chan_sip.c: = No match Their Call ID: 26fcad8e3a77820a0b0706777f533c11@192.168.254.250 Their Tag Our tag: as70a76736 Dec 24 20:20:49 DEBUG[29856] chan_sip.c: = Found Their Call ID: 505727df600341243507c21d4d4361e1@192.168.254.250 Their Tag Our tag: as39fe13ef Dec 24 20:20:49 DEBUG[29856] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #54 Dec 24 20:20:49 DEBUG[29856] chan_sip.c: Stopping retransmission on '505727df600341243507c21d4d4361e1@192.168.254.250' of Request 102: Match Found Dec 24 20:20:49 DEBUG[29856] chan_sip.c: Header 0: SIP/2.0 200 OK (14) Dec 24 20:20:49 DEBUG[29856] chan_sip.c: Header 1: To: ;tag=f9bf535cb983705i1 (60) Dec 24 20:20:49 DEBUG[29856] chan_sip.c: Header 2: From: "Asterisk" ;tag=as70a76736 (62) Dec 24 20:20:49 DEBUG[29856] chan_sip.c: Header 3: Call-ID: 26fcad8e3a77820a0b0706777f533c11@192.168.254.250 (57) Dec 24 20:20:49 DEBUG[29856] chan_sip.c: Header 4: CSeq: 102 OPTIONS (17) Dec 24 20:20:49 DEBUG[29856] chan_sip.c: Header 5: Via: SIP/2.0/UDP 192.168.254.250:5060;branch=z9hG4bK798702f4;rport=5060 (71) Dec 24 20:20:49 DEBUG[29856] chan_sip.c: Header 6: Server: Linksys/PAP2-3.1.6(LS) (30) Dec 24 20:20:49 DEBUG[29856] chan_sip.c: Header 7: Content-Length: 0 (17) Dec 24 20:20:49 DEBUG[29856] chan_sip.c: Header 8: Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER (61) Dec 24 20:20:49 DEBUG[29856] chan_sip.c: Header 9: Supported: x-sipura (19) Dec 24 20:20:49 DEBUG[29856] chan_sip.c: Header 10: (0) Dec 24 20:20:49 DEBUG[29856] chan_sip.c: = Found Their Call ID: 26fcad8e3a77820a0b0706777f533c11@192.168.254.250 Their Tag Our tag: as70a76736 Dec 24 20:20:49 DEBUG[29856] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #56 Dec 24 20:20:49 DEBUG[29856] chan_sip.c: Stopping retransmission on '26fcad8e3a77820a0b0706777f533c11@192.168.254.250' of Request 102: Match Found Dec 24 20:20:49 DEBUG[29856] chan_sip.c: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP) Dec 24 20:20:49 DEBUG[29856] acl.c: ##### Testing 192.168.254.252 with 192.168.254.0 Dec 24 20:20:49 DEBUG[29856] chan_sip.c: Header 0: OPTIONS sip:ext2006@192.168.254.252:5060 SIP/2.0 (48) Dec 24 20:20:49 DEBUG[29856] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.254.250:5060;branch=z9hG4bK705b02a2;rport (66) Dec 24 20:20:49 DEBUG[29856] chan_sip.c: Header 2: From: "Asterisk" ;tag=as28ba08df (62) Dec 24 20:20:49 DEBUG[29856] chan_sip.c: Header 3: To: (38) Dec 24 20:20:49 DEBUG[29856] chan_sip.c: Header 4: Contact: (39) Dec 24 20:20:49 DEBUG[29856] chan_sip.c: Header 5: Call-ID: 78064a7b29b527276bbcf28701ed4b60@192.168.254.250 (57) Dec 24 20:20:49 DEBUG[29856] chan_sip.c: Header 6: CSeq: 102 OPTIONS (17) Dec 24 20:20:49 DEBUG[29856] chan_sip.c: Header 7: User-Agent: Asterisk PBX (24) Dec 24 20:20:49 DEBUG[29856] chan_sip.c: Header 8: Max-Forwards: 70 (16) Dec 24 20:20:49 DEBUG[29856] chan_sip.c: Header 9: Date: Sun, 25 Dec 2005 03:20:49 GMT (35) Dec 24 20:20:49 DEBUG[29856] chan_sip.c: Header 10: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY (66) Dec 24 20:20:49 DEBUG[29856] chan_sip.c: Header 11: Content-Length: 0 (17) Dec 24 20:20:49 DEBUG[29856] chan_sip.c: Header 12: (0) Dec 24 20:20:49 DEBUG[29856] chan_sip.c: *** SIP TIMER: Initalizing retransmit timer on packet: Id #60 Dec 24 20:20:49 DEBUG[29856] chan_sip.c: Header 0: SIP/2.0 200 OK (14) Dec 24 20:20:49 DEBUG[29856] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.254.250:5060;branch=z9hG4bK705b02a2;rport (66) Dec 24 20:20:49 DEBUG[29856] chan_sip.c: Header 2: From: "Asterisk" ;tag=as28ba08df (62) Dec 24 20:20:49 DEBUG[29856] chan_sip.c: Header 3: To: ;tag=7ECBFB08-638E95E1 (60) Dec 24 20:20:49 DEBUG[29856] chan_sip.c: Header 4: CSeq: 102 OPTIONS (17) Dec 24 20:20:49 DEBUG[29856] chan_sip.c: Header 5: Call-ID: 78064a7b29b527276bbcf28701ed4b60@192.168.254.250 (57) Dec 24 20:20:49 DEBUG[29856] chan_sip.c: Header 6: Contact: (43) Dec 24 20:20:49 DEBUG[29856] chan_sip.c: Header 7: Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER (96) Dec 24 20:20:49 DEBUG[29856] chan_sip.c: Header 8: User-Agent: PolycomSoundPointIP-SPIP_500-UA/1.6.2.0041 (54) Dec 24 20:20:49 DEBUG[29856] chan_sip.c: Header 9: Content-Length: 0 (17) Dec 24 20:20:49 DEBUG[29856] chan_sip.c: Header 10: (0) Dec 24 20:20:49 DEBUG[29856] chan_sip.c: = Found Their Call ID: 78064a7b29b527276bbcf28701ed4b60@192.168.254.250 Their Tag Our tag: as28ba08df Dec 24 20:20:49 DEBUG[29856] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #60 Dec 24 20:20:49 DEBUG[29856] chan_sip.c: Stopping retransmission on '78064a7b29b527276bbcf28701ed4b60@192.168.254.250' of Request 102: Match Found Dec 24 20:20:50 DEBUG[29856] chan_sip.c: Header 0: INFO sip:2600@192.168.254.250 SIP/2.0 (37) Dec 24 20:20:50 DEBUG[29856] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.254.251:5061;branch=z9hG4bK-36bd3df8 (61) Dec 24 20:20:50 DEBUG[29856] chan_sip.c: Header 2: From: Kai-Uwe Jensen ;tag=ba73a2acd02f5a5o0 (72) Dec 24 20:20:50 DEBUG[29856] chan_sip.c: Header 3: To: ;tag=as1cb89302 (45) Dec 24 20:20:50 DEBUG[29856] chan_sip.c: Header 4: Call-ID: e8556078-ec817249@192.168.254.251 (42) Dec 24 20:20:50 DEBUG[29856] chan_sip.c: Header 5: CSeq: 103 INFO (14) Dec 24 20:20:50 DEBUG[29856] chan_sip.c: Header 6: Max-Forwards: 70 (16) Dec 24 20:20:50 DEBUG[29856] chan_sip.c: Header 7: Proxy-Authorization: Digest username="ext2001",realm="asterisk",nonce="21b57201",uri="sip:2600@192.168.254.250",algorithm=MD5,response="2f860e284313c7b6ee767365b8694f53" (169) Dec 24 20:20:50 DEBUG[29856] chan_sip.c: Header 8: User-Agent: Linksys/PAP2-3.1.6(LS) (34) Dec 24 20:20:50 DEBUG[29856] chan_sip.c: Header 9: Content-Length: 24 (18) Dec 24 20:20:50 DEBUG[29856] chan_sip.c: Header 10: Content-Type: application/dtmf-relay (36) Dec 24 20:20:50 DEBUG[29856] chan_sip.c: Header 11: (0) Dec 24 20:20:50 DEBUG[29856] chan_sip.c: Line: Signal=8 (8) Dec 24 20:20:50 DEBUG[29856] chan_sip.c: Line: Duration=100 (12) Dec 24 20:20:50 DEBUG[29856] chan_sip.c: = Found Their Call ID: e8556078-ec817249@192.168.254.251 Their Tag ba73a2acd02f5a5o0 Our tag: as1cb89302 Dec 24 20:20:50 DEBUG[29856] chan_sip.c: **** Received INFO (13) - Command in SIP INFO Dec 24 20:20:50 DEBUG[29867] channel.c: Scheduling timer at 0 sample intervals Dec 24 20:20:50 DEBUG[29867] channel.c: Set channel SIP/ext2001-f732 to write format ulaw Dec 24 20:20:50 DEBUG[29867] pbx.c: Oooh, got something to jump out with ('8')! Dec 24 20:20:50 DEBUG[29856] chan_sip.c: Header 0: INFO sip:2600@192.168.254.250 SIP/2.0 (37) Dec 24 20:20:50 DEBUG[29856] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.254.251:5061;branch=z9hG4bK-113f341c (61) Dec 24 20:20:50 DEBUG[29856] chan_sip.c: Header 2: From: Kai-Uwe Jensen ;tag=ba73a2acd02f5a5o0 (72) Dec 24 20:20:50 DEBUG[29856] chan_sip.c: Header 3: To: ;tag=as1cb89302 (45) Dec 24 20:20:50 DEBUG[29856] chan_sip.c: Header 4: Call-ID: e8556078-ec817249@192.168.254.251 (42) Dec 24 20:20:50 DEBUG[29856] chan_sip.c: Header 5: CSeq: 104 INFO (14) Dec 24 20:20:50 DEBUG[29856] chan_sip.c: Header 6: Max-Forwards: 70 (16) Dec 24 20:20:50 DEBUG[29856] chan_sip.c: Header 7: Proxy-Authorization: Digest username="ext2001",realm="asterisk",nonce="21b57201",uri="sip:2600@192.168.254.250",algorithm=MD5,response="2f860e284313c7b6ee767365b8694f53" (169) Dec 24 20:20:50 DEBUG[29856] chan_sip.c: Header 8: User-Agent: Linksys/PAP2-3.1.6(LS) (34) Dec 24 20:20:50 DEBUG[29856] chan_sip.c: Header 9: Content-Length: 24 (18) Dec 24 20:20:50 DEBUG[29856] chan_sip.c: Header 10: Content-Type: application/dtmf-relay (36) Dec 24 20:20:50 DEBUG[29856] chan_sip.c: Header 11: (0) Dec 24 20:20:50 DEBUG[29856] chan_sip.c: Line: Signal=9 (8) Dec 24 20:20:50 DEBUG[29856] chan_sip.c: Line: Duration=100 (12) Dec 24 20:20:50 DEBUG[29856] chan_sip.c: = Found Their Call ID: e8556078-ec817249@192.168.254.251 Their Tag ba73a2acd02f5a5o0 Our tag: as1cb89302 Dec 24 20:20:50 DEBUG[29856] chan_sip.c: **** Received INFO (13) - Command in SIP INFO Dec 24 20:20:50 DEBUG[29856] chan_sip.c: Header 0: INFO sip:2600@192.168.254.250 SIP/2.0 (37) Dec 24 20:20:50 DEBUG[29856] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.254.251:5061;branch=z9hG4bK-f8d71461 (61) Dec 24 20:20:50 DEBUG[29856] chan_sip.c: Header 2: From: Kai-Uwe Jensen ;tag=ba73a2acd02f5a5o0 (72) Dec 24 20:20:50 DEBUG[29856] chan_sip.c: Header 3: To: ;tag=as1cb89302 (45) Dec 24 20:20:50 DEBUG[29856] chan_sip.c: Header 4: Call-ID: e8556078-ec817249@192.168.254.251 (42) Dec 24 20:20:50 DEBUG[29856] chan_sip.c: Header 5: CSeq: 105 INFO (14) Dec 24 20:20:50 DEBUG[29856] chan_sip.c: Header 6: Max-Forwards: 70 (16) Dec 24 20:20:50 DEBUG[29856] chan_sip.c: Header 7: Proxy-Authorization: Digest username="ext2001",realm="asterisk",nonce="21b57201",uri="sip:2600@192.168.254.250",algorithm=MD5,response="2f860e284313c7b6ee767365b8694f53" (169) Dec 24 20:20:50 DEBUG[29856] chan_sip.c: Header 8: User-Agent: Linksys/PAP2-3.1.6(LS) (34) Dec 24 20:20:50 DEBUG[29856] chan_sip.c: Header 9: Content-Length: 24 (18) Dec 24 20:20:50 DEBUG[29856] chan_sip.c: Header 10: Content-Type: application/dtmf-relay (36) Dec 24 20:20:50 DEBUG[29856] chan_sip.c: Header 11: (0) Dec 24 20:20:50 DEBUG[29856] chan_sip.c: Line: Signal=8 (8) Dec 24 20:20:50 DEBUG[29856] chan_sip.c: Line: Duration=100 (12) Dec 24 20:20:50 DEBUG[29856] chan_sip.c: = Found Their Call ID: e8556078-ec817249@192.168.254.251 Their Tag ba73a2acd02f5a5o0 Our tag: as1cb89302 Dec 24 20:20:50 DEBUG[29856] chan_sip.c: **** Received INFO (13) - Command in SIP INFO Dec 24 20:20:51 DEBUG[29856] chan_sip.c: Header 0: (0) Dec 24 20:20:51 DEBUG[29856] chan_sip.c: Header 0: INFO sip:2600@192.168.254.250 SIP/2.0 (37) Dec 24 20:20:51 DEBUG[29856] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.254.251:5061;branch=z9hG4bK-8060a943 (61) Dec 24 20:20:51 DEBUG[29856] chan_sip.c: Header 2: From: Kai-Uwe Jensen ;tag=ba73a2acd02f5a5o0 (72) Dec 24 20:20:51 DEBUG[29856] chan_sip.c: Header 3: To: ;tag=as1cb89302 (45) Dec 24 20:20:51 DEBUG[29856] chan_sip.c: Header 4: Call-ID: e8556078-ec817249@192.168.254.251 (42) Dec 24 20:20:51 DEBUG[29856] chan_sip.c: Header 5: CSeq: 106 INFO (14) Dec 24 20:20:51 DEBUG[29856] chan_sip.c: Header 6: Max-Forwards: 70 (16) Dec 24 20:20:51 DEBUG[29856] chan_sip.c: Header 7: Proxy-Authorization: Digest username="ext2001",realm="asterisk",nonce="21b57201",uri="sip:2600@192.168.254.250",algorithm=MD5,response="2f860e284313c7b6ee767365b8694f53" (169) Dec 24 20:20:51 DEBUG[29856] chan_sip.c: Header 8: User-Agent: Linksys/PAP2-3.1.6(LS) (34) Dec 24 20:20:51 DEBUG[29856] chan_sip.c: Header 9: Content-Length: 24 (18) Dec 24 20:20:51 DEBUG[29856] chan_sip.c: Header 10: Content-Type: application/dtmf-relay (36) Dec 24 20:20:51 DEBUG[29856] chan_sip.c: Header 11: (0) Dec 24 20:20:51 DEBUG[29856] chan_sip.c: Line: Signal=7 (8) Dec 24 20:20:51 DEBUG[29856] chan_sip.c: Line: Duration=100 (12) Dec 24 20:20:51 DEBUG[29856] chan_sip.c: = Found Their Call ID: e8556078-ec817249@192.168.254.251 Their Tag ba73a2acd02f5a5o0 Our tag: as1cb89302 Dec 24 20:20:51 DEBUG[29856] chan_sip.c: **** Received INFO (13) - Command in SIP INFO Dec 24 20:20:52 DEBUG[29856] chan_sip.c: Header 0: INFO sip:2600@192.168.254.250 SIP/2.0 (37) Dec 24 20:20:52 DEBUG[29856] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.254.251:5061;branch=z9hG4bK-67c049ed (61) Dec 24 20:20:52 DEBUG[29856] chan_sip.c: Header 2: From: Kai-Uwe Jensen ;tag=ba73a2acd02f5a5o0 (72) Dec 24 20:20:52 DEBUG[29856] chan_sip.c: Header 3: To: ;tag=as1cb89302 (45) Dec 24 20:20:52 DEBUG[29856] chan_sip.c: Header 4: Call-ID: e8556078-ec817249@192.168.254.251 (42) Dec 24 20:20:52 DEBUG[29856] chan_sip.c: Header 5: CSeq: 107 INFO (14) Dec 24 20:20:52 DEBUG[29856] chan_sip.c: Header 6: Max-Forwards: 70 (16) Dec 24 20:20:52 DEBUG[29856] chan_sip.c: Header 7: Proxy-Authorization: Digest username="ext2001",realm="asterisk",nonce="21b57201",uri="sip:2600@192.168.254.250",algorithm=MD5,response="2f860e284313c7b6ee767365b8694f53" (169) Dec 24 20:20:52 DEBUG[29856] chan_sip.c: Header 8: User-Agent: Linksys/PAP2-3.1.6(LS) (34) Dec 24 20:20:52 DEBUG[29856] chan_sip.c: Header 9: Content-Length: 24 (18) Dec 24 20:20:52 DEBUG[29856] chan_sip.c: Header 10: Content-Type: application/dtmf-relay (36) Dec 24 20:20:52 DEBUG[29856] chan_sip.c: Header 11: (0) Dec 24 20:20:52 DEBUG[29856] chan_sip.c: Line: Signal=7 (8) Dec 24 20:20:52 DEBUG[29856] chan_sip.c: Line: Duration=100 (12) Dec 24 20:20:52 DEBUG[29856] chan_sip.c: = Found Their Call ID: e8556078-ec817249@192.168.254.251 Their Tag ba73a2acd02f5a5o0 Our tag: as1cb89302 Dec 24 20:20:52 DEBUG[29856] chan_sip.c: **** Received INFO (13) - Command in SIP INFO Dec 24 20:20:53 DEBUG[29856] chan_sip.c: Header 0: INFO sip:2600@192.168.254.250 SIP/2.0 (37) Dec 24 20:20:53 DEBUG[29856] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.254.251:5061;branch=z9hG4bK-bed81199 (61) Dec 24 20:20:53 DEBUG[29856] chan_sip.c: Header 2: From: Kai-Uwe Jensen ;tag=ba73a2acd02f5a5o0 (72) Dec 24 20:20:53 DEBUG[29856] chan_sip.c: Header 3: To: ;tag=as1cb89302 (45) Dec 24 20:20:53 DEBUG[29856] chan_sip.c: Header 4: Call-ID: e8556078-ec817249@192.168.254.251 (42) Dec 24 20:20:53 DEBUG[29856] chan_sip.c: Header 5: CSeq: 108 INFO (14) Dec 24 20:20:53 DEBUG[29856] chan_sip.c: Header 6: Max-Forwards: 70 (16) Dec 24 20:20:53 DEBUG[29856] chan_sip.c: Header 7: Proxy-Authorization: Digest username="ext2001",realm="asterisk",nonce="21b57201",uri="sip:2600@192.168.254.250",algorithm=MD5,response="2f860e284313c7b6ee767365b8694f53" (169) Dec 24 20:20:53 DEBUG[29856] chan_sip.c: Header 8: User-Agent: Linksys/PAP2-3.1.6(LS) (34) Dec 24 20:20:53 DEBUG[29856] chan_sip.c: Header 9: Content-Length: 24 (18) Dec 24 20:20:53 DEBUG[29856] chan_sip.c: Header 10: Content-Type: application/dtmf-relay (36) Dec 24 20:20:53 DEBUG[29856] chan_sip.c: Header 11: (0) Dec 24 20:20:53 DEBUG[29856] chan_sip.c: Line: Signal=6 (8) Dec 24 20:20:53 DEBUG[29856] chan_sip.c: Line: Duration=100 (12) Dec 24 20:20:53 DEBUG[29856] chan_sip.c: = Found Their Call ID: e8556078-ec817249@192.168.254.251 Their Tag ba73a2acd02f5a5o0 Our tag: as1cb89302 Dec 24 20:20:53 DEBUG[29856] chan_sip.c: **** Received INFO (13) - Command in SIP INFO Dec 24 20:20:53 DEBUG[29856] chan_sip.c: Header 0: INFO sip:2600@192.168.254.250 SIP/2.0 (37) Dec 24 20:20:53 DEBUG[29856] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.254.251:5061;branch=z9hG4bK-d5150e35 (61) Dec 24 20:20:53 DEBUG[29856] chan_sip.c: Header 2: From: Kai-Uwe Jensen ;tag=ba73a2acd02f5a5o0 (72) Dec 24 20:20:53 DEBUG[29856] chan_sip.c: Header 3: To: ;tag=as1cb89302 (45) Dec 24 20:20:53 DEBUG[29856] chan_sip.c: Header 4: Call-ID: e8556078-ec817249@192.168.254.251 (42) Dec 24 20:20:53 DEBUG[29856] chan_sip.c: Header 5: CSeq: 109 INFO (14) Dec 24 20:20:53 DEBUG[29856] chan_sip.c: Header 6: Max-Forwards: 70 (16) Dec 24 20:20:53 DEBUG[29856] chan_sip.c: Header 7: Proxy-Authorization: Digest username="ext2001",realm="asterisk",nonce="21b57201",uri="sip:2600@192.168.254.250",algorithm=MD5,response="2f860e284313c7b6ee767365b8694f53" (169) Dec 24 20:20:53 DEBUG[29856] chan_sip.c: Header 8: User-Agent: Linksys/PAP2-3.1.6(LS) (34) Dec 24 20:20:53 DEBUG[29856] chan_sip.c: Header 9: Content-Length: 24 (18) Dec 24 20:20:53 DEBUG[29856] chan_sip.c: Header 10: Content-Type: application/dtmf-relay (36) Dec 24 20:20:53 DEBUG[29856] chan_sip.c: Header 11: (0) Dec 24 20:20:53 DEBUG[29856] chan_sip.c: Line: Signal=2 (8) Dec 24 20:20:53 DEBUG[29856] chan_sip.c: Line: Duration=100 (12) Dec 24 20:20:53 DEBUG[29856] chan_sip.c: = Found Their Call ID: e8556078-ec817249@192.168.254.251 Their Tag ba73a2acd02f5a5o0 Our tag: as1cb89302 Dec 24 20:20:53 DEBUG[29856] chan_sip.c: **** Received INFO (13) - Command in SIP INFO Dec 24 20:20:54 DEBUG[29856] chan_sip.c: Header 0: INFO sip:2600@192.168.254.250 SIP/2.0 (37) Dec 24 20:20:54 DEBUG[29856] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.254.251:5061;branch=z9hG4bK-7a2cda8f (61) Dec 24 20:20:54 DEBUG[29856] chan_sip.c: Header 2: From: Kai-Uwe Jensen ;tag=ba73a2acd02f5a5o0 (72) Dec 24 20:20:54 DEBUG[29856] chan_sip.c: Header 3: To: ;tag=as1cb89302 (45) Dec 24 20:20:54 DEBUG[29856] chan_sip.c: Header 4: Call-ID: e8556078-ec817249@192.168.254.251 (42) Dec 24 20:20:54 DEBUG[29856] chan_sip.c: Header 5: CSeq: 110 INFO (14) Dec 24 20:20:54 DEBUG[29856] chan_sip.c: Header 6: Max-Forwards: 70 (16) Dec 24 20:20:54 DEBUG[29856] chan_sip.c: Header 7: Proxy-Authorization: Digest username="ext2001",realm="asterisk",nonce="21b57201",uri="sip:2600@192.168.254.250",algorithm=MD5,response="2f860e284313c7b6ee767365b8694f53" (169) Dec 24 20:20:54 DEBUG[29856] chan_sip.c: Header 8: User-Agent: Linksys/PAP2-3.1.6(LS) (34) Dec 24 20:20:54 DEBUG[29856] chan_sip.c: Header 9: Content-Length: 24 (18) Dec 24 20:20:54 DEBUG[29856] chan_sip.c: Header 10: Content-Type: application/dtmf-relay (36) Dec 24 20:20:54 DEBUG[29856] chan_sip.c: Header 11: (0) Dec 24 20:20:54 DEBUG[29856] chan_sip.c: Line: Signal=# (8) Dec 24 20:20:54 DEBUG[29856] chan_sip.c: Line: Duration=100 (12) Dec 24 20:20:54 DEBUG[29856] chan_sip.c: = Found Their Call ID: e8556078-ec817249@192.168.254.251 Their Tag ba73a2acd02f5a5o0 Our tag: as1cb89302 Dec 24 20:20:54 DEBUG[29856] chan_sip.c: **** Received INFO (13) - Command in SIP INFO Dec 24 20:20:54 DEBUG[29867] pbx.c: Launching 'Goto' Dec 24 20:20:54 DEBUG[29867] pbx.c: Launching 'Wait' Dec 24 20:20:55 DEBUG[29867] pbx.c: Launching 'MeetMe' Dec 24 20:20:55 DEBUG[29867] config.c: Parsing /etc/asterisk/meetme.conf Dec 24 20:20:55 DEBUG[29867] chan_zap.c: Using channel -2 Dec 24 20:20:55 DEBUG[29867] channel.c: Set channel Zap/pseudo-1755859757 to read format slin Dec 24 20:20:55 DEBUG[29867] channel.c: Set channel Zap/pseudo-1755859757 to write format slin Dec 24 20:20:55 DEBUG[29867] channel.c: Set channel SIP/ext2001-f732 to write format gsm Dec 24 20:20:55 DEBUG[29867] rtp.c: Difference is 42528, ms is 5336 Dec 24 20:20:55 DEBUG[29867] channel.c: Scheduling timer at 160 sample intervals Dec 24 20:20:55 DEBUG[29853] devicestate.c: Changing state for Zap/pseudo - state 2 (In use) Dec 24 20:20:55 DEBUG[29871] app_queue.c: Device 'Zap/pseudo' changed to state '2' (In use) Dec 24 20:20:58 DEBUG[29867] channel.c: Scheduling timer at 0 sample intervals Dec 24 20:20:58 DEBUG[29867] channel.c: Scheduling timer at 0 sample intervals Dec 24 20:20:58 DEBUG[29867] channel.c: Set channel SIP/ext2001-f732 to write format ulaw Dec 24 20:20:58 DEBUG[29867] channel.c: Set channel SIP/ext2001-f732 to write format slin Dec 24 20:20:58 DEBUG[29867] channel.c: Set channel SIP/ext2001-f732 to read format slin Dec 24 20:20:58 DEBUG[29867] app_meetme.c: Placed channel SIP/ext2001-f732 in ZAP conf 1023 Dec 24 20:20:58 DEBUG[29867] channel.c: Set channel SIP/ext2001-f732 to write format slin Dec 24 20:20:58 DEBUG[29867] channel.c: Scheduling timer at 160 sample intervals Dec 24 20:20:58 DEBUG[29867] channel.c: Generator got voice, switching to phase locked mode Dec 24 20:20:58 DEBUG[29867] channel.c: Scheduling timer at 0 sample intervals Dec 24 20:20:58 DEBUG[29867] channel.c: Set channel SIP/ext2001-f732 to write format slin Dec 24 20:20:58 DEBUG[29867] channel.c: Set channel SIP/ext2001-f732 to write format slin Dec 24 20:20:58 DEBUG[29867] res_musiconhold.c: SIP/ext2001-f732 Opened file 3 '/var/lib/asterisk/mohmp3/MoH' Dec 24 20:21:00 DEBUG[29856] chan_sip.c: Header 0: (0) Dec 24 20:21:01 DEBUG[29870] chan_zap.c: DTMF digit: 2 on Zap/pseudo-1755859757 ################################################################# Restarts here #################################################################