Dec 24 13:39:00 DEBUG[12075] chan_sip.c: Header 0: REGISTER sip:cojensen.net SIP/2.0 (33) Dec 24 13:39:00 DEBUG[12075] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.254.60:8917;branch=z9hG4bK-d87543-b87b1065292f3c40-1--d87543-;rport (92) Dec 24 13:39:00 DEBUG[12075] chan_sip.c: Header 2: Max-Forwards: 70 (16) Dec 24 13:39:00 DEBUG[12075] chan_sip.c: Header 3: Contact: (42) Dec 24 13:39:00 DEBUG[12075] chan_sip.c: Header 4: To: "Kai-Uwe Jensen" (46) Dec 24 13:39:00 DEBUG[12075] chan_sip.c: Header 5: From: "Kai-Uwe Jensen";tag=6b1c673d (61) Dec 24 13:39:00 DEBUG[12075] chan_sip.c: Header 6: Call-ID: 1d3f56532d28e464@TUFETUFOMi5hbWVyaWNhcy5ocHFjb3JwLm5ldA.. (66) Dec 24 13:39:00 DEBUG[12075] chan_sip.c: Header 7: CSeq: 1 REGISTER (16) Dec 24 13:39:00 DEBUG[12075] chan_sip.c: Header 8: Expires: 3600 (13) Dec 24 13:39:00 DEBUG[12075] chan_sip.c: Header 9: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO (81) Dec 24 13:39:00 DEBUG[12075] chan_sip.c: Header 10: Supported: eventlist (20) Dec 24 13:39:00 DEBUG[12075] chan_sip.c: Header 11: User-Agent: eyeBeam release 3010n stamp 19039 (45) Dec 24 13:39:00 DEBUG[12075] chan_sip.c: Header 12: Content-Length: 0 (17) Dec 24 13:39:00 DEBUG[12075] chan_sip.c: Header 13: (0) Dec 24 13:39:00 DEBUG[12075] chan_sip.c: = No match Their Call ID: 7ec44bce3ac5a65a1e70384c6dbe2282@192.168.254.250 Their Tag cb2000b247d89723001a836145f3b053.1614 Our tag: as2c95cf17 Dec 24 13:39:00 DEBUG[12075] chan_sip.c: = No match Their Call ID: 06e58d0f318a0d3f7c0269614a478651@192.168.254.250 Their Tag b11cb9bb270104b49a99a995b8c68544.9c37 Our tag: as4219e13e Dec 24 13:39:00 DEBUG[12075] chan_sip.c: = No match Their Call ID: 704a6f430de6c4940708d0313b20cf31@192.168.254.250 Their Tag 21a483426c2cd5d9b85bffe6bba40a2e.933b Our tag: as6ff7d8a1 Dec 24 13:39:00 DEBUG[12075] acl.c: ##### Testing 192.168.254.1 with 192.168.254.0 Dec 24 13:39:00 DEBUG[12075] chan_sip.c: Allocating new SIP dialog for 1d3f56532d28e464@TUFETUFOMi5hbWVyaWNhcy5ocHFjb3JwLm5ldA.. - REGISTER (No RTP) Dec 24 13:39:00 DEBUG[12075] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER Dec 24 13:39:01 DEBUG[12075] chan_sip.c: Header 0: REGISTER sip:cojensen.net SIP/2.0 (33) Dec 24 13:39:01 DEBUG[12075] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.254.60:8917;branch=z9hG4bK-d87543-416fc76189275470-1--d87543-;rport (92) Dec 24 13:39:01 DEBUG[12075] chan_sip.c: Header 2: Max-Forwards: 70 (16) Dec 24 13:39:01 DEBUG[12075] chan_sip.c: Header 3: Contact: (42) Dec 24 13:39:01 DEBUG[12075] chan_sip.c: Header 4: To: "Kai-Uwe Jensen" (46) Dec 24 13:39:01 DEBUG[12075] chan_sip.c: Header 5: From: "Kai-Uwe Jensen";tag=6b1c673d (61) Dec 24 13:39:01 DEBUG[12075] chan_sip.c: Header 6: Call-ID: 1d3f56532d28e464@TUFETUFOMi5hbWVyaWNhcy5ocHFjb3JwLm5ldA.. (66) Dec 24 13:39:01 DEBUG[12075] chan_sip.c: Header 7: CSeq: 2 REGISTER (16) Dec 24 13:39:01 DEBUG[12075] chan_sip.c: Header 8: Expires: 3600 (13) Dec 24 13:39:01 DEBUG[12075] chan_sip.c: Header 9: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO (81) Dec 24 13:39:01 DEBUG[12075] chan_sip.c: Header 10: Supported: eventlist (20) Dec 24 13:39:01 DEBUG[12075] chan_sip.c: Header 11: User-Agent: eyeBeam release 3010n stamp 19039 (45) Dec 24 13:39:01 DEBUG[12075] chan_sip.c: Header 12: Authorization: Digest username="ext2003",realm="asterisk",nonce="0bbe8a7d",uri="sip:cojensen.net",response="f032757f99ec153c9e3bcdaf17d96683",algorithm=MD5 (155) Dec 24 13:39:01 DEBUG[12075] chan_sip.c: Header 13: Content-Length: 0 (17) Dec 24 13:39:01 DEBUG[12075] chan_sip.c: Header 14: (0) Dec 24 13:39:01 DEBUG[12075] chan_sip.c: = Found Their Call ID: 1d3f56532d28e464@TUFETUFOMi5hbWVyaWNhcy5ocHFjb3JwLm5ldA.. Their Tag 6b1c673d Our tag: as58010d8c Dec 24 13:39:01 DEBUG[12075] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER Dec 24 13:39:01 DEBUG[12075] chan_sip.c: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP) Dec 24 13:39:01 DEBUG[12075] acl.c: ##### Testing 192.168.254.1 with 192.168.254.0 Dec 24 13:39:01 DEBUG[12075] chan_sip.c: Header 0: OPTIONS sip:ext2003@192.168.254.60:8917 SIP/2.0 (47) Dec 24 13:39:01 DEBUG[12075] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.254.250:5060;branch=z9hG4bK4d503b1e;rport (66) Dec 24 13:39:01 DEBUG[12075] chan_sip.c: Header 2: From: "Asterisk" ;tag=as218dfaf6 (62) Dec 24 13:39:01 DEBUG[12075] chan_sip.c: Header 3: To: (37) Dec 24 13:39:01 DEBUG[12075] chan_sip.c: Header 4: Contact: (39) Dec 24 13:39:01 DEBUG[12075] chan_sip.c: Header 5: Call-ID: 321315ca2f6dd3d253295eb630d76198@192.168.254.250 (57) Dec 24 13:39:01 DEBUG[12075] chan_sip.c: Header 6: CSeq: 102 OPTIONS (17) Dec 24 13:39:01 DEBUG[12075] chan_sip.c: Header 7: User-Agent: Asterisk PBX (24) Dec 24 13:39:01 DEBUG[12075] chan_sip.c: Header 8: Max-Forwards: 70 (16) Dec 24 13:39:01 DEBUG[12075] chan_sip.c: Header 9: Date: Sat, 24 Dec 2005 20:39:01 GMT (35) Dec 24 13:39:01 DEBUG[12075] chan_sip.c: Header 10: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY (66) Dec 24 13:39:01 DEBUG[12075] chan_sip.c: Header 11: Content-Length: 0 (17) Dec 24 13:39:01 DEBUG[12075] chan_sip.c: Header 12: (0) Dec 24 13:39:01 DEBUG[12075] chan_sip.c: *** SIP TIMER: Initalizing retransmit timer on packet: Id #45 Dec 24 13:39:01 DEBUG[12072] chan_sip.c: Checking device state for peer ext2003 Dec 24 13:39:01 DEBUG[12072] devicestate.c: Changing state for SIP/ext2003 - state 1 (Not in use) Dec 24 13:39:01 DEBUG[12072] chan_sip.c: Checking device state for peer ext2003 Dec 24 13:39:01 DEBUG[12072] chan_sip.c: Checking device state for peer ext2003 Dec 24 13:39:01 DEBUG[12083] app_queue.c: Device 'SIP/ext2003' changed to state '1' (Not in use) Dec 24 13:39:01 DEBUG[12075] chan_sip.c: Header 0: SIP/2.0 200 OK (14) Dec 24 13:39:01 DEBUG[12075] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.254.250:5060;branch=z9hG4bK4d503b1e;rport=5060;received=67.174.106.30 (94) Dec 24 13:39:01 DEBUG[12075] chan_sip.c: Header 2: Contact: (34) Dec 24 13:39:01 DEBUG[12075] chan_sip.c: Header 3: To: ;tag=597b2a14 (50) Dec 24 13:39:01 DEBUG[12075] chan_sip.c: Header 4: From: "Asterisk";tag=as218dfaf6 (61) Dec 24 13:39:01 DEBUG[12075] chan_sip.c: Header 5: Call-ID: 321315ca2f6dd3d253295eb630d76198@192.168.254.250 (57) Dec 24 13:39:01 DEBUG[12075] chan_sip.c: Header 6: CSeq: 102 OPTIONS (17) Dec 24 13:39:01 DEBUG[12075] chan_sip.c: Header 7: Accept: application/sdp (23) Dec 24 13:39:01 DEBUG[12075] chan_sip.c: Header 8: Accept-Language: en (19) Dec 24 13:39:01 DEBUG[12075] chan_sip.c: Header 9: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO (81) Dec 24 13:39:01 DEBUG[12075] chan_sip.c: Header 10: Supported: eventlist (20) Dec 24 13:39:01 DEBUG[12075] chan_sip.c: Header 11: User-Agent: eyeBeam release 3010n stamp 19039 (45) Dec 24 13:39:01 DEBUG[12075] chan_sip.c: Header 12: Content-Length: 0 (17) Dec 24 13:39:01 DEBUG[12075] chan_sip.c: Header 13: (0) Dec 24 13:39:01 DEBUG[12075] chan_sip.c: = Found Their Call ID: 321315ca2f6dd3d253295eb630d76198@192.168.254.250 Their Tag Our tag: as218dfaf6 Dec 24 13:39:01 DEBUG[12075] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #45 Dec 24 13:39:01 DEBUG[12075] chan_sip.c: Stopping retransmission on '321315ca2f6dd3d253295eb630d76198@192.168.254.250' of Request 102: Match Found Dec 24 13:39:01 DEBUG[12072] chan_sip.c: Checking device state for peer ext2003 Dec 24 13:39:01 DEBUG[12072] devicestate.c: Changing state for SIP/ext2003 - state 1 (Not in use) Dec 24 13:39:01 DEBUG[12072] chan_sip.c: Checking device state for peer ext2003 Dec 24 13:39:01 DEBUG[12072] chan_sip.c: Checking device state for peer ext2003 Dec 24 13:39:01 DEBUG[12084] app_queue.c: Device 'SIP/ext2003' changed to state '1' (Not in use) Dec 24 13:39:05 DEBUG[12075] chan_sip.c: Allocating new SIP dialog for (No Call-ID) - NOTIFY (No RTP) Dec 24 13:39:05 DEBUG[12075] acl.c: ##### Testing 192.168.254.1 with 192.168.254.0 Dec 24 13:39:05 DEBUG[12075] chan_sip.c: Header 0: NOTIFY sip:ext2003@192.168.254.60:8917 SIP/2.0 (46) Dec 24 13:39:05 DEBUG[12075] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.254.250:5060;branch=z9hG4bK71999702;rport (66) Dec 24 13:39:05 DEBUG[12075] chan_sip.c: Header 2: From: "Asterisk" ;tag=as1e95841b (62) Dec 24 13:39:05 DEBUG[12075] chan_sip.c: Header 3: To: (37) Dec 24 13:39:05 DEBUG[12075] chan_sip.c: Header 4: Contact: (39) Dec 24 13:39:05 DEBUG[12075] chan_sip.c: Header 5: Call-ID: 174331503c8666a15333539d595d128f@192.168.254.250 (57) Dec 24 13:39:05 DEBUG[12075] chan_sip.c: Header 6: CSeq: 102 NOTIFY (16) Dec 24 13:39:05 DEBUG[12075] chan_sip.c: Header 7: User-Agent: Asterisk PBX (24) Dec 24 13:39:05 DEBUG[12075] chan_sip.c: Header 8: Max-Forwards: 70 (16) Dec 24 13:39:05 DEBUG[12075] chan_sip.c: Header 9: Event: message-summary (22) Dec 24 13:39:05 DEBUG[12075] chan_sip.c: Header 10: Content-Type: application/simple-message-summary (48) Dec 24 13:39:05 DEBUG[12075] chan_sip.c: Header 11: Content-Length: 80 (18) Dec 24 13:39:05 DEBUG[12075] chan_sip.c: Header 12: (0) Dec 24 13:39:05 DEBUG[12075] chan_sip.c: Line: Messages-Waiting: no (20) Dec 24 13:39:05 DEBUG[12075] chan_sip.c: Line: Message-Account: sip:asterisk@ (30) Dec 24 13:39:05 DEBUG[12075] chan_sip.c: Line: Voice-Message: 0/0 (0/0) (24) Dec 24 13:39:05 DEBUG[12075] chan_sip.c: *** SIP TIMER: Initalizing retransmit timer on packet: Id #49 Dec 24 13:39:05 DEBUG[12075] chan_sip.c: Header 0: SIP/2.0 200 OK (14) Dec 24 13:39:05 DEBUG[12075] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.254.250:5060;branch=z9hG4bK71999702;rport=5060;received=67.174.106.30 (94) Dec 24 13:39:05 DEBUG[12075] chan_sip.c: Header 2: Contact: (34) Dec 24 13:39:05 DEBUG[12075] chan_sip.c: Header 3: To: ;tag=fd6dc502 (50) Dec 24 13:39:05 DEBUG[12075] chan_sip.c: Header 4: From: "Asterisk";tag=as1e95841b (61) Dec 24 13:39:05 DEBUG[12075] chan_sip.c: Header 5: Call-ID: 174331503c8666a15333539d595d128f@192.168.254.250 (57) Dec 24 13:39:05 DEBUG[12075] chan_sip.c: Header 6: CSeq: 102 NOTIFY (16) Dec 24 13:39:05 DEBUG[12075] chan_sip.c: Header 7: User-Agent: eyeBeam release 3010n stamp 19039 (45) Dec 24 13:39:05 DEBUG[12075] chan_sip.c: Header 8: Content-Length: 0 (17) Dec 24 13:39:05 DEBUG[12075] chan_sip.c: Header 9: (0) Dec 24 13:39:05 DEBUG[12075] chan_sip.c: = Found Their Call ID: 174331503c8666a15333539d595d128f@192.168.254.250 Their Tag Our tag: as1e95841b Dec 24 13:39:05 DEBUG[12075] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #49 Dec 24 13:39:05 DEBUG[12075] chan_sip.c: Stopping retransmission on '174331503c8666a15333539d595d128f@192.168.254.250' of Request 102: Match Found Dec 24 13:39:05 DEBUG[12075] chan_sip.c: Auto destroying call '7ec44bce3ac5a65a1e70384c6dbe2282@192.168.254.250' Dec 24 13:39:05 DEBUG[12075] chan_sip.c: Auto destroying call '704a6f430de6c4940708d0313b20cf31@192.168.254.250' Dec 24 13:39:05 DEBUG[12075] chan_sip.c: Auto destroying call '06e58d0f318a0d3f7c0269614a478651@192.168.254.250' Dec 24 13:39:09 DEBUG[12075] chan_sip.c: Header 0: (0) Dec 24 13:39:10 DEBUG[12075] chan_sip.c: Header 0: INVITE sip:2600@192.168.254.250;user=phone SIP/2.0 (50) Dec 24 13:39:10 DEBUG[12075] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.254.252:5060;branch=z9hG4bK2853007a132E9BD (67) Dec 24 13:39:10 DEBUG[12075] chan_sip.c: Header 2: From: "Kai-Uwe Jensen" ;tag=E4DDD358-75027071 (74) Dec 24 13:39:10 DEBUG[12075] chan_sip.c: Header 3: To: (41) Dec 24 13:39:10 DEBUG[12075] chan_sip.c: Header 4: CSeq: 1 INVITE (14) Dec 24 13:39:10 DEBUG[12075] chan_sip.c: Header 5: Call-ID: ecf5e574-b09a8c3e-3d378bcf@192.168.254.252 (51) Dec 24 13:39:10 DEBUG[12075] chan_sip.c: Header 6: Contact: (43) Dec 24 13:39:10 DEBUG[12075] chan_sip.c: Header 7: Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER (96) Dec 24 13:39:10 DEBUG[12075] chan_sip.c: Header 8: User-Agent: PolycomSoundPointIP-SPIP_500-UA/1.6.2.0041 (54) Dec 24 13:39:10 DEBUG[12075] chan_sip.c: Header 9: Supported: 100rel,replace (25) Dec 24 13:39:10 DEBUG[12075] chan_sip.c: Header 10: Allow-Events: talk,hold,conference (34) Dec 24 13:39:10 DEBUG[12075] chan_sip.c: Header 11: Max-Forwards: 70 (16) Dec 24 13:39:10 DEBUG[12075] chan_sip.c: Header 12: Content-Type: application/sdp (29) Dec 24 13:39:10 DEBUG[12075] chan_sip.c: Header 13: Content-Length: 243 (19) Dec 24 13:39:10 DEBUG[12075] chan_sip.c: Header 14: (0) Dec 24 13:39:10 DEBUG[12075] chan_sip.c: Line: v=0 (3) Dec 24 13:39:10 DEBUG[12075] chan_sip.c: Line: o=- 1135456524 1135456524 IN IP4 192.168.254.252 (48) Dec 24 13:39:10 DEBUG[12075] chan_sip.c: Line: s=Polycom IP Phone (18) Dec 24 13:39:10 DEBUG[12075] chan_sip.c: Line: c=IN IP4 192.168.254.252 (24) Dec 24 13:39:10 DEBUG[12075] chan_sip.c: Line: t=0 0 (5) Dec 24 13:39:10 DEBUG[12075] chan_sip.c: Line: m=audio 2226 RTP/AVP 0 8 18 101 (31) Dec 24 13:39:10 DEBUG[12075] chan_sip.c: Line: a=rtpmap:0 PCMU/8000 (20) Dec 24 13:39:10 DEBUG[12075] chan_sip.c: Line: a=rtpmap:8 PCMA/8000 (20) Dec 24 13:39:10 DEBUG[12075] chan_sip.c: Line: a=rtpmap:18 G729/8000 (21) Dec 24 13:39:10 DEBUG[12075] chan_sip.c: Line: a=rtpmap:101 telephone-event/8000 (33) Dec 24 13:39:10 DEBUG[12075] chan_sip.c: = No match Their Call ID: 1d3f56532d28e464@TUFETUFOMi5hbWVyaWNhcy5ocHFjb3JwLm5ldA.. Their Tag 6b1c673d Our tag: as58010d8c Dec 24 13:39:10 DEBUG[12075] acl.c: ##### Testing 192.168.254.252 with 192.168.254.0 Dec 24 13:39:10 DEBUG[12075] chan_sip.c: Allocating new SIP dialog for ecf5e574-b09a8c3e-3d378bcf@192.168.254.252 - INVITE (With RTP) Dec 24 13:39:10 DEBUG[12075] chan_sip.c: **** Received INVITE (5) - Command in SIP INVITE Dec 24 13:39:10 DEBUG[12075] chan_sip.c: Begin: parsing SIP "Supported: 100rel,replace" Dec 24 13:39:10 DEBUG[12075] chan_sip.c: Found SIP option: -100rel- Dec 24 13:39:10 DEBUG[12075] chan_sip.c: Matched SIP option: 100rel Dec 24 13:39:10 DEBUG[12075] chan_sip.c: Found SIP option: -replace- Dec 24 13:39:10 DEBUG[12075] chan_sip.c: Found no match for SIP option: replace (Please file bug report!) Dec 24 13:39:10 DEBUG[12075] chan_sip.c: * SIP extension value: 2 for call ecf5e574-b09a8c3e-3d378bcf@192.168.254.252 Dec 24 13:39:10 DEBUG[12075] chan_sip.c: Setting NAT on RTP to 0 Dec 24 13:39:10 DEBUG[12075] chan_sip.c: Setting NAT on VRTP to 0 Dec 24 13:39:10 DEBUG[12075] chan_sip.c: *** SIP TIMER: Initalizing retransmit timer on packet: Id #51 Dec 24 13:39:10 DEBUG[12075] chan_sip.c: Header 0: ACK sip:2600@192.168.254.250 SIP/2.0 (36) Dec 24 13:39:10 DEBUG[12075] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.254.252:5060;branch=z9hG4bK2853007a132E9BD (67) Dec 24 13:39:10 DEBUG[12075] chan_sip.c: Header 2: From: "Kai-Uwe Jensen" ;tag=E4DDD358-75027071 (74) Dec 24 13:39:10 DEBUG[12075] chan_sip.c: Header 3: To: ;tag=as445c1d14 (56) Dec 24 13:39:10 DEBUG[12075] chan_sip.c: Header 4: CSeq: 1 ACK (11) Dec 24 13:39:10 DEBUG[12075] chan_sip.c: Header 5: Call-ID: ecf5e574-b09a8c3e-3d378bcf@192.168.254.252 (51) Dec 24 13:39:10 DEBUG[12075] chan_sip.c: Header 6: Contact: (43) Dec 24 13:39:10 DEBUG[12075] chan_sip.c: Header 7: Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER (96) Dec 24 13:39:10 DEBUG[12075] chan_sip.c: Header 8: User-Agent: PolycomSoundPointIP-SPIP_500-UA/1.6.2.0041 (54) Dec 24 13:39:10 DEBUG[12075] chan_sip.c: Header 9: Max-Forwards: 70 (16) Dec 24 13:39:10 DEBUG[12075] chan_sip.c: Header 10: Content-Length: 0 (17) Dec 24 13:39:10 DEBUG[12075] chan_sip.c: Header 11: (0) Dec 24 13:39:10 DEBUG[12075] chan_sip.c: = Found Their Call ID: ecf5e574-b09a8c3e-3d378bcf@192.168.254.252 Their Tag E4DDD358-75027071 Our tag: as445c1d14 Dec 24 13:39:10 DEBUG[12075] chan_sip.c: **** Received ACK (6) - Command in SIP ACK Dec 24 13:39:10 DEBUG[12075] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #51 Dec 24 13:39:10 DEBUG[12075] chan_sip.c: Stopping retransmission on 'ecf5e574-b09a8c3e-3d378bcf@192.168.254.252' of Response 1: Match Found Dec 24 13:39:10 DEBUG[12075] chan_sip.c: Header 0: INVITE sip:2600@192.168.254.250;user=phone SIP/2.0 (50) Dec 24 13:39:10 DEBUG[12075] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.254.252:5060;branch=z9hG4bKd6213c23808A0D42 (68) Dec 24 13:39:10 DEBUG[12075] chan_sip.c: Header 2: From: "Kai-Uwe Jensen" ;tag=E4DDD358-75027071 (74) Dec 24 13:39:10 DEBUG[12075] chan_sip.c: Header 3: To: (41) Dec 24 13:39:10 DEBUG[12075] chan_sip.c: Header 4: CSeq: 2 INVITE (14) Dec 24 13:39:10 DEBUG[12075] chan_sip.c: Header 5: Call-ID: ecf5e574-b09a8c3e-3d378bcf@192.168.254.252 (51) Dec 24 13:39:10 DEBUG[12075] chan_sip.c: Header 6: Contact: (43) Dec 24 13:39:10 DEBUG[12075] chan_sip.c: Header 7: Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER (96) Dec 24 13:39:10 DEBUG[12075] chan_sip.c: Header 8: User-Agent: PolycomSoundPointIP-SPIP_500-UA/1.6.2.0041 (54) Dec 24 13:39:10 DEBUG[12075] chan_sip.c: Header 9: Supported: 100rel,replace (25) Dec 24 13:39:10 DEBUG[12075] chan_sip.c: Header 10: Allow-Events: talk,hold,conference (34) Dec 24 13:39:10 DEBUG[12075] chan_sip.c: Header 11: Proxy-Authorization: Digest username="ext2006", realm="asterisk", nonce="7ad42d8c", uri="sip:2600@192.168.254.250;user=phone", response="004c334c04e2d2af1498025dd3620875", algorithm=MD5 (185) Dec 24 13:39:10 DEBUG[12075] chan_sip.c: Header 12: Max-Forwards: 70 (16) Dec 24 13:39:10 DEBUG[12075] chan_sip.c: Header 13: Content-Type: application/sdp (29) Dec 24 13:39:10 DEBUG[12075] chan_sip.c: Header 14: Content-Length: 243 (19) Dec 24 13:39:10 DEBUG[12075] chan_sip.c: Header 15: (0) Dec 24 13:39:10 DEBUG[12075] chan_sip.c: Line: v=0 (3) Dec 24 13:39:10 DEBUG[12075] chan_sip.c: Line: o=- 1135456524 1135456524 IN IP4 192.168.254.252 (48) Dec 24 13:39:10 DEBUG[12075] chan_sip.c: Line: s=Polycom IP Phone (18) Dec 24 13:39:10 DEBUG[12075] chan_sip.c: Line: c=IN IP4 192.168.254.252 (24) Dec 24 13:39:10 DEBUG[12075] chan_sip.c: Line: t=0 0 (5) Dec 24 13:39:10 DEBUG[12075] chan_sip.c: Line: m=audio 2226 RTP/AVP 0 8 18 101 (31) Dec 24 13:39:10 DEBUG[12075] chan_sip.c: Line: a=rtpmap:0 PCMU/8000 (20) Dec 24 13:39:10 DEBUG[12075] chan_sip.c: Line: a=rtpmap:8 PCMA/8000 (20) Dec 24 13:39:10 DEBUG[12075] chan_sip.c: Line: a=rtpmap:18 G729/8000 (21) Dec 24 13:39:10 DEBUG[12075] chan_sip.c: Line: a=rtpmap:101 telephone-event/8000 (33) Dec 24 13:39:10 DEBUG[12075] chan_sip.c: = Found Their Call ID: ecf5e574-b09a8c3e-3d378bcf@192.168.254.252 Their Tag E4DDD358-75027071 Our tag: as445c1d14 Dec 24 13:39:10 DEBUG[12075] chan_sip.c: **** Received INVITE (5) - Command in SIP INVITE Dec 24 13:39:10 DEBUG[12075] chan_sip.c: Setting NAT on RTP to 0 Dec 24 13:39:10 DEBUG[12075] chan_sip.c: Setting NAT on VRTP to 0 Dec 24 13:39:10 DEBUG[12075] chan_sip.c: Peer audio RTP is at port 192.168.254.252:2226 Dec 24 13:39:10 DEBUG[12075] chan_sip.c: Peer video RTP is at port 192.168.254.252:65535 Dec 24 13:39:10 DEBUG[12075] chan_sip.c: Checking SIP call limits for device ext2006 Dec 24 13:39:10 DEBUG[12075] chan_sip.c: Updating call counter for incoming call Dec 24 13:39:10 DEBUG[12075] chan_sip.c: build_route: Contact hop: Dec 24 13:39:10 DEBUG[12072] chan_sip.c: Checking device state for peer ext2006 Dec 24 13:39:10 DEBUG[12072] devicestate.c: Changing state for SIP/ext2006 - state 2 (In use) Dec 24 13:39:10 DEBUG[12072] chan_sip.c: Checking device state for peer ext2006 Dec 24 13:39:10 DEBUG[12072] chan_sip.c: Checking device state for peer ext2006 Dec 24 13:39:10 DEBUG[12085] pbx.c: Launching 'Goto' Dec 24 13:39:10 DEBUG[12085] pbx.c: Launching 'Answer' Dec 24 13:39:10 DEBUG[12072] chan_sip.c: Checking device state for peer ext2006 Dec 24 13:39:10 DEBUG[12072] channel.c: Avoiding initial deadlock for 'SIP/ext2006-c324' Dec 24 13:39:10 DEBUG[12085] chan_sip.c: sip_answer(SIP/ext2006-c324) Dec 24 13:39:10 DEBUG[12085] chan_sip.c: *** SIP TIMER: Initalizing retransmit timer on packet: Id #53 Dec 24 13:39:10 DEBUG[12085] pbx.c: Launching 'Wait' Dec 24 13:39:10 DEBUG[12072] devicestate.c: Changing state for SIP/ext2006 - state 2 (In use) Dec 24 13:39:10 DEBUG[12072] chan_sip.c: Checking device state for peer ext2006 Dec 24 13:39:10 DEBUG[12072] chan_sip.c: Checking device state for peer ext2006 Dec 24 13:39:10 DEBUG[12086] app_queue.c: Device 'SIP/ext2006' changed to state '2' (In use) Dec 24 13:39:10 DEBUG[12087] app_queue.c: Device 'SIP/ext2006' changed to state '2' (In use) Dec 24 13:39:10 DEBUG[12075] chan_sip.c: Header 0: ACK sip:2600@192.168.254.250 SIP/2.0 (36) Dec 24 13:39:10 DEBUG[12075] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.254.252:5060;branch=z9hG4bK4a84a786FB8DD299 (68) Dec 24 13:39:10 DEBUG[12075] chan_sip.c: Header 2: From: "Kai-Uwe Jensen" ;tag=E4DDD358-75027071 (74) Dec 24 13:39:10 DEBUG[12075] chan_sip.c: Header 3: To: ;tag=as219a39e0 (56) Dec 24 13:39:10 DEBUG[12075] chan_sip.c: Header 4: CSeq: 2 ACK (11) Dec 24 13:39:10 DEBUG[12075] chan_sip.c: Header 5: Call-ID: ecf5e574-b09a8c3e-3d378bcf@192.168.254.252 (51) Dec 24 13:39:10 DEBUG[12075] chan_sip.c: Header 6: Contact: (43) Dec 24 13:39:10 DEBUG[12075] chan_sip.c: Header 7: Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER (96) Dec 24 13:39:10 DEBUG[12075] chan_sip.c: Header 8: User-Agent: PolycomSoundPointIP-SPIP_500-UA/1.6.2.0041 (54) Dec 24 13:39:10 DEBUG[12075] chan_sip.c: Header 9: Max-Forwards: 70 (16) Dec 24 13:39:10 DEBUG[12075] chan_sip.c: Header 10: Content-Length: 0 (17) Dec 24 13:39:10 DEBUG[12075] chan_sip.c: Header 11: (0) Dec 24 13:39:10 DEBUG[12075] chan_sip.c: = Found Their Call ID: ecf5e574-b09a8c3e-3d378bcf@192.168.254.252 Their Tag E4DDD358-75027071 Our tag: as219a39e0 Dec 24 13:39:10 DEBUG[12075] chan_sip.c: **** Received ACK (6) - Command in SIP ACK Dec 24 13:39:10 DEBUG[12075] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #53 Dec 24 13:39:10 DEBUG[12075] chan_sip.c: Stopping retransmission on 'ecf5e574-b09a8c3e-3d378bcf@192.168.254.252' of Response 2: Match Found Dec 24 13:39:11 DEBUG[12085] pbx.c: Launching 'BackGround' Dec 24 13:39:11 DEBUG[12085] channel.c: Set channel SIP/ext2006-c324 to write format gsm Dec 24 13:39:11 DEBUG[12085] rtp.c: Ooh, format changed from unknown to ulaw Dec 24 13:39:11 DEBUG[12085] channel.c: Scheduling timer at 160 sample intervals Dec 24 13:39:13 DEBUG[12085] rtp.c: Sending dtmf: 56 (8), at 192.168.254.252 Dec 24 13:39:13 DEBUG[12085] channel.c: Scheduling timer at 0 sample intervals Dec 24 13:39:13 DEBUG[12085] channel.c: Set channel SIP/ext2006-c324 to write format ulaw Dec 24 13:39:13 DEBUG[12085] pbx.c: Oooh, got something to jump out with ('8')! Dec 24 13:39:13 DEBUG[12085] rtp.c: Sending dtmf: 57 (9), at 192.168.254.252 Dec 24 13:39:13 DEBUG[12085] rtp.c: Sending dtmf: 56 (8), at 192.168.254.252 Dec 24 13:39:14 DEBUG[12085] rtp.c: Sending dtmf: 55 (7), at 192.168.254.252 Dec 24 13:39:14 DEBUG[12085] rtp.c: Sending dtmf: 55 (7), at 192.168.254.252 Dec 24 13:39:14 DEBUG[12085] rtp.c: Sending dtmf: 54 (6), at 192.168.254.252 Dec 24 13:39:14 DEBUG[12085] rtp.c: Sending dtmf: 50 (2), at 192.168.254.252 Dec 24 13:39:15 DEBUG[12085] rtp.c: Sending dtmf: 35 (#), at 192.168.254.252 Dec 24 13:39:15 DEBUG[12085] pbx.c: Launching 'Goto' Dec 24 13:39:15 DEBUG[12085] pbx.c: Launching 'Wait' Dec 24 13:39:16 DEBUG[12075] chan_sip.c: Auto destroying call '1d3f56532d28e464@TUFETUFOMi5hbWVyaWNhcy5ocHFjb3JwLm5ldA..' Dec 24 13:39:16 DEBUG[12085] pbx.c: Launching 'MeetMe' Dec 24 13:39:16 DEBUG[12085] config.c: Parsing /etc/asterisk/meetme.conf Dec 24 13:39:16 DEBUG[12085] chan_zap.c: Using channel -2 Dec 24 13:39:16 DEBUG[12085] channel.c: Set channel Zap/pseudo-476194946 to read format slin Dec 24 13:39:16 DEBUG[12085] channel.c: Set channel Zap/pseudo-476194946 to write format slin Dec 24 13:39:16 DEBUG[12085] channel.c: Set channel SIP/ext2006-c324 to write format gsm Dec 24 13:39:16 DEBUG[12085] rtp.c: Difference is 25240, ms is 3175 Dec 24 13:39:16 DEBUG[12085] channel.c: Scheduling timer at 160 sample intervals Dec 24 13:39:16 DEBUG[12072] devicestate.c: Changing state for Zap/pseudo - state 2 (In use) Dec 24 13:39:16 DEBUG[12089] app_queue.c: Device 'Zap/pseudo' changed to state '2' (In use) Dec 24 13:39:18 DEBUG[12075] chan_sip.c: Header 0: (0) Dec 24 13:39:19 DEBUG[12085] channel.c: Scheduling timer at 0 sample intervals Dec 24 13:39:19 DEBUG[12085] channel.c: Scheduling timer at 0 sample intervals Dec 24 13:39:19 DEBUG[12085] channel.c: Set channel SIP/ext2006-c324 to write format ulaw Dec 24 13:39:19 DEBUG[12085] channel.c: Set channel SIP/ext2006-c324 to write format slin Dec 24 13:39:19 DEBUG[12085] channel.c: Set channel SIP/ext2006-c324 to read format slin Dec 24 13:39:19 DEBUG[12085] app_meetme.c: Placed channel SIP/ext2006-c324 in ZAP conf 1023 Dec 24 13:39:19 DEBUG[12085] channel.c: Set channel SIP/ext2006-c324 to write format slin Dec 24 13:39:19 DEBUG[12085] channel.c: Scheduling timer at 160 sample intervals Dec 24 13:39:19 DEBUG[12085] channel.c: Generator got voice, switching to phase locked mode Dec 24 13:39:19 DEBUG[12085] channel.c: Scheduling timer at 0 sample intervals Dec 24 13:39:19 DEBUG[12085] channel.c: Set channel SIP/ext2006-c324 to write format slin Dec 24 13:39:19 DEBUG[12085] channel.c: Set channel SIP/ext2006-c324 to write format slin Dec 24 13:39:19 DEBUG[12085] res_musiconhold.c: SIP/ext2006-c324 Opened file 0 '/var/lib/asterisk/mohmp3/Hold Music' Dec 24 13:39:20 DEBUG[12085] rtp.c: Got RTCP report of 92 bytes Dec 24 13:39:20 DEBUG[12085] app_meetme.c: Got unrecognized frame on channel SIP/ext2006-c324, f->frametype=5,f->subclass=0 Dec 24 13:39:23 DEBUG[12077] chan_iax2.c: Allocate call number Dec 24 13:39:23 DEBUG[12077] chan_iax2.c: Registration created on call 2 Dec 24 13:39:24 DEBUG[12075] chan_sip.c: Header 0: INVITE sip:2600@cojensen.net SIP/2.0 (36) Dec 24 13:39:24 DEBUG[12075] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.254.60:8917;branch=z9hG4bK-d87543-df09b343d8437430-1--d87543-;rport (92) Dec 24 13:39:24 DEBUG[12075] chan_sip.c: Header 2: Max-Forwards: 70 (16) Dec 24 13:39:24 DEBUG[12075] chan_sip.c: Header 3: Contact: (42) Dec 24 13:39:24 DEBUG[12075] chan_sip.c: Header 4: To: (27) Dec 24 13:39:24 DEBUG[12075] chan_sip.c: Header 5: From: "Kai-Uwe Jensen";tag=a5645b0a (61) Dec 24 13:39:24 DEBUG[12075] chan_sip.c: Header 6: Call-ID: cf6b472bf7571c6e@TUFETUFOMi5hbWVyaWNhcy5ocHFjb3JwLm5ldA.. (66) Dec 24 13:39:24 DEBUG[12075] chan_sip.c: Header 7: CSeq: 1 INVITE (14) Dec 24 13:39:24 DEBUG[12075] chan_sip.c: Header 8: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO (81) Dec 24 13:39:24 DEBUG[12075] chan_sip.c: Header 9: Content-Type: application/sdp (29) Dec 24 13:39:24 DEBUG[12075] chan_sip.c: Header 10: Supported: eventlist (20) Dec 24 13:39:24 DEBUG[12075] chan_sip.c: Header 11: User-Agent: eyeBeam release 3010n stamp 19039 (45) Dec 24 13:39:24 DEBUG[12075] chan_sip.c: Header 12: Content-Length: 336 (19) Dec 24 13:39:24 DEBUG[12075] chan_sip.c: Header 13: (0) Dec 24 13:39:24 DEBUG[12075] chan_sip.c: Line: v=0 (3) Dec 24 13:39:24 DEBUG[12075] chan_sip.c: Line: o=- 258245085 258245130 IN IP4 192.168.254.60 (45) Dec 24 13:39:24 DEBUG[12075] chan_sip.c: Line: s=eyeBeam (9) Dec 24 13:39:24 DEBUG[12075] chan_sip.c: Line: c=IN IP4 192.168.254.60 (23) Dec 24 13:39:24 DEBUG[12075] chan_sip.c: Line: t=0 0 (5) Dec 24 13:39:24 DEBUG[12075] chan_sip.c: Line: m=audio 9080 RTP/AVP 0 18 3 97 101 (34) Dec 24 13:39:24 DEBUG[12075] chan_sip.c: Line: a=alt:1 1 : 7B7E75E3 0000007D 192.168.254.60 9080 (49) Dec 24 13:39:24 DEBUG[12075] chan_sip.c: Line: a=fmtp:101 0-15 (15) Dec 24 13:39:24 DEBUG[12075] chan_sip.c: Line: a=rtpmap:0 pcmu/8000 (20) Dec 24 13:39:24 DEBUG[12075] chan_sip.c: Line: a=rtpmap:18 g729/8000 (21) Dec 24 13:39:24 DEBUG[12075] chan_sip.c: Line: a=rtpmap:3 gsm/8000 (19) Dec 24 13:39:24 DEBUG[12075] chan_sip.c: Line: a=rtpmap:97 speex/8000 (22) Dec 24 13:39:24 DEBUG[12075] chan_sip.c: Line: a=rtpmap:101 telephone-event/8000 (33) Dec 24 13:39:24 DEBUG[12075] chan_sip.c: Line: a=sendrecv (10) Dec 24 13:39:24 DEBUG[12075] chan_sip.c: = No match Their Call ID: ecf5e574-b09a8c3e-3d378bcf@192.168.254.252 Their Tag E4DDD358-75027071 Our tag: as219a39e0 Dec 24 13:39:24 DEBUG[12075] acl.c: ##### Testing 192.168.254.1 with 192.168.254.0 Dec 24 13:39:24 DEBUG[12075] chan_sip.c: Allocating new SIP dialog for cf6b472bf7571c6e@TUFETUFOMi5hbWVyaWNhcy5ocHFjb3JwLm5ldA.. - INVITE (With RTP) Dec 24 13:39:24 DEBUG[12075] chan_sip.c: **** Received INVITE (5) - Command in SIP INVITE Dec 24 13:39:24 DEBUG[12075] chan_sip.c: Begin: parsing SIP "Supported: eventlist" Dec 24 13:39:24 DEBUG[12075] chan_sip.c: Found SIP option: -eventlist- Dec 24 13:39:24 DEBUG[12075] chan_sip.c: Matched SIP option: eventlist Dec 24 13:39:24 DEBUG[12075] chan_sip.c: * SIP extension value: 2048 for call cf6b472bf7571c6e@TUFETUFOMi5hbWVyaWNhcy5ocHFjb3JwLm5ldA.. Dec 24 13:39:24 DEBUG[12075] chan_sip.c: Setting NAT on RTP to 524288 Dec 24 13:39:24 DEBUG[12075] chan_sip.c: Setting NAT on VRTP to 524288 Dec 24 13:39:24 DEBUG[12075] chan_sip.c: *** SIP TIMER: Initalizing retransmit timer on packet: Id #54 Dec 24 13:39:24 DEBUG[12075] chan_sip.c: Header 0: ACK sip:2600@cojensen.net SIP/2.0 (33) Dec 24 13:39:24 DEBUG[12075] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.254.60:8917;branch=z9hG4bK-d87543-df09b343d8437430-1--d87543-;rport (92) Dec 24 13:39:24 DEBUG[12075] chan_sip.c: Header 2: To: ;tag=as5393dad8 (42) Dec 24 13:39:24 DEBUG[12075] chan_sip.c: Header 3: From: "Kai-Uwe Jensen";tag=a5645b0a (61) Dec 24 13:39:24 DEBUG[12075] chan_sip.c: Header 4: Call-ID: cf6b472bf7571c6e@TUFETUFOMi5hbWVyaWNhcy5ocHFjb3JwLm5ldA.. (66) Dec 24 13:39:24 DEBUG[12075] chan_sip.c: Header 5: CSeq: 1 ACK (11) Dec 24 13:39:24 DEBUG[12075] chan_sip.c: Header 6: Content-Length: 0 (17) Dec 24 13:39:24 DEBUG[12075] chan_sip.c: Header 7: (0) Dec 24 13:39:24 DEBUG[12075] chan_sip.c: = Found Their Call ID: cf6b472bf7571c6e@TUFETUFOMi5hbWVyaWNhcy5ocHFjb3JwLm5ldA.. Their Tag a5645b0a Our tag: as5393dad8 Dec 24 13:39:24 DEBUG[12075] chan_sip.c: **** Received ACK (6) - Command in SIP ACK Dec 24 13:39:24 DEBUG[12075] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #54 Dec 24 13:39:24 DEBUG[12075] chan_sip.c: Stopping retransmission on 'cf6b472bf7571c6e@TUFETUFOMi5hbWVyaWNhcy5ocHFjb3JwLm5ldA..' of Response 1: Match Found Dec 24 13:39:24 DEBUG[12075] chan_sip.c: Header 0: INVITE sip:2600@cojensen.net SIP/2.0 (36) Dec 24 13:39:24 DEBUG[12075] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.254.60:8917;branch=z9hG4bK-d87543-674fb83cb5299930-1--d87543-;rport (92) Dec 24 13:39:24 DEBUG[12075] chan_sip.c: Header 2: Max-Forwards: 70 (16) Dec 24 13:39:24 DEBUG[12075] chan_sip.c: Header 3: Contact: (42) Dec 24 13:39:24 DEBUG[12075] chan_sip.c: Header 4: To: (27) Dec 24 13:39:24 DEBUG[12075] chan_sip.c: Header 5: From: "Kai-Uwe Jensen";tag=a5645b0a (61) Dec 24 13:39:24 DEBUG[12075] chan_sip.c: Header 6: Call-ID: cf6b472bf7571c6e@TUFETUFOMi5hbWVyaWNhcy5ocHFjb3JwLm5ldA.. (66) Dec 24 13:39:24 DEBUG[12075] chan_sip.c: Header 7: CSeq: 2 INVITE (14) Dec 24 13:39:24 DEBUG[12075] chan_sip.c: Header 8: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO (81) Dec 24 13:39:24 DEBUG[12075] chan_sip.c: Header 9: Content-Type: application/sdp (29) Dec 24 13:39:24 DEBUG[12075] chan_sip.c: Header 10: Proxy-Authorization: Digest username="ext2003",realm="asterisk",nonce="147b6f01",uri="sip:2600@cojensen.net",response="769c34158eb53d42735e8780dcc113ba",algorithm=MD5 (166) Dec 24 13:39:24 DEBUG[12075] chan_sip.c: Header 11: Supported: eventlist (20) Dec 24 13:39:24 DEBUG[12075] chan_sip.c: Header 12: User-Agent: eyeBeam release 3010n stamp 19039 (45) Dec 24 13:39:24 DEBUG[12075] chan_sip.c: Header 13: Content-Length: 336 (19) Dec 24 13:39:24 DEBUG[12075] chan_sip.c: Header 14: (0) Dec 24 13:39:24 DEBUG[12075] chan_sip.c: Line: v=0 (3) Dec 24 13:39:24 DEBUG[12075] chan_sip.c: Line: o=- 258245085 258245130 IN IP4 192.168.254.60 (45) Dec 24 13:39:24 DEBUG[12075] chan_sip.c: Line: s=eyeBeam (9) Dec 24 13:39:24 DEBUG[12075] chan_sip.c: Line: c=IN IP4 192.168.254.60 (23) Dec 24 13:39:24 DEBUG[12075] chan_sip.c: Line: t=0 0 (5) Dec 24 13:39:24 DEBUG[12075] chan_sip.c: Line: m=audio 9080 RTP/AVP 0 18 3 97 101 (34) Dec 24 13:39:24 DEBUG[12075] chan_sip.c: Line: a=alt:1 1 : 7B7E75E3 0000007D 192.168.254.60 9080 (49) Dec 24 13:39:24 DEBUG[12075] chan_sip.c: Line: a=fmtp:101 0-15 (15) Dec 24 13:39:24 DEBUG[12075] chan_sip.c: Line: a=rtpmap:0 pcmu/8000 (20) Dec 24 13:39:24 DEBUG[12075] chan_sip.c: Line: a=rtpmap:18 g729/8000 (21) Dec 24 13:39:24 DEBUG[12075] chan_sip.c: Line: a=rtpmap:3 gsm/8000 (19) Dec 24 13:39:24 DEBUG[12075] chan_sip.c: Line: a=rtpmap:97 speex/8000 (22) Dec 24 13:39:24 DEBUG[12075] chan_sip.c: Line: a=rtpmap:101 telephone-event/8000 (33) Dec 24 13:39:24 DEBUG[12075] chan_sip.c: Line: a=sendrecv (10) Dec 24 13:39:24 DEBUG[12075] chan_sip.c: = Found Their Call ID: cf6b472bf7571c6e@TUFETUFOMi5hbWVyaWNhcy5ocHFjb3JwLm5ldA.. Their Tag a5645b0a Our tag: as5393dad8 Dec 24 13:39:24 DEBUG[12075] chan_sip.c: **** Received INVITE (5) - Command in SIP INVITE Dec 24 13:39:24 DEBUG[12075] chan_sip.c: Setting NAT on RTP to 524288 Dec 24 13:39:24 DEBUG[12075] chan_sip.c: Setting NAT on VRTP to 524288 Dec 24 13:39:24 DEBUG[12075] chan_sip.c: Peer audio RTP is at port 192.168.254.60:9080 Dec 24 13:39:24 DEBUG[12075] chan_sip.c: Peer video RTP is at port 192.168.254.60:65535 Dec 24 13:39:24 DEBUG[12075] chan_sip.c: Checking SIP call limits for device ext2003 Dec 24 13:39:24 DEBUG[12075] chan_sip.c: Updating call counter for incoming call Dec 24 13:39:24 DEBUG[12075] chan_sip.c: build_route: Contact hop: Dec 24 13:39:24 DEBUG[12072] chan_sip.c: Checking device state for peer ext2003 Dec 24 13:39:24 DEBUG[12072] devicestate.c: Changing state for SIP/ext2003 - state 2 (In use) Dec 24 13:39:24 DEBUG[12072] chan_sip.c: Checking device state for peer ext2003 Dec 24 13:39:24 DEBUG[12072] chan_sip.c: Checking device state for peer ext2003 Dec 24 13:39:24 DEBUG[12090] pbx.c: Launching 'NoOp' Dec 24 13:39:24 DEBUG[12090] pbx.c: Launching 'NoOp' Dec 24 13:39:24 DEBUG[12090] pbx.c: Expression result is '0' Dec 24 13:39:24 DEBUG[12090] pbx.c: Launching 'GotoIf' Dec 24 13:39:24 DEBUG[12090] pbx.c: Expression result is '0' Dec 24 13:39:24 DEBUG[12090] pbx.c: Launching 'GotoIf' Dec 24 13:39:24 DEBUG[12090] pbx.c: Expression result is '1' Dec 24 13:39:24 DEBUG[12090] pbx.c: Launching 'GotoIf' Dec 24 13:39:24 DEBUG[12090] pbx.c: Launching 'Goto' Dec 24 13:39:24 DEBUG[12090] pbx.c: Launching 'Answer' Dec 24 13:39:24 DEBUG[12091] app_queue.c: Device 'SIP/ext2003' changed to state '2' (In use) Dec 24 13:39:24 DEBUG[12072] chan_sip.c: Checking device state for peer ext2003 Dec 24 13:39:24 DEBUG[12072] channel.c: Avoiding initial deadlock for 'SIP/ext2003-a44b' Dec 24 13:39:24 DEBUG[12090] chan_sip.c: sip_answer(SIP/ext2003-a44b) Dec 24 13:39:24 DEBUG[12072] channel.c: Avoiding initial deadlock for 'SIP/ext2003-a44b' Dec 24 13:39:24 DEBUG[12090] chan_sip.c: *** SIP TIMER: Initalizing retransmit timer on packet: Id #56 Dec 24 13:39:24 DEBUG[12090] pbx.c: Launching 'Wait' Dec 24 13:39:24 DEBUG[12072] devicestate.c: Changing state for SIP/ext2003 - state 2 (In use) Dec 24 13:39:24 DEBUG[12072] chan_sip.c: Checking device state for peer ext2003 Dec 24 13:39:24 DEBUG[12072] chan_sip.c: Checking device state for peer ext2003 Dec 24 13:39:24 DEBUG[12092] app_queue.c: Device 'SIP/ext2003' changed to state '2' (In use) Dec 24 13:39:24 DEBUG[12090] rtp.c: Got RTCP report of 72 bytes Dec 24 13:39:24 DEBUG[12075] chan_sip.c: Header 0: ACK sip:2600@192.168.254.250 SIP/2.0 (36) Dec 24 13:39:24 DEBUG[12075] chan_sip.c: Header 1: Via: SIP/2.0/UDP 192.168.254.60:8917;branch=z9hG4bK-d87543-bf1a0d365b14433a-1--d87543-;rport (92) Dec 24 13:39:24 DEBUG[12075] chan_sip.c: Header 2: Max-Forwards: 70 (16) Dec 24 13:39:24 DEBUG[12075] chan_sip.c: Header 3: Contact: (42) Dec 24 13:39:24 DEBUG[12075] chan_sip.c: Header 4: To: ;tag=as49a86cce (42) Dec 24 13:39:24 DEBUG[12075] chan_sip.c: Header 5: From: "Kai-Uwe Jensen";tag=a5645b0a (61) Dec 24 13:39:24 DEBUG[12075] chan_sip.c: Header 6: Call-ID: cf6b472bf7571c6e@TUFETUFOMi5hbWVyaWNhcy5ocHFjb3JwLm5ldA.. (66) Dec 24 13:39:24 DEBUG[12075] chan_sip.c: Header 7: CSeq: 2 ACK (11) Dec 24 13:39:24 DEBUG[12075] chan_sip.c: Header 8: User-Agent: eyeBeam release 3010n stamp 19039 (45) Dec 24 13:39:24 DEBUG[12075] chan_sip.c: Header 9: Content-Length: 0 (17) Dec 24 13:39:24 DEBUG[12075] chan_sip.c: Header 10: (0) Dec 24 13:39:24 DEBUG[12075] chan_sip.c: = Found Their Call ID: cf6b472bf7571c6e@TUFETUFOMi5hbWVyaWNhcy5ocHFjb3JwLm5ldA.. Their Tag a5645b0a Our tag: as49a86cce Dec 24 13:39:24 DEBUG[12075] chan_sip.c: **** Received ACK (6) - Command in SIP ACK Dec 24 13:39:24 DEBUG[12075] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #56 Dec 24 13:39:24 DEBUG[12075] chan_sip.c: Stopping retransmission on 'cf6b472bf7571c6e@TUFETUFOMi5hbWVyaWNhcy5ocHFjb3JwLm5ldA..' of Response 2: Match Found Dec 24 13:39:25 DEBUG[12090] pbx.c: Launching 'BackGround' Dec 24 13:39:25 DEBUG[12090] channel.c: Set channel SIP/ext2003-a44b to write format gsm Dec 24 13:39:25 DEBUG[12090] rtp.c: Ooh, format changed from unknown to ulaw Dec 24 13:39:25 DEBUG[12090] channel.c: Scheduling timer at 160 sample intervals Dec 24 13:39:26 DEBUG[12090] rtp.c: Sending dtmf: 56 (8), at 192.168.254.60 Dec 24 13:39:26 DEBUG[12090] channel.c: Scheduling timer at 0 sample intervals Dec 24 13:39:26 DEBUG[12090] channel.c: Set channel SIP/ext2003-a44b to write format ulaw Dec 24 13:39:26 DEBUG[12090] pbx.c: Oooh, got something to jump out with ('8')! Dec 24 13:39:27 DEBUG[12090] rtp.c: Sending dtmf: 57 (9), at 192.168.254.60 Dec 24 13:39:27 DEBUG[12090] rtp.c: Sending dtmf: 56 (8), at 192.168.254.60 Dec 24 13:39:27 DEBUG[12075] chan_sip.c: Header 0: (0) Dec 24 13:39:27 DEBUG[12090] rtp.c: Sending dtmf: 55 (7), at 192.168.254.60 Dec 24 13:39:28 DEBUG[12090] rtp.c: Sending dtmf: 55 (7), at 192.168.254.60 Dec 24 13:39:29 DEBUG[12090] rtp.c: Sending dtmf: 54 (6), at 192.168.254.60 Dec 24 13:39:29 DEBUG[12090] rtp.c: Got RTCP report of 52 bytes Dec 24 13:39:29 DEBUG[12090] rtp.c: Got RTCP report of 72 bytes Dec 24 13:39:29 DEBUG[12088] chan_zap.c: DTMF digit: 6 on Zap/pseudo-476194946 ############################################################ This is where it crashed. The following records are from starting up asterisk again through safe_asterisk Dec 24 13:39:33 DEBUG[12137] cdr_addon_mysql.c: cdr_mysql: got hostname of localhost Dec 24 13:39:33 DEBUG[12137] cdr_addon_mysql.c: cdr_mysql: got port of 0 Dec 24 13:39:33 DEBUG[12137] cdr_addon_mysql.c: cdr_mysql: got user of ...... Dec 24 13:39:33 DEBUG[12137] cdr_addon_mysql.c: cdr_mysql: got dbname of asteriskcdrdb Dec 24 13:39:33 DEBUG[12137] cdr_addon_mysql.c: cdr_mysql: got password of ........ Dec 24 13:39:33 DEBUG[12137] cdr_addon_mysql.c: Successfully connected to MySQL database.