uboot*CLI> <--- SIP read from 10.10.10.23:7261 ---> INVITE sip:0123123123@172.16.16.111 SIP/2.0 Via: SIP/2.0/UDP 10.10.10.23:7261;branch=z9hG4bK-7cf2b0f5 From: fax ;tag=dd3745a828a6d77do1 To: Remote-Party-ID: fax ;screen=yes;party=calling Call-ID: 4f322544-84e21c91@192.168.20.12 CSeq: 101 INVITE Max-Forwards: 70 Contact: fax Expires: 240 User-Agent: Linksys/SPA2102-3.3.6 Content-Length: 397 Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER Supported: x-sipura Content-Type: application/sdp v=0 o=- 6057606 6057606 IN IP4 192.168.20.12 s=- c=IN IP4 10.10.10.23 t=0 0 m=audio 16406 RTP/AVP 8 0 2 4 18 96 97 98 101 a=rtpmap:8 PCMA/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:2 G726-32/8000 a=rtpmap:4 G723/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:101 telephone-event/8000 a=fmtp:101 0-15 a=ptime:30 a=sendrecv -------------> [Dec 27 18:30:43] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 0: INVITE sip:0123123123@172.16.16.111 SIP/2.0 (40) [Dec 27 18:30:43] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 1: Via: SIP/2.0/UDP 10.10.10.23:7261;branch=z9hG4bK-7cf2b0f5 (57) [Dec 27 18:30:43] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 2: From: fax ;tag=dd3745a828a6d77do1 (59) [Dec 27 18:30:43] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 3: To: (31) [Dec 27 18:30:43] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 4: Remote-Party-ID: fax ;screen=yes;party=calling (72) [Dec 27 18:30:43] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 5: Call-ID: 4f322544-84e21c91@192.168.20.12 (40) [Dec 27 18:30:43] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 6: CSeq: 101 INVITE (16) [Dec 27 18:30:43] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 7: Max-Forwards: 70 (16) [Dec 27 18:30:43] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 8: Contact: fax (45) [Dec 27 18:30:43] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 9: Expires: 240 (12) [Dec 27 18:30:43] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 10: User-Agent: Linksys/SPA2102-3.3.6 (33) [Dec 27 18:30:43] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 11: Content-Length: 397 (19) [Dec 27 18:30:43] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 12: Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER (61) [Dec 27 18:30:43] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 13: Supported: x-sipura (19) [Dec 27 18:30:43] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 14: Content-Type: application/sdp (29) [Dec 27 18:30:43] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 15: (0) [Dec 27 18:30:43] DEBUG[9953]: chan_sip.c:4559 parse_request: Line: v=0 (3) [Dec 27 18:30:43] DEBUG[9953]: chan_sip.c:4559 parse_request: Line: o=- 6057606 6057606 IN IP4 192.168.20.12 (40) [Dec 27 18:30:43] DEBUG[9953]: chan_sip.c:4559 parse_request: Line: s=- (3) [Dec 27 18:30:43] DEBUG[9953]: chan_sip.c:4559 parse_request: Line: c=IN IP4 10.10.10.23 (20) [Dec 27 18:30:43] DEBUG[9953]: chan_sip.c:4559 parse_request: Line: t=0 0 (5) [Dec 27 18:30:43] DEBUG[9953]: chan_sip.c:4559 parse_request: Line: m=audio 16406 RTP/AVP 8 0 2 4 18 96 97 98 101 (45) [Dec 27 18:30:43] DEBUG[9953]: chan_sip.c:4559 parse_request: Line: a=rtpmap:8 PCMA/8000 (20) [Dec 27 18:30:43] DEBUG[9953]: chan_sip.c:4559 parse_request: Line: a=rtpmap:0 PCMU/8000 (20) [Dec 27 18:30:43] DEBUG[9953]: chan_sip.c:4559 parse_request: Line: a=rtpmap:2 G726-32/8000 (23) [Dec 27 18:30:43] DEBUG[9953]: chan_sip.c:4559 parse_request: Line: a=rtpmap:4 G723/8000 (20) [Dec 27 18:30:43] DEBUG[9953]: chan_sip.c:4559 parse_request: Line: a=rtpmap:18 G729a/8000 (22) [Dec 27 18:30:43] DEBUG[9953]: chan_sip.c:4559 parse_request: Line: a=rtpmap:96 G726-40/8000 (24) [Dec 27 18:30:43] DEBUG[9953]: chan_sip.c:4559 parse_request: Line: a=rtpmap:97 G726-24/8000 (24) [Dec 27 18:30:43] DEBUG[9953]: chan_sip.c:4559 parse_request: Line: a=rtpmap:98 G726-16/8000 (24) [Dec 27 18:30:43] DEBUG[9953]: chan_sip.c:4559 parse_request: Line: a=rtpmap:101 telephone-event/8000 (33) [Dec 27 18:30:43] DEBUG[9953]: chan_sip.c:4559 parse_request: Line: a=fmtp:101 0-15 (15) [Dec 27 18:30:43] DEBUG[9953]: chan_sip.c:4559 parse_request: Line: a=ptime:30 (10) [Dec 27 18:30:43] DEBUG[9953]: chan_sip.c:4559 parse_request: Line: a=sendrecv (10) --- (15 headers 18 lines) --- [Dec 27 18:30:43] DEBUG[9953]: chan_sip.c:2559 do_setnat: Setting NAT on RTP to Off [Dec 27 18:30:43] DEBUG[9953]: chan_sip.c:2564 do_setnat: Setting NAT on VRTP to Off [Dec 27 18:30:43] DEBUG[9953]: chan_sip.c:2569 do_setnat: Setting NAT on UDPTL to Off [Dec 27 18:30:43] DEBUG[9953]: chan_sip.c:4264 sip_alloc: Allocating new SIP dialog for 4f322544-84e21c91@192.168.20.12 - INVITE (With RTP) [Dec 27 18:30:43] DEBUG[9953]: chan_sip.c:14453 handle_request: **** Received INVITE (5) - Command in SIP INVITE [Dec 27 18:30:43] DEBUG[9953]: chan_sip.c:1662 parse_sip_options: Begin: parsing SIP "Supported: x-sipura" [Dec 27 18:30:43] DEBUG[9953]: chan_sip.c:1670 parse_sip_options: Found SIP option: -x-sipura- [Dec 27 18:30:43] DEBUG[9953]: chan_sip.c:1682 parse_sip_options: Found private SIP option, not supported: x-sipura Sending to 10.10.10.23 : 7261 (no NAT) Using INVITE request as basis request - 4f322544-84e21c91@192.168.20.12 [Dec 27 18:30:43] DEBUG[9953]: chan_sip.c:2559 do_setnat: Setting NAT on RTP to Off [Dec 27 18:30:43] DEBUG[9953]: chan_sip.c:2564 do_setnat: Setting NAT on VRTP to Off [Dec 27 18:30:43] DEBUG[9953]: chan_sip.c:2569 do_setnat: Setting NAT on UDPTL to Off <--- Reliably Transmitting (no NAT) to 10.10.10.23:7261 ---> SIP/2.0 407 Proxy Authentication Required Via: SIP/2.0/UDP 10.10.10.23:7261;branch=z9hG4bK-7cf2b0f5;received=10.10.10.23 From: fax ;tag=dd3745a828a6d77do1 To: ;tag=as55814327 Call-ID: 4f322544-84e21c91@192.168.20.12 CSeq: 101 INVITE User-Agent: blabla.ch 1.4PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Proxy-Authenticate: Digest algorithm=MD5, realm="blabla.ch", nonce="11068d8b" Content-Length: 0 <------------> [Dec 27 18:30:43] DEBUG[9953]: chan_sip.c:1957 __sip_reliable_xmit: *** SIP TIMER: Initalizing retransmit timer on packet: Id #18 Scheduling destruction of SIP dialog '4f322544-84e21c91@192.168.20.12' in 32000 ms (Method: INVITE) Found user '123456789' uboot*CLI> <--- SIP read from 10.10.10.23:7261 ---> ACK sip:0123123123@172.16.16.111 SIP/2.0 Via: SIP/2.0/UDP 10.10.10.23:7261;branch=z9hG4bK-7cf2b0f5 From: fax ;tag=dd3745a828a6d77do1 To: ;tag=as55814327 Call-ID: 4f322544-84e21c91@192.168.20.12 CSeq: 101 ACK Max-Forwards: 70 Contact: fax User-Agent: Linksys/SPA2102-3.3.6 Content-Length: 0 <-------------> [Dec 27 18:30:43] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 0: ACK sip:0123123123@172.16.16.111 SIP/2.0 (37) [Dec 27 18:30:43] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 1: Via: SIP/2.0/UDP 10.10.10.23:7261;branch=z9hG4bK-7cf2b0f5 (57) [Dec 27 18:30:43] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 2: From: fax ;tag=dd3745a828a6d77do1 (59) [Dec 27 18:30:43] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 3: To: ;tag=as55814327 (46) [Dec 27 18:30:43] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 4: Call-ID: 4f322544-84e21c91@192.168.20.12 (40) [Dec 27 18:30:43] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 5: CSeq: 101 ACK (13) [Dec 27 18:30:43] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 6: Max-Forwards: 70 (16) [Dec 27 18:30:43] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 7: Contact: fax (45) [Dec 27 18:30:43] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 8: User-Agent: Linksys/SPA2102-3.3.6 (33) [Dec 27 18:30:43] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 9: Content-Length: 0 (17) [Dec 27 18:30:43] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 10: (0) --- (10 headers 0 lines) --- [Dec 27 18:30:43] DEBUG[9953]: chan_sip.c:14453 handle_request: **** Received ACK (6) - Command in SIP ACK [Dec 27 18:30:43] DEBUG[9953]: chan_sip.c:2061 __sip_ack: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #18 [Dec 27 18:30:43] DEBUG[9953]: chan_sip.c:2071 __sip_ack: Stopping retransmission on '4f322544-84e21c91@192.168.20.12' of Response 101: Match Not Found uboot*CLI> <--- SIP read from 10.10.10.23:7261 ---> INVITE sip:0123123123@172.16.16.111 SIP/2.0 Via: SIP/2.0/UDP 10.10.10.23:7261;branch=z9hG4bK-efc76d51 From: fax ;tag=dd3745a828a6d77do1 To: Remote-Party-ID: fax ;screen=yes;party=calling Call-ID: 4f322544-84e21c91@192.168.20.12 CSeq: 102 INVITE Max-Forwards: 70 Proxy-Authorization: Digest username="123456789",realm="blabla.ch",nonce="11068d8b",uri="sip:0123123123@172.16.16.111",algorithm=MD5,response="a6751cf192a7320df6d082091111fa37" Contact: fax Expires: 240 User-Agent: Linksys/SPA2102-3.3.6 Content-Length: 397 Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER Supported: x-sipura Content-Type: application/sdp v=0 o=- 6057606 6057606 IN IP4 192.168.20.12 s=- c=IN IP4 10.10.10.23 t=0 0 m=audio 16406 RTP/AVP 8 0 2 4 18 96 97 98 101 a=rtpmap:8 PCMA/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:2 G726-32/8000 a=rtpmap:4 G723/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:101 telephone-event/8000 a=fmtp:101 0-15 a=ptime:30 a=sendrecv <-------------> [Dec 27 18:30:43] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 0: INVITE sip:0123123123@172.16.16.111 SIP/2.0 (40) [Dec 27 18:30:43] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 1: Via: SIP/2.0/UDP 10.10.10.23:7261;branch=z9hG4bK-efc76d51 (57) [Dec 27 18:30:43] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 2: From: fax ;tag=dd3745a828a6d77do1 (59) [Dec 27 18:30:43] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 3: To: (31) [Dec 27 18:30:43] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 4: Remote-Party-ID: fax ;screen=yes;party=calling (72) [Dec 27 18:30:43] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 5: Call-ID: 4f322544-84e21c91@192.168.20.12 (40) [Dec 27 18:30:43] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 6: CSeq: 102 INVITE (16) [Dec 27 18:30:43] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 7: Max-Forwards: 70 (16) [Dec 27 18:30:43] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 8: Proxy-Authorization: Digest username="123456789",realm="blabla.ch",nonce="11068d8b",uri="sip:0123123123@172.16.16.111",algorithm=MD5,response="a6751cf192a7320df6d082091111fa37" (171) [Dec 27 18:30:43] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 9: Contact: fax (45) [Dec 27 18:30:43] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 10: Expires: 240 (12) [Dec 27 18:30:43] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 11: User-Agent: Linksys/SPA2102-3.3.6 (33) [Dec 27 18:30:43] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 12: Content-Length: 397 (19) [Dec 27 18:30:43] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 13: Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER (61) [Dec 27 18:30:43] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 14: Supported: x-sipura (19) [Dec 27 18:30:43] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 15: Content-Type: application/sdp (29) [Dec 27 18:30:43] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 16: (0) [Dec 27 18:30:43] DEBUG[9953]: chan_sip.c:4559 parse_request: Line: v=0 (3) [Dec 27 18:30:43] DEBUG[9953]: chan_sip.c:4559 parse_request: Line: o=- 6057606 6057606 IN IP4 192.168.20.12 (40) [Dec 27 18:30:43] DEBUG[9953]: chan_sip.c:4559 parse_request: Line: s=- (3) [Dec 27 18:30:43] DEBUG[9953]: chan_sip.c:4559 parse_request: Line: c=IN IP4 10.10.10.23 (20) [Dec 27 18:30:43] DEBUG[9953]: chan_sip.c:4559 parse_request: Line: t=0 0 (5) [Dec 27 18:30:43] DEBUG[9953]: chan_sip.c:4559 parse_request: Line: m=audio 16406 RTP/AVP 8 0 2 4 18 96 97 98 101 (45) [Dec 27 18:30:43] DEBUG[9953]: chan_sip.c:4559 parse_request: Line: a=rtpmap:8 PCMA/8000 (20) [Dec 27 18:30:43] DEBUG[9953]: chan_sip.c:4559 parse_request: Line: a=rtpmap:0 PCMU/8000 (20) [Dec 27 18:30:43] DEBUG[9953]: chan_sip.c:4559 parse_request: Line: a=rtpmap:2 G726-32/8000 (23) [Dec 27 18:30:43] DEBUG[9953]: chan_sip.c:4559 parse_request: Line: a=rtpmap:4 G723/8000 (20) [Dec 27 18:30:43] DEBUG[9953]: chan_sip.c:4559 parse_request: Line: a=rtpmap:18 G729a/8000 (22) [Dec 27 18:30:43] DEBUG[9953]: chan_sip.c:4559 parse_request: Line: a=rtpmap:96 G726-40/8000 (24) [Dec 27 18:30:43] DEBUG[9953]: chan_sip.c:4559 parse_request: Line: a=rtpmap:97 G726-24/8000 (24) [Dec 27 18:30:43] DEBUG[9953]: chan_sip.c:4559 parse_request: Line: a=rtpmap:98 G726-16/8000 (24) [Dec 27 18:30:43] DEBUG[9953]: chan_sip.c:4559 parse_request: Line: a=rtpmap:101 telephone-event/8000 (33) [Dec 27 18:30:43] DEBUG[9953]: chan_sip.c:4559 parse_request: Line: a=fmtp:101 0-15 (15) [Dec 27 18:30:43] DEBUG[9953]: chan_sip.c:4559 parse_request: Line: a=ptime:30 (10) [Dec 27 18:30:43] DEBUG[9953]: chan_sip.c:4559 parse_request: Line: a=sendrecv (10) --- (16 headers 18 lines) --- [Dec 27 18:30:43] DEBUG[9953]: chan_sip.c:14453 handle_request: **** Received INVITE (5) - Command in SIP INVITE [Dec 27 18:30:43] DEBUG[9953]: chan_sip.c:1662 parse_sip_options: Begin: parsing SIP "Supported: x-sipura" [Dec 27 18:30:43] DEBUG[9953]: chan_sip.c:1670 parse_sip_options: Found SIP option: -x-sipura- [Dec 27 18:30:43] DEBUG[9953]: chan_sip.c:1682 parse_sip_options: Found private SIP option, not supported: x-sipura Sending to 10.10.10.23 : 7261 (no NAT) Using INVITE request as basis request - 4f322544-84e21c91@192.168.20.12 [Dec 27 18:30:43] DEBUG[9953]: chan_sip.c:2559 do_setnat: Setting NAT on RTP to Off [Dec 27 18:30:43] DEBUG[9953]: chan_sip.c:2564 do_setnat: Setting NAT on VRTP to Off [Dec 27 18:30:43] DEBUG[9953]: chan_sip.c:2569 do_setnat: Setting NAT on UDPTL to Off Found user '123456789' Found RTP audio format 8 Found RTP audio format 0 Found RTP audio format 2 Found RTP audio format 4 Found RTP audio format 18 Found RTP audio format 96 Found RTP audio format 97 Found RTP audio format 98 Found RTP audio format 101 [Dec 27 18:30:43] DEBUG[9953]: chan_sip.c:4838 process_sdp: Peer doesn't provide T.38 UDPTL Peer audio RTP is at port 10.10.10.23:16406 Found description format PCMA for ID 8 Found description format PCMU for ID 0 Found description format G726-32 for ID 2 Found description format G723 for ID 4 Found description format G729a for ID 18 Found description format G726-40 for ID 96 Found description format G726-24 for ID 97 Found description format G726-16 for ID 98 Found description format telephone-event for ID 101 Got unsupported a:fmtp in SDP offer [Dec 27 18:30:43] DEBUG[9953]: chan_sip.c:5068 process_sdp: T38 state changed to 0 on channel Capabilities: us - 0xa (gsm|alaw), peer - audio=0xd0d (g723|ulaw|alaw|g726|g729|ilbc)/video=0x0 (nothing), combined - 0x8 (alaw) Non-codec capabilities (dtmf): us - 0x1 (telephone-event), peer - 0x1 (telephone-event), combined - 0x1 (telephone-event) Peer audio RTP is at port 10.10.10.23:16406 [Dec 27 18:30:43] DEBUG[9953]: chan_sip.c:5145 process_sdp: We're settling with these formats: 0x8 (alaw) [Dec 27 18:30:43] DEBUG[9953]: chan_sip.c:13237 handle_request_invite: Checking SIP call limits for device 123456789 [Dec 27 18:30:43] DEBUG[9953]: chan_sip.c:2978 update_call_counter: Updating call counter for incoming call Looking for 0123123123 in privileged (domain 172.16.16.111) [Dec 27 18:30:43] DEBUG[9953]: chan_sip.c:3766 sip_new: *** Our native formats are 0x8 (alaw) [Dec 27 18:30:43] DEBUG[9953]: chan_sip.c:3767 sip_new: *** Joint capabilities are 0x8 (alaw) [Dec 27 18:30:43] DEBUG[9953]: chan_sip.c:3768 sip_new: *** Our capabilities are 0xa (gsm|alaw) [Dec 27 18:30:43] DEBUG[9953]: chan_sip.c:3769 sip_new: *** AST_CODEC_CHOOSE formats are 0x8 (alaw) [Dec 27 18:30:43] DEBUG[9953]: chan_sip.c:3792 sip_new: This channel will not be able to handle video. [Dec 27 18:30:43] DEBUG[9953]: chan_sip.c:7891 build_route: build_route: Contact hop: fax list_route: hop: [Dec 27 18:30:43] DEBUG[9953]: chan_sip.c:13310 handle_request_invite: SIP/123456789-081ddcc0: New call is still down.... Trying... <--- Transmitting (no NAT) to 10.10.10.23:7261 ---> SIP/2.0 100 Trying Via: SIP/2.0/UDP 10.10.10.23:7261;branch=z9hG4bK-efc76d51;received=10.10.10.23 From: fax ;tag=dd3745a828a6d77do1 To: Call-ID: 4f322544-84e21c91@192.168.20.12 CSeq: 102 INVITE User-Agent: blabla.ch 1.4PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Contact: Content-Length: 0 <------------> [Dec 27 18:30:43] DEBUG[9953]: devicestate.c:303 __ast_device_state_changed_literal: Notification of state change to be queued on device/channel SIP/123456789-081ddcc0 [Dec 27 18:30:43] DEBUG[9930]: devicestate.c:161 ast_device_state: No provider found, checking channel drivers for SIP - 123456789 [Dec 27 18:30:43] DEBUG[9930]: chan_sip.c:15048 sip_devicestate: Checking device state for peer 123456789 [Dec 27 18:30:43] DEBUG[9930]: devicestate.c:287 do_state_change: Changing state for SIP/123456789 - state 1 (Not in use) [Dec 27 18:30:43] DEBUG[9984]: app_queue.c:546 changethread: Device 'SIP/123456789' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. [Dec 27 18:30:43] DEBUG[9983]: pbx.c:1767 pbx_extension_helper: Launching 'Dial' -- Executing [0123123123@privileged:1] Dial("SIP/123456789-081ddcc0", "SIP/0123123123@172.16.16.155") in new stack [Dec 27 18:30:43] DEBUG[9930]: devicestate.c:161 ast_device_state: No provider found, checking channel drivers for SIP - 123456789 [Dec 27 18:30:43] DEBUG[9930]: chan_sip.c:15048 sip_devicestate: Checking device state for peer 123456789 [Dec 27 18:30:43] DEBUG[9983]: chan_sip.c:15106 sip_request_call: Asked to create a SIP channel with formats: 0x8 (alaw) [Dec 27 18:30:43] DEBUG[9983]: chan_sip.c:4264 sip_alloc: Allocating new SIP dialog for (No Call-ID) - INVITE (With RTP) [Dec 27 18:30:43] DEBUG[9983]: chan_sip.c:3766 sip_new: *** Our native formats are 0x8 (alaw) [Dec 27 18:30:43] DEBUG[9983]: chan_sip.c:3767 sip_new: *** Joint capabilities are 0x0 (nothing) [Dec 27 18:30:43] DEBUG[9983]: chan_sip.c:3768 sip_new: *** Our capabilities are 0x40a (gsm|alaw|ilbc) [Dec 27 18:30:43] DEBUG[9983]: chan_sip.c:3769 sip_new: *** AST_CODEC_CHOOSE formats are 0x8 (alaw) [Dec 27 18:30:43] DEBUG[9983]: chan_sip.c:3771 sip_new: *** Our preferred formats from the incoming channel are 0x8 (alaw) [Dec 27 18:30:43] DEBUG[9983]: chan_sip.c:3792 sip_new: This channel will not be able to handle video. [Dec 27 18:30:43] DEBUG[9983]: chan_sip.c:16701 sip_set_rtp_peer: Early remote bridge setting SIP '237a442d0168be044b12b5b00c645a30@172.16.16.111' - Sending media to 10.10.10.23 [Dec 27 18:30:43] DEBUG[9983]: rtp.c:1570 ast_rtp_make_compatible: Seeded SDP of 'SIP/172.16.16.155-081e29c0' with that of 'SIP/123456789-081ddcc0' [Dec 27 18:30:43] DEBUG[9983]: channel.c:3185 ast_channel_inherit_variables: Not copying variable STACK-privileged-0123123123-1. [Dec 27 18:30:43] DEBUG[9983]: channel.c:3185 ast_channel_inherit_variables: Not copying variable SIPCALLID. [Dec 27 18:30:43] DEBUG[9983]: channel.c:3185 ast_channel_inherit_variables: Not copying variable SIPUSERAGENT. [Dec 27 18:30:43] DEBUG[9983]: channel.c:3185 ast_channel_inherit_variables: Not copying variable SIPDOMAIN. [Dec 27 18:30:43] DEBUG[9983]: channel.c:3185 ast_channel_inherit_variables: Not copying variable SIPURI. [Dec 27 18:30:43] DEBUG[9983]: chan_sip.c:2812 sip_call: Outgoing Call for 0123123123 [Dec 27 18:30:43] DEBUG[9983]: chan_sip.c:2978 update_call_counter: Updating call counter for outgoing call [Dec 27 18:30:43] DEBUG[9983]: chan_sip.c:2826 sip_call: Our T38 capability (3840), joint T38 capability (3840) [Dec 27 18:30:43] DEBUG[9983]: chan_sip.c:6122 add_sdp: ** Our capability: 0x40a (gsm|alaw|ilbc) Video flag: False [Dec 27 18:30:43] DEBUG[9983]: chan_sip.c:6123 add_sdp: ** Our prefcodec: 0x8 (alaw) Audio is at 172.16.16.111 port 16034 Adding codec 0x8 (alaw) to SDP Adding codec 0x2 (gsm) to SDP Adding codec 0x400 (ilbc) to SDP Adding non-codec 0x1 (telephone-event) to SDP [Dec 27 18:30:43] DEBUG[9983]: chan_sip.c:6254 add_sdp: -- Done with adding codecs to SDP [Dec 27 18:30:43] DEBUG[9983]: chan_sip.c:6299 add_sdp: Done building SDP. Settling with this capability: 0x40a (gsm|alaw|ilbc) [Dec 27 18:30:43] DEBUG[9983]: chan_sip.c:4527 parse_request: Header 0: INVITE sip:0123123123@172.16.16.155 SIP/2.0 (40) [Dec 27 18:30:43] DEBUG[9983]: chan_sip.c:4527 parse_request: Header 1: Via: SIP/2.0/UDP 172.16.16.111:5060;branch=z9hG4bK0b3ec78b;rport (61) [Dec 27 18:30:43] DEBUG[9983]: chan_sip.c:4527 parse_request: Header 2: From: "7960@Lab" ;tag=as77ca8eeb (59) [Dec 27 18:30:43] DEBUG[9983]: chan_sip.c:4527 parse_request: Header 3: To: (31) [Dec 27 18:30:43] DEBUG[9983]: chan_sip.c:4527 parse_request: Header 4: Contact: (36) [Dec 27 18:30:43] DEBUG[9983]: chan_sip.c:4527 parse_request: Header 5: Call-ID: 237a442d0168be044b12b5b00c645a30@172.16.16.111 (52) [Dec 27 18:30:43] DEBUG[9983]: chan_sip.c:4527 parse_request: Header 6: CSeq: 102 INVITE (16) [Dec 27 18:30:43] DEBUG[9983]: chan_sip.c:4527 parse_request: Header 7: User-Agent: blabla.ch 1.4PBX (26) [Dec 27 18:30:43] DEBUG[9983]: chan_sip.c:4527 parse_request: Header 8: Max-Forwards: 70 (16) [Dec 27 18:30:43] DEBUG[9983]: chan_sip.c:4527 parse_request: Header 9: Date: Wed, 27 Dec 2006 17:30:43 GMT (35) [Dec 27 18:30:43] DEBUG[9983]: chan_sip.c:4527 parse_request: Header 10: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY (66) [Dec 27 18:30:43] DEBUG[9983]: chan_sip.c:4527 parse_request: Header 11: Supported: replaces (19) [Dec 27 18:30:43] DEBUG[9983]: chan_sip.c:4527 parse_request: Header 12: Content-Type: application/sdp (29) [Dec 27 18:30:43] DEBUG[9983]: chan_sip.c:4527 parse_request: Header 13: Content-Length: 304 (19) [Dec 27 18:30:43] DEBUG[9983]: chan_sip.c:4527 parse_request: Header 14: (0) [Dec 27 18:30:43] DEBUG[9983]: chan_sip.c:4559 parse_request: Line: v=0 (3) [Dec 27 18:30:43] DEBUG[9983]: chan_sip.c:4559 parse_request: Line: o=root 9983 9983 IN IP4 10.10.10.23 (35) [Dec 27 18:30:43] DEBUG[9983]: chan_sip.c:4559 parse_request: Line: s=session (9) [Dec 27 18:30:43] DEBUG[9983]: chan_sip.c:4559 parse_request: Line: c=IN IP4 10.10.10.23 (20) [Dec 27 18:30:43] DEBUG[9983]: chan_sip.c:4559 parse_request: Line: t=0 0 (5) [Dec 27 18:30:43] DEBUG[9983]: chan_sip.c:4559 parse_request: Line: m=audio 16406 RTP/AVP 8 3 97 101 (32) [Dec 27 18:30:43] DEBUG[9983]: chan_sip.c:4559 parse_request: Line: a=rtpmap:8 PCMA/8000 (20) [Dec 27 18:30:43] DEBUG[9983]: chan_sip.c:4559 parse_request: Line: a=rtpmap:3 GSM/8000 (19) [Dec 27 18:30:43] DEBUG[9983]: chan_sip.c:4559 parse_request: Line: a=rtpmap:97 iLBC/8000 (21) [Dec 27 18:30:43] DEBUG[9983]: chan_sip.c:4559 parse_request: Line: a=fmtp:97 mode=30 (17) [Dec 27 18:30:43] DEBUG[9983]: chan_sip.c:4559 parse_request: Line: a=rtpmap:101 telephone-event/8000 (33) [Dec 27 18:30:43] DEBUG[9983]: chan_sip.c:4559 parse_request: Line: a=fmtp:101 0-16 (15) [Dec 27 18:30:43] DEBUG[9983]: chan_sip.c:4559 parse_request: Line: a=silenceSupp:off - - - - (25) [Dec 27 18:30:43] DEBUG[9983]: chan_sip.c:4559 parse_request: Line: a=ptime:20 (10) [Dec 27 18:30:43] DEBUG[9983]: chan_sip.c:4559 parse_request: Line: a=sendrecv (10) Reliably Transmitting (no NAT) to 172.16.16.155:5060: INVITE sip:0123123123@172.16.16.155 SIP/2.0 Via: SIP/2.0/UDP 172.16.16.111:5060;branch=z9hG4bK0b3ec78b;rport From: "7960@Lab" ;tag=as77ca8eeb To: Contact: Call-ID: 237a442d0168be044b12b5b00c645a30@172.16.16.111 CSeq: 102 INVITE User-Agent: blabla.ch 1.4PBX Max-Forwards: 70 Date: Wed, 27 Dec 2006 17:30:43 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Content-Type: application/sdp Content-Length: 304 v=0 o=root 9983 9983 IN IP4 10.10.10.23 s=session c=IN IP4 10.10.10.23 t=0 0 m=audio 16406 RTP/AVP 8 3 97 101 a=rtpmap:8 PCMA/8000 a=rtpmap:3 GSM/8000 a=rtpmap:97 iLBC/8000 a=fmtp:97 mode=30 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=silenceSupp:off - - - - a=ptime:20 a=sendrecv --- [Dec 27 18:30:43] DEBUG[9983]: chan_sip.c:1957 __sip_reliable_xmit: *** SIP TIMER: Initalizing retransmit timer on packet: Id #20 -- Called 0123123123@172.16.16.155 <--- SIP read from 172.16.16.155:5060 ---> SIP/2.0 100 Trying Via: SIP/2.0/UDP 172.16.16.111:5060;received=172.16.16.111;branch=z9hG4bK0b3ec78b;rport=5060 From: "7960@Lab" ;tag=as77ca8eeb To: Call-ID: 237a442d0168be044b12b5b00c645a30@172.16.16.111 CSeq: 102 INVITE <-------------> [Dec 27 18:30:43] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 0: SIP/2.0 100 Trying (18) [Dec 27 18:30:43] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 1: Via: SIP/2.0/UDP 172.16.16.111:5060;received=172.16.16.111;branch=z9hG4bK0b3ec78b;rport=5060 (86) [Dec 27 18:30:43] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 2: From: "7960@Lab" ;tag=as77ca8eeb (59) [Dec 27 18:30:43] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 3: To: (31) [Dec 27 18:30:43] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 4: Call-ID: 237a442d0168be044b12b5b00c645a30@172.16.16.111 (52) [Dec 27 18:30:43] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 5: CSeq: 102 INVITE (16) [Dec 27 18:30:43] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 6: (0) --- (6 headers 0 lines) --- [Dec 27 18:30:43] DEBUG[9953]: chan_sip.c:2104 __sip_semi_ack: *** SIP TIMER: Cancelling retransmission #20 - INVITE (got response) [Dec 27 18:30:43] DEBUG[9953]: chan_sip.c:2113 __sip_semi_ack: (Provisional) Stopping retransmission (but retaining packet) on '237a442d0168be044b12b5b00c645a30@172.16.16.111' Request 102: Found [Dec 27 18:30:43] DEBUG[9953]: chan_sip.c:11531 handle_response_invite: SIP response 100 to standard invite uboot*CLI> <--- SIP read from 172.16.16.155:5060 ---> SIP/2.0 183 Session Progress Via: SIP/2.0/UDP 172.16.16.111:5060;received=172.16.16.111;branch=z9hG4bK0b3ec78b;rport=5060 From: "7960@Lab" ;tag=as77ca8eeb To: ;tag=1017816372 Call-ID: 237a442d0168be044b12b5b00c645a30@172.16.16.111 CSeq: 102 INVITE Contact: Content-Type: application/sdp Content-Length: 341 v=0 o=- 565 0 IN IP4 172.16.16.155 s=Cisco SDP 0 c=IN IP4 172.16.16.155 t=0 0 m=audio 16004 RTP/AVP 8 101 100 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=rtpmap:100 X-NSE/8000 a=fmtp:100 200-202 a=X-sqn:0 a=X-cap: 1 audio RTP/AVP 100 a=X-cpar: a=rtpmap:100 X-NSE/8000 a=X-cpar: a=fmtp:100 200-202 a=X-cap: 2 image udptl t38 <-------------> [Dec 27 18:30:45] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 0: SIP/2.0 183 Session Progress (28) [Dec 27 18:30:45] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 1: Via: SIP/2.0/UDP 172.16.16.111:5060;received=172.16.16.111;branch=z9hG4bK0b3ec78b;rport=5060 (86) [Dec 27 18:30:45] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 2: From: "7960@Lab" ;tag=as77ca8eeb (59) [Dec 27 18:30:45] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 3: To: ;tag=1017816372 (46) [Dec 27 18:30:45] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 4: Call-ID: 237a442d0168be044b12b5b00c645a30@172.16.16.111 (52) [Dec 27 18:30:45] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 5: CSeq: 102 INVITE (16) [Dec 27 18:30:45] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 6: Contact: (55) [Dec 27 18:30:45] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 7: Content-Type: application/sdp (29) [Dec 27 18:30:45] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 8: Content-Length: 341 (19) [Dec 27 18:30:45] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 9: (0) [Dec 27 18:30:45] DEBUG[9953]: chan_sip.c:4559 parse_request: Line: v=0 (3) [Dec 27 18:30:45] DEBUG[9953]: chan_sip.c:4559 parse_request: Line: o=- 565 0 IN IP4 172.16.16.155 (27) [Dec 27 18:30:45] DEBUG[9953]: chan_sip.c:4559 parse_request: Line: s=Cisco SDP 0 (13) [Dec 27 18:30:45] DEBUG[9953]: chan_sip.c:4559 parse_request: Line: c=IN IP4 172.16.16.155 (19) [Dec 27 18:30:45] DEBUG[9953]: chan_sip.c:4559 parse_request: Line: t=0 0 (5) [Dec 27 18:30:45] DEBUG[9953]: chan_sip.c:4559 parse_request: Line: m=audio 16004 RTP/AVP 8 101 100 (31) [Dec 27 18:30:45] DEBUG[9953]: chan_sip.c:4559 parse_request: Line: a=rtpmap:101 telephone-event/8000 (33) [Dec 27 18:30:45] DEBUG[9953]: chan_sip.c:4559 parse_request: Line: a=fmtp:101 0-15 (15) [Dec 27 18:30:45] DEBUG[9953]: chan_sip.c:4559 parse_request: Line: a=rtpmap:100 X-NSE/8000 (23) [Dec 27 18:30:45] DEBUG[9953]: chan_sip.c:4559 parse_request: Line: a=fmtp:100 200-202 (18) [Dec 27 18:30:45] DEBUG[9953]: chan_sip.c:4559 parse_request: Line: a=X-sqn:0 (9) [Dec 27 18:30:45] DEBUG[9953]: chan_sip.c:4559 parse_request: Line: a=X-cap: 1 audio RTP/AVP 100 (28) [Dec 27 18:30:45] DEBUG[9953]: chan_sip.c:4559 parse_request: Line: a=X-cpar: a=rtpmap:100 X-NSE/8000 (33) [Dec 27 18:30:45] DEBUG[9953]: chan_sip.c:4559 parse_request: Line: a=X-cpar: a=fmtp:100 200-202 (28) [Dec 27 18:30:45] DEBUG[9953]: chan_sip.c:4559 parse_request: Line: a=X-cap: 2 image udptl t38 (26) --- (9 headers 15 lines) --- [Dec 27 18:30:45] DEBUG[9953]: chan_sip.c:2113 __sip_semi_ack: (Provisional) Stopping retransmission (but retaining packet) on '237a442d0168be044b12b5b00c645a30@172.16.16.111' Request 102: Found [Dec 27 18:30:45] DEBUG[9953]: chan_sip.c:11531 handle_response_invite: SIP response 183 to standard invite Found RTP audio format 8 Found RTP audio format 101 Found RTP audio format 100 [Dec 27 18:30:45] DEBUG[9953]: chan_sip.c:4838 process_sdp: Peer doesn't provide T.38 UDPTL Peer audio RTP is at port 172.16.16.155:16004 Found description format telephone-event for ID 101 Got unsupported a:fmtp in SDP offer Found description format X-NSE for ID 100 Got unsupported a:fmtp in SDP offer [Dec 27 18:30:45] DEBUG[9953]: chan_sip.c:5068 process_sdp: T38 state changed to 0 on channel SIP/172.16.16.155-081e29c0 Capabilities: us - 0x40a (gsm|alaw|ilbc), peer - audio=0x8 (alaw)/video=0x0 (nothing), combined - 0x8 (alaw) Non-codec capabilities (dtmf): us - 0x1 (telephone-event), peer - 0x1 (telephone-event), combined - 0x1 (telephone-event) Peer audio RTP is at port 172.16.16.155:16004 Peer video RTP is at port 172.16.16.155:63040 [Dec 27 18:30:45] DEBUG[9953]: chan_sip.c:5145 process_sdp: We're settling with these formats: 0x8 (alaw) [Dec 27 18:30:45] DEBUG[9953]: chan_sip.c:5152 process_sdp: We have an owner, now see if we need to change this call -- SIP/172.16.16.155-081e29c0 is making progress passing it to SIP/123456789-081ddcc0 [Dec 27 18:30:45] DEBUG[9983]: chan_sip.c:16701 sip_set_rtp_peer: Early remote bridge setting SIP '4f322544-84e21c91@192.168.20.12' - Sending media to 172.16.16.155 [Dec 27 18:30:45] DEBUG[9983]: rtp.c:1505 ast_rtp_early_bridge: Setting early bridge SDP of 'SIP/123456789-081ddcc0' with that of 'SIP/172.16.16.155-081e29c0' [Dec 27 18:30:45] DEBUG[9983]: chan_sip.c:6354 transmit_response_with_sdp: Setting framing from config on incoming call [Dec 27 18:30:45] DEBUG[9983]: chan_sip.c:6122 add_sdp: ** Our capability: 0x8 (alaw) Video flag: True [Dec 27 18:30:45] DEBUG[9983]: chan_sip.c:6123 add_sdp: ** Our prefcodec: 0x0 (nothing) Audio is at 172.16.16.111 port 16014 Adding codec 0x8 (alaw) to SDP Adding non-codec 0x1 (telephone-event) to SDP [Dec 27 18:30:45] DEBUG[9983]: chan_sip.c:6254 add_sdp: -- Done with adding codecs to SDP [Dec 27 18:30:45] DEBUG[9983]: chan_sip.c:6299 add_sdp: Done building SDP. Settling with this capability: 0x8 (alaw) <--- Transmitting (no NAT) to 10.10.10.23:7261 ---> SIP/2.0 183 Session Progress Via: SIP/2.0/UDP 10.10.10.23:7261;branch=z9hG4bK-efc76d51;received=10.10.10.23 From: fax ;tag=dd3745a828a6d77do1 To: ;tag=as46f4d96c Call-ID: 4f322544-84e21c91@192.168.20.12 CSeq: 102 INVITE User-Agent: blabla.ch 1.4PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Contact: Content-Type: application/sdp Content-Length: 234 v=0 o=root 9983 9983 IN IP4 172.16.16.155 s=session c=IN IP4 172.16.16.155 t=0 0 m=audio 16004 RTP/AVP 8 101 a=rtpmap:8 PCMA/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=silenceSupp:off - - - - a=ptime:20 a=sendrecv <------------> uboot*CLI> <--- SIP read from 172.16.16.155:5060 ---> SIP/2.0 180 Ringing Via: SIP/2.0/UDP 172.16.16.111:5060;received=172.16.16.111;branch=z9hG4bK0b3ec78b;rport=5060 From: "7960@Lab" ;tag=as77ca8eeb To: ;tag=1017816372 Call-ID: 237a442d0168be044b12b5b00c645a30@172.16.16.111 CSeq: 102 INVITE Contact: Content-Length: 0 <-------------> [Dec 27 18:30:46] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 0: SIP/2.0 180 Ringing (19) [Dec 27 18:30:46] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 1: Via: SIP/2.0/UDP 172.16.16.111:5060;received=172.16.16.111;branch=z9hG4bK0b3ec78b;rport=5060 (86) [Dec 27 18:30:46] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 2: From: "7960@Lab" ;tag=as77ca8eeb (59) [Dec 27 18:30:46] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 3: To: ;tag=1017816372 (46) [Dec 27 18:30:46] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 4: Call-ID: 237a442d0168be044b12b5b00c645a30@172.16.16.111 (52) [Dec 27 18:30:46] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 5: CSeq: 102 INVITE (16) [Dec 27 18:30:46] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 6: Contact: (55) [Dec 27 18:30:46] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 7: Content-Length: 0 (17) [Dec 27 18:30:46] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 8: (0) --- (8 headers 0 lines) --- [Dec 27 18:30:46] DEBUG[9953]: chan_sip.c:2113 __sip_semi_ack: (Provisional) Stopping retransmission (but retaining packet) on '237a442d0168be044b12b5b00c645a30@172.16.16.111' Request 102: Found [Dec 27 18:30:46] DEBUG[9953]: chan_sip.c:11531 handle_response_invite: SIP response 180 to standard invite [Dec 27 18:30:46] DEBUG[9953]: devicestate.c:303 __ast_device_state_changed_literal: Notification of state change to be queued on device/channel SIP/172.16.16.155-081e29c0 -- SIP/172.16.16.155-081e29c0 is ringing [Dec 27 18:30:46] DEBUG[9983]: rtp.c:1505 ast_rtp_early_bridge: Setting early bridge SDP of 'SIP/123456789-081ddcc0' with that of 'SIP/172.16.16.155-081e29c0' <--- Transmitting (no NAT) to 10.10.10.23:7261 ---> SIP/2.0 180 Ringing Via: SIP/2.0/UDP 10.10.10.23:7261;branch=z9hG4bK-efc76d51;received=10.10.10.23 From: fax ;tag=dd3745a828a6d77do1 To: ;tag=as46f4d96c Call-ID: 4f322544-84e21c91@192.168.20.12 CSeq: 102 INVITE User-Agent: blabla.ch 1.4PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Contact: Content-Length: 0 <------------> [Dec 27 18:30:46] DEBUG[9930]: devicestate.c:161 ast_device_state: No provider found, checking channel drivers for SIP - 172.16.16.155 [Dec 27 18:30:46] DEBUG[9930]: chan_sip.c:15048 sip_devicestate: Checking device state for peer 172.16.16.155 [Dec 27 18:30:46] DEBUG[9930]: devicestate.c:287 do_state_change: Changing state for SIP/172.16.16.155 - state 6 (Ringing) [Dec 27 18:30:46] DEBUG[9985]: app_queue.c:546 changethread: Device 'SIP/172.16.16.155' changed to state '6' (Ringing) but we don't care because they're not a member of any queue. uboot*CLI> <--- SIP read from 10.10.10.23:7250 ---> NOTIFY sip:172.16.16.111 SIP/2.0 Via: SIP/2.0/UDP 10.10.10.23:7251;branch=z9hG4bK-c7c42ead From: alx ;tag=dc778fd1839473do0 To: Call-ID: 75ea1f55-8603187f@192.168.20.12 CSeq: 4032 NOTIFY Max-Forwards: 70 Event: keep-alive User-Agent: Linksys/SPA2102-3.3.6 Content-Length: 0 <-------------> [Dec 27 18:30:50] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 0: NOTIFY sip:172.16.16.111 SIP/2.0 (29) [Dec 27 18:30:50] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 1: Via: SIP/2.0/UDP 10.10.10.23:7251;branch=z9hG4bK-c7c42ead (57) [Dec 27 18:30:50] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 2: From: alx ;tag=dc778fd1839473do0 (58) [Dec 27 18:30:50] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 3: To: (20) [Dec 27 18:30:50] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 4: Call-ID: 75ea1f55-8603187f@192.168.20.12 (40) [Dec 27 18:30:50] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 5: CSeq: 4032 NOTIFY (17) [Dec 27 18:30:50] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 6: Max-Forwards: 70 (16) [Dec 27 18:30:50] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 7: Event: keep-alive (17) [Dec 27 18:30:50] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 8: User-Agent: Linksys/SPA2102-3.3.6 (33) [Dec 27 18:30:50] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 9: Content-Length: 0 (17) [Dec 27 18:30:50] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 10: (0) --- (10 headers 0 lines) --- <--- Transmitting (no NAT) to 10.10.10.23:7250 ---> SIP/2.0 489 Bad event Via: SIP/2.0/UDP 10.10.10.23:7251;branch=z9hG4bK-c7c42ead;received=10.10.10.23 From: alx ;tag=dc778fd1839473do0 To: ;tag=as76ff0233 Call-ID: 75ea1f55-8603187f@192.168.20.12 CSeq: 4032 NOTIFY User-Agent: blabla.ch 1.4PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Content-Length: 0 <------------> [Dec 27 18:30:50] DEBUG[9953]: chan_sip.c:14631 sipsock_read: Invalid SIP message - rejected , no callid, len 326 uboot*CLI> <--- SIP read from 10.10.10.23:7261 ---> NOTIFY sip:172.16.16.111 SIP/2.0 Via: SIP/2.0/UDP 10.10.10.23:7261;branch=z9hG4bK-1f30fd19 From: fax ;tag=268feafdffbb7d3do1 To: Call-ID: 75bd615d-2ad57e17@192.168.20.12 CSeq: 4032 NOTIFY Max-Forwards: 70 Event: keep-alive User-Agent: Linksys/SPA2102-3.3.6 Content-Length: 0 <-------------> [Dec 27 18:30:50] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 0: NOTIFY sip:172.16.16.111 SIP/2.0 (29) [Dec 27 18:30:50] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 1: Via: SIP/2.0/UDP 10.10.10.23:7261;branch=z9hG4bK-1f30fd19 (57) [Dec 27 18:30:50] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 2: From: fax ;tag=268feafdffbb7d3do1 (59) [Dec 27 18:30:50] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 3: To: (20) [Dec 27 18:30:50] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 4: Call-ID: 75bd615d-2ad57e17@192.168.20.12 (40) [Dec 27 18:30:50] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 5: CSeq: 4032 NOTIFY (17) [Dec 27 18:30:50] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 6: Max-Forwards: 70 (16) [Dec 27 18:30:50] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 7: Event: keep-alive (17) [Dec 27 18:30:50] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 8: User-Agent: Linksys/SPA2102-3.3.6 (33) [Dec 27 18:30:50] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 9: Content-Length: 0 (17) [Dec 27 18:30:50] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 10: (0) --- (10 headers 0 lines) --- uboot*CLI> <--- Transmitting (no NAT) to 10.10.10.23:7261 ---> SIP/2.0 489 Bad event Via: SIP/2.0/UDP 10.10.10.23:7261;branch=z9hG4bK-1f30fd19;received=10.10.10.23 From: fax ;tag=268feafdffbb7d3do1 To: ;tag=as3ef6ed7e Call-ID: 75bd615d-2ad57e17@192.168.20.12 CSeq: 4032 NOTIFY User-Agent: blabla.ch 1.4PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Content-Length: 0 <------------> [Dec 27 18:30:50] DEBUG[9953]: chan_sip.c:14631 sipsock_read: Invalid SIP message - rejected , no callid, len 327 uboot*CLI> <--- SIP read from 172.16.16.155:5060 ---> SIP/2.0 200 Ok Via: SIP/2.0/UDP 172.16.16.111:5060;received=172.16.16.111;branch=z9hG4bK0b3ec78b;rport=5060 From: "7960@Lab" ;tag=as77ca8eeb To: ;tag=1017816372 Call-ID: 237a442d0168be044b12b5b00c645a30@172.16.16.111 CSeq: 102 INVITE Contact: Allow: INVITE,ACK,PRACK,SUBSCRIBE,BYE,CANCEL,NOTIFY,INFO,REFER,UPDATE Supported: timer Content-Type: application/sdp Content-Length: 341 v=0 o=- 565 0 IN IP4 172.16.16.155 s=Cisco SDP 0 c=IN IP4 172.16.16.155 t=0 0 m=audio 16004 RTP/AVP 8 101 100 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=rtpmap:100 X-NSE/8000 a=fmtp:100 200-202 a=X-sqn:0 a=X-cap: 1 audio RTP/AVP 100 a=X-cpar: a=rtpmap:100 X-NSE/8000 a=X-cpar: a=fmtp:100 200-202 a=X-cap: 2 image udptl t38 <-------------> [Dec 27 18:30:51] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 0: SIP/2.0 200 Ok (14) [Dec 27 18:30:51] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 1: Via: SIP/2.0/UDP 172.16.16.111:5060;received=172.16.16.111;branch=z9hG4bK0b3ec78b;rport=5060 (86) [Dec 27 18:30:51] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 2: From: "7960@Lab" ;tag=as77ca8eeb (59) [Dec 27 18:30:51] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 3: To: ;tag=1017816372 (46) [Dec 27 18:30:51] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 4: Call-ID: 237a442d0168be044b12b5b00c645a30@172.16.16.111 (52) [Dec 27 18:30:51] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 5: CSeq: 102 INVITE (16) [Dec 27 18:30:51] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 6: Contact: (55) [Dec 27 18:30:51] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 7: Allow: INVITE,ACK,PRACK,SUBSCRIBE,BYE,CANCEL,NOTIFY,INFO,REFER,UPDATE (69) [Dec 27 18:30:51] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 8: Supported: timer (16) [Dec 27 18:30:51] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 9: Content-Type: application/sdp (29) [Dec 27 18:30:51] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 10: Content-Length: 341 (19) [Dec 27 18:30:51] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 11: (0) [Dec 27 18:30:51] DEBUG[9953]: chan_sip.c:4559 parse_request: Line: v=0 (3) [Dec 27 18:30:51] DEBUG[9953]: chan_sip.c:4559 parse_request: Line: o=- 565 0 IN IP4 172.16.16.155 (27) [Dec 27 18:30:51] DEBUG[9953]: chan_sip.c:4559 parse_request: Line: s=Cisco SDP 0 (13) [Dec 27 18:30:51] DEBUG[9953]: chan_sip.c:4559 parse_request: Line: c=IN IP4 172.16.16.155 (19) [Dec 27 18:30:51] DEBUG[9953]: chan_sip.c:4559 parse_request: Line: t=0 0 (5) [Dec 27 18:30:51] DEBUG[9953]: chan_sip.c:4559 parse_request: Line: m=audio 16004 RTP/AVP 8 101 100 (31) [Dec 27 18:30:51] DEBUG[9953]: chan_sip.c:4559 parse_request: Line: a=rtpmap:101 telephone-event/8000 (33) [Dec 27 18:30:51] DEBUG[9953]: chan_sip.c:4559 parse_request: Line: a=fmtp:101 0-15 (15) [Dec 27 18:30:51] DEBUG[9953]: chan_sip.c:4559 parse_request: Line: a=rtpmap:100 X-NSE/8000 (23) [Dec 27 18:30:51] DEBUG[9953]: chan_sip.c:4559 parse_request: Line: a=fmtp:100 200-202 (18) [Dec 27 18:30:51] DEBUG[9953]: chan_sip.c:4559 parse_request: Line: a=X-sqn:0 (9) [Dec 27 18:30:51] DEBUG[9953]: chan_sip.c:4559 parse_request: Line: a=X-cap: 1 audio RTP/AVP 100 (28) [Dec 27 18:30:51] DEBUG[9953]: chan_sip.c:4559 parse_request: Line: a=X-cpar: a=rtpmap:100 X-NSE/8000 (33) [Dec 27 18:30:51] DEBUG[9953]: chan_sip.c:4559 parse_request: Line: a=X-cpar: a=fmtp:100 200-202 (28) [Dec 27 18:30:51] DEBUG[9953]: chan_sip.c:4559 parse_request: Line: a=X-cap: 2 image udptl t38 (26) --- (11 headers 15 lines) --- [Dec 27 18:30:51] DEBUG[9953]: chan_sip.c:2053 __sip_ack: Acked pending invite 102 [Dec 27 18:30:51] DEBUG[9953]: chan_sip.c:2071 __sip_ack: Stopping retransmission on '237a442d0168be044b12b5b00c645a30@172.16.16.111' of Request 102: Match Not Found [Dec 27 18:30:51] DEBUG[9953]: chan_sip.c:11531 handle_response_invite: SIP response 200 to standard invite Found RTP audio format 8 Found RTP audio format 101 Found RTP audio format 100 [Dec 27 18:30:51] DEBUG[9953]: chan_sip.c:4838 process_sdp: Peer doesn't provide T.38 UDPTL Peer audio RTP is at port 172.16.16.155:16004 Found description format telephone-event for ID 101 Got unsupported a:fmtp in SDP offer Found description format X-NSE for ID 100 Got unsupported a:fmtp in SDP offer [Dec 27 18:30:51] DEBUG[9953]: chan_sip.c:5068 process_sdp: T38 state changed to 0 on channel SIP/172.16.16.155-081e29c0 Capabilities: us - 0x40a (gsm|alaw|ilbc), peer - audio=0x8 (alaw)/video=0x0 (nothing), combined - 0x8 (alaw) Non-codec capabilities (dtmf): us - 0x1 (telephone-event), peer - 0x1 (telephone-event), combined - 0x1 (telephone-event) Peer audio RTP is at port 172.16.16.155:16004 Peer video RTP is at port 172.16.16.155:63040 [Dec 27 18:30:51] DEBUG[9953]: chan_sip.c:5145 process_sdp: We're settling with these formats: 0x8 (alaw) [Dec 27 18:30:51] DEBUG[9953]: chan_sip.c:5152 process_sdp: We have an owner, now see if we need to change this call [Dec 27 18:30:51] DEBUG[9953]: chan_sip.c:2978 update_call_counter: Updating call counter for outgoing call [Dec 27 18:30:51] DEBUG[9953]: chan_sip.c:7891 build_route: build_route: Contact hop: list_route: hop: [Dec 27 18:30:51] DEBUG[9953]: chan_sip.c:5576 reqprep: Strict routing enforced for session 237a442d0168be044b12b5b00c645a30@172.16.16.111 set_destination: Parsing for address/port to send to set_destination: set destination to 172.16.16.155, port 5060 Transmitting (no NAT) to 172.16.16.155:5060: ACK sip:0123123123@172.16.16.155:5060;transport=udp SIP/2.0 Via: SIP/2.0/UDP 172.16.16.111:5060;branch=z9hG4bK4ad758b8;rport From: "7960@Lab" ;tag=as77ca8eeb To: ;tag=1017816372 Contact: Call-ID: 237a442d0168be044b12b5b00c645a30@172.16.16.111 CSeq: 102 ACK User-Agent: blabla.ch 1.4PBX Max-Forwards: 70 Content-Length: 0 --- [Dec 27 18:30:51] DEBUG[9983]: devicestate.c:303 __ast_device_state_changed_literal: Notification of state change to be queued on device/channel SIP/172.16.16.155-081e29c0 -- SIP/172.16.16.155-081e29c0 answered SIP/123456789-081ddcc0 [Dec 27 18:30:51] DEBUG[9983]: rtp.c:1505 ast_rtp_early_bridge: Setting early bridge SDP of 'SIP/123456789-081ddcc0' with that of 'SIP/172.16.16.155-081e29c0' [Dec 27 18:30:51] DEBUG[9983]: devicestate.c:303 __ast_device_state_changed_literal: Notification of state change to be queued on device/channel SIP/123456789-081ddcc0 [Dec 27 18:30:51] DEBUG[9983]: chan_sip.c:3428 sip_answer: SIP answering channel: SIP/123456789-081ddcc0 [Dec 27 18:30:51] DEBUG[9983]: chan_sip.c:6354 transmit_response_with_sdp: Setting framing from config on incoming call [Dec 27 18:30:51] DEBUG[9983]: chan_sip.c:6122 add_sdp: ** Our capability: 0x8 (alaw) Video flag: True [Dec 27 18:30:51] DEBUG[9983]: chan_sip.c:6123 add_sdp: ** Our prefcodec: 0x0 (nothing) Audio is at 172.16.16.111 port 16014 Adding codec 0x8 (alaw) to SDP Adding non-codec 0x1 (telephone-event) to SDP [Dec 27 18:30:51] DEBUG[9983]: chan_sip.c:6254 add_sdp: -- Done with adding codecs to SDP [Dec 27 18:30:51] DEBUG[9983]: chan_sip.c:6299 add_sdp: Done building SDP. Settling with this capability: 0x8 (alaw) <--- Reliably Transmitting (no NAT) to 10.10.10.23:7261 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 10.10.10.23:7261;branch=z9hG4bK-efc76d51;received=10.10.10.23 From: fax ;tag=dd3745a828a6d77do1 To: ;tag=as46f4d96c Call-ID: 4f322544-84e21c91@192.168.20.12 CSeq: 102 INVITE User-Agent: blabla.ch 1.4PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Contact: Content-Type: application/sdp Content-Length: 234 v=0 o=root 9983 9984 IN IP4 172.16.16.155 s=session c=IN IP4 172.16.16.155 t=0 0 m=audio 16004 RTP/AVP 8 101 a=rtpmap:8 PCMA/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=silenceSupp:off - - - - a=ptime:20 a=sendrecv <------------> [Dec 27 18:30:51] DEBUG[9983]: chan_sip.c:1957 __sip_reliable_xmit: *** SIP TIMER: Initalizing retransmit timer on packet: Id #22 -- Native bridging SIP/123456789-081ddcc0 and SIP/172.16.16.155-081e29c0 [Dec 27 18:30:51] DEBUG[9930]: devicestate.c:161 ast_device_state: No provider found, checking channel drivers for SIP - 172.16.16.155 [Dec 27 18:30:51] DEBUG[9930]: chan_sip.c:15048 sip_devicestate: Checking device state for peer 172.16.16.155 [Dec 27 18:30:51] DEBUG[9930]: devicestate.c:287 do_state_change: Changing state for SIP/172.16.16.155 - state 2 (In use) [Dec 27 18:30:51] DEBUG[9930]: devicestate.c:161 ast_device_state: No provider found, checking channel drivers for SIP - 123456789 [Dec 27 18:30:51] DEBUG[9930]: chan_sip.c:15048 sip_devicestate: Checking device state for peer 123456789 [Dec 27 18:30:51] DEBUG[9930]: devicestate.c:287 do_state_change: Changing state for SIP/123456789 - state 1 (Not in use) [Dec 27 18:30:51] DEBUG[9987]: app_queue.c:546 changethread: Device 'SIP/123456789' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. [Dec 27 18:30:51] DEBUG[9930]: devicestate.c:161 ast_device_state: No provider found, checking channel drivers for SIP - 123456789 [Dec 27 18:30:51] DEBUG[9930]: chan_sip.c:15048 sip_devicestate: Checking device state for peer 123456789 [Dec 27 18:30:51] DEBUG[9986]: app_queue.c:546 changethread: Device 'SIP/172.16.16.155' changed to state '2' (In use) but we don't care because they're not a member of any queue. uboot*CLI> <--- SIP read from 10.10.10.23:7261 ---> ACK sip:0123123123@172.16.16.111 SIP/2.0 Via: SIP/2.0/UDP 10.10.10.23:7261;branch=z9hG4bK-6f5ea20e From: fax ;tag=dd3745a828a6d77do1 To: ;tag=as46f4d96c Call-ID: 4f322544-84e21c91@192.168.20.12 CSeq: 102 ACK Max-Forwards: 70 Proxy-Authorization: Digest username="123456789",realm="blabla.ch",nonce="11068d8b",uri="sip:0123123123@172.16.16.111",algorithm=MD5,response="81226f3b7decf234f635cff032798794" Contact: fax User-Agent: Linksys/SPA2102-3.3.6 Content-Length: 0 <-------------> [Dec 27 18:30:51] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 0: ACK sip:0123123123@172.16.16.111 SIP/2.0 (37) [Dec 27 18:30:51] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 1: Via: SIP/2.0/UDP 10.10.10.23:7261;branch=z9hG4bK-6f5ea20e (57) [Dec 27 18:30:51] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 2: From: fax ;tag=dd3745a828a6d77do1 (59) [Dec 27 18:30:51] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 3: To: ;tag=as46f4d96c (46) [Dec 27 18:30:51] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 4: Call-ID: 4f322544-84e21c91@192.168.20.12 (40) [Dec 27 18:30:51] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 5: CSeq: 102 ACK (13) [Dec 27 18:30:51] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 6: Max-Forwards: 70 (16) [Dec 27 18:30:51] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 7: Proxy-Authorization: Digest username="123456789",realm="blabla.ch",nonce="11068d8b",uri="sip:0123123123@172.16.16.111",algorithm=MD5,response="81226f3b7decf234f635cff032798794" (171) [Dec 27 18:30:51] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 8: Contact: fax (45) [Dec 27 18:30:51] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 9: User-Agent: Linksys/SPA2102-3.3.6 (33) [Dec 27 18:30:51] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 10: Content-Length: 0 (17) [Dec 27 18:30:51] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 11: (0) --- (11 headers 0 lines) --- [Dec 27 18:30:51] DEBUG[9953]: chan_sip.c:14453 handle_request: **** Received ACK (6) - Command in SIP ACK [Dec 27 18:30:51] DEBUG[9953]: chan_sip.c:2061 __sip_ack: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #22 [Dec 27 18:30:51] DEBUG[9953]: chan_sip.c:2071 __sip_ack: Stopping retransmission on '4f322544-84e21c91@192.168.20.12' of Response 102: Match Not Found uboot*CLI> <--- SIP read from 10.10.10.23:7261 ---> INVITE sip:0123123123@172.16.16.111 SIP/2.0 Via: SIP/2.0/UDP 10.10.10.23:7261;branch=z9hG4bK-5a5b99e From: fax ;tag=dd3745a828a6d77do1 To: ;tag=as46f4d96c Remote-Party-ID: fax ;screen=yes;party=calling Call-ID: 4f322544-84e21c91@192.168.20.12 CSeq: 103 INVITE Max-Forwards: 70 Proxy-Authorization: Digest username="123456789",realm="blabla.ch",nonce="11068d8b",uri="sip:0123123123@172.16.16.111",algorithm=MD5,response="a6751cf192a7320df6d082091111fa37" Contact: fax Expires: 30 User-Agent: Linksys/SPA2102-3.3.6 Content-Length: 208 Content-Type: application/sdp v=0 o=- 6058453 6058453 IN IP4 192.168.20.12 s=- c=IN IP4 10.10.10.23 t=0 0 m=audio 16406 RTP/AVP 8 101 a=rtpmap:8 PCMA/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=ptime:30 a=sendrecv <-------------> [Dec 27 18:30:51] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 0: INVITE sip:0123123123@172.16.16.111 SIP/2.0 (40) [Dec 27 18:30:51] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 1: Via: SIP/2.0/UDP 10.10.10.23:7261;branch=z9hG4bK-5a5b99e (56) [Dec 27 18:30:51] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 2: From: fax ;tag=dd3745a828a6d77do1 (59) [Dec 27 18:30:51] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 3: To: ;tag=as46f4d96c (46) [Dec 27 18:30:51] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 4: Remote-Party-ID: fax ;screen=yes;party=calling (72) [Dec 27 18:30:51] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 5: Call-ID: 4f322544-84e21c91@192.168.20.12 (40) [Dec 27 18:30:51] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 6: CSeq: 103 INVITE (16) [Dec 27 18:30:51] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 7: Max-Forwards: 70 (16) [Dec 27 18:30:51] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 8: Proxy-Authorization: Digest username="123456789",realm="blabla.ch",nonce="11068d8b",uri="sip:0123123123@172.16.16.111",algorithm=MD5,response="a6751cf192a7320df6d082091111fa37" (171) [Dec 27 18:30:51] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 9: Contact: fax (45) [Dec 27 18:30:51] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 10: Expires: 30 (11) [Dec 27 18:30:51] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 11: User-Agent: Linksys/SPA2102-3.3.6 (33) [Dec 27 18:30:51] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 12: Content-Length: 208 (19) [Dec 27 18:30:51] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 13: Content-Type: application/sdp (29) [Dec 27 18:30:51] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 14: (0) [Dec 27 18:30:51] DEBUG[9953]: chan_sip.c:4559 parse_request: Line: v=0 (3) [Dec 27 18:30:51] DEBUG[9953]: chan_sip.c:4559 parse_request: Line: o=- 6058453 6058453 IN IP4 192.168.20.12 (40) [Dec 27 18:30:51] DEBUG[9953]: chan_sip.c:4559 parse_request: Line: s=- (3) [Dec 27 18:30:51] DEBUG[9953]: chan_sip.c:4559 parse_request: Line: c=IN IP4 10.10.10.23 (20) [Dec 27 18:30:51] DEBUG[9953]: chan_sip.c:4559 parse_request: Line: t=0 0 (5) [Dec 27 18:30:51] DEBUG[9953]: chan_sip.c:4559 parse_request: Line: m=audio 16406 RTP/AVP 8 101 (27) [Dec 27 18:30:51] DEBUG[9953]: chan_sip.c:4559 parse_request: Line: a=rtpmap:8 PCMA/8000 (20) [Dec 27 18:30:51] DEBUG[9953]: chan_sip.c:4559 parse_request: Line: a=rtpmap:101 telephone-event/8000 (33) [Dec 27 18:30:51] DEBUG[9953]: chan_sip.c:4559 parse_request: Line: a=fmtp:101 0-15 (15) [Dec 27 18:30:51] DEBUG[9953]: chan_sip.c:4559 parse_request: Line: a=ptime:30 (10) [Dec 27 18:30:51] DEBUG[9953]: chan_sip.c:4559 parse_request: Line: a=sendrecv (10) --- (14 headers 11 lines) --- [Dec 27 18:30:51] DEBUG[9953]: chan_sip.c:14453 handle_request: **** Received INVITE (5) - Command in SIP INVITE Sending to 10.10.10.23 : 7261 (no NAT) Found RTP audio format 8 Found RTP audio format 101 [Dec 27 18:30:51] DEBUG[9953]: chan_sip.c:4838 process_sdp: Peer doesn't provide T.38 UDPTL Peer audio RTP is at port 10.10.10.23:16406 Found description format PCMA for ID 8 Found description format telephone-event for ID 101 Got unsupported a:fmtp in SDP offer [Dec 27 18:30:51] DEBUG[9953]: chan_sip.c:5068 process_sdp: T38 state changed to 0 on channel SIP/123456789-081ddcc0 Capabilities: us - 0xa (gsm|alaw), peer - audio=0x8 (alaw)/video=0x0 (nothing), combined - 0x8 (alaw) Non-codec capabilities (dtmf): us - 0x1 (telephone-event), peer - 0x1 (telephone-event), combined - 0x1 (telephone-event) Peer audio RTP is at port 10.10.10.23:16406 [Dec 27 18:30:51] DEBUG[9953]: chan_sip.c:5145 process_sdp: We're settling with these formats: 0x8 (alaw) [Dec 27 18:30:51] DEBUG[9953]: chan_sip.c:5152 process_sdp: We have an owner, now see if we need to change this call [Dec 27 18:30:51] DEBUG[9953]: chan_sip.c:13288 handle_request_invite: Got a SIP re-invite for call 4f322544-84e21c91@192.168.20.12 [Dec 27 18:30:51] DEBUG[9953]: chan_sip.c:13383 handle_request_invite: SIP/123456789-081ddcc0: This call is UP.... [Dec 27 18:30:51] DEBUG[9953]: chan_sip.c:6354 transmit_response_with_sdp: Setting framing from config on incoming call [Dec 27 18:30:51] DEBUG[9953]: chan_sip.c:6122 add_sdp: ** Our capability: 0x8 (alaw) Video flag: True [Dec 27 18:30:51] DEBUG[9953]: chan_sip.c:6123 add_sdp: ** Our prefcodec: 0x0 (nothing) Audio is at 172.16.16.111 port 16014 Adding codec 0x8 (alaw) to SDP Adding non-codec 0x1 (telephone-event) to SDP [Dec 27 18:30:51] DEBUG[9953]: chan_sip.c:6254 add_sdp: -- Done with adding codecs to SDP [Dec 27 18:30:51] DEBUG[9953]: chan_sip.c:6299 add_sdp: Done building SDP. Settling with this capability: 0x8 (alaw) <--- Reliably Transmitting (no NAT) to 10.10.10.23:7261 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 10.10.10.23:7261;branch=z9hG4bK-5a5b99e;received=10.10.10.23 From: fax ;tag=dd3745a828a6d77do1 To: ;tag=as46f4d96c Call-ID: 4f322544-84e21c91@192.168.20.12 CSeq: 103 INVITE User-Agent: blabla.ch 1.4PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Contact: Content-Type: application/sdp Content-Length: 234 v=0 o=root 9983 9985 IN IP4 172.16.16.155 s=session c=IN IP4 172.16.16.155 t=0 0 m=audio 16004 RTP/AVP 8 101 a=rtpmap:8 PCMA/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=silenceSupp:off - - - - a=ptime:20 a=sendrecv <------------> [Dec 27 18:30:51] DEBUG[9953]: chan_sip.c:1957 __sip_reliable_xmit: *** SIP TIMER: Initalizing retransmit timer on packet: Id #23 uboot*CLI> <--- SIP read from 10.10.10.23:7261 ---> ACK sip:0123123123@172.16.16.111 SIP/2.0 Via: SIP/2.0/UDP 10.10.10.23:7261;branch=z9hG4bK-7370752 From: fax ;tag=dd3745a828a6d77do1 To: ;tag=as46f4d96c Call-ID: 4f322544-84e21c91@192.168.20.12 CSeq: 103 ACK Max-Forwards: 70 Proxy-Authorization: Digest username="123456789",realm="blabla.ch",nonce="11068d8b",uri="sip:0123123123@172.16.16.111",algorithm=MD5,response="81226f3b7decf234f635cff032798794" Contact: fax User-Agent: Linksys/SPA2102-3.3.6 Content-Length: 0 <-------------> [Dec 27 18:30:51] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 0: ACK sip:0123123123@172.16.16.111 SIP/2.0 (37) [Dec 27 18:30:51] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 1: Via: SIP/2.0/UDP 10.10.10.23:7261;branch=z9hG4bK-7370752 (56) [Dec 27 18:30:51] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 2: From: fax ;tag=dd3745a828a6d77do1 (59) [Dec 27 18:30:51] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 3: To: ;tag=as46f4d96c (46) [Dec 27 18:30:51] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 4: Call-ID: 4f322544-84e21c91@192.168.20.12 (40) [Dec 27 18:30:51] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 5: CSeq: 103 ACK (13) [Dec 27 18:30:51] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 6: Max-Forwards: 70 (16) [Dec 27 18:30:51] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 7: Proxy-Authorization: Digest username="123456789",realm="blabla.ch",nonce="11068d8b",uri="sip:0123123123@172.16.16.111",algorithm=MD5,response="81226f3b7decf234f635cff032798794" (171) [Dec 27 18:30:51] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 8: Contact: fax (45) [Dec 27 18:30:51] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 9: User-Agent: Linksys/SPA2102-3.3.6 (33) [Dec 27 18:30:51] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 10: Content-Length: 0 (17) [Dec 27 18:30:51] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 11: (0) --- (11 headers 0 lines) --- [Dec 27 18:30:51] DEBUG[9953]: chan_sip.c:14453 handle_request: **** Received ACK (6) - Command in SIP ACK [Dec 27 18:30:51] DEBUG[9953]: chan_sip.c:2061 __sip_ack: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #23 [Dec 27 18:30:51] DEBUG[9953]: chan_sip.c:2071 __sip_ack: Stopping retransmission on '4f322544-84e21c91@192.168.20.12' of Response 103: Match Not Found uboot*CLI> <--- SIP read from 172.16.16.155:5060 ---> INVITE sip:0123456789@172.16.16.111 SIP/2.0 Via: SIP/2.0/UDP 172.16.16.155:5060;branch=z9hG4bK7g18dd3020tgub8ib5k0sb0000g00.1 From: ;tag=1017816372 To: "7960@Lab" ;tag=as77ca8eeb Call-ID: 237a442d0168be044b12b5b00c645a30@172.16.16.111 CSeq: 1 INVITE Supported: timer Contact: Allow: INVITE,ACK,PRACK,SUBSCRIBE,BYE,CANCEL,NOTIFY,INFO,REFER,UPDATE Max-Forwards: 69 ontent-Type: application/sdp Content-Length: 236 v=0 o=- 565 1 IN IP4 172.16.16.155 s=Cisco SDP 0 c=IN IP4 172.16.16.155 t=0 0 m=image 16004 udptl t38 a=X-sqn:0 a=X-cap: 1 audio RTP/AVP 100 a=X-cpar: a=rtpmap:100 X-NSE/8000 a=X-cpar: a=fmtp:100 200-202 a=X-cap: 2 image udptl t38 <-------------> [Dec 27 18:30:59] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 0: INVITE sip:0123456789@172.16.16.111 SIP/2.0 (40) [Dec 27 18:30:59] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 1: Via: SIP/2.0/UDP 172.16.16.155:5060;branch=z9hG4bK7g18dd3020tgub8ib5k0sb0000g00.1 (78) [Dec 27 18:30:59] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 2: From: ;tag=1017816372 (48) [Dec 27 18:30:59] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 3: To: "7960@Lab" ;tag=as77ca8eeb (57) [Dec 27 18:30:59] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 4: Call-ID: 237a442d0168be044b12b5b00c645a30@172.16.16.111 (52) [Dec 27 18:30:59] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 5: CSeq: 1 INVITE (14) [Dec 27 18:30:59] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 6: Supported: timer (16) [Dec 27 18:30:59] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 7: Contact: (59) [Dec 27 18:30:59] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 8: Allow: INVITE,ACK,PRACK,SUBSCRIBE,BYE,CANCEL,NOTIFY,INFO,REFER,UPDATE (69) [Dec 27 18:30:59] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 9: Max-Forwards: 69 (16) [Dec 27 18:30:59] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 10: Content-Type: application/sdp (29) [Dec 27 18:30:59] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 11: Content-Length: 236 (19) [Dec 27 18:30:59] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 12: (0) [Dec 27 18:30:59] DEBUG[9953]: chan_sip.c:4559 parse_request: Line: v=0 (3) [Dec 27 18:30:59] DEBUG[9953]: chan_sip.c:4559 parse_request: Line: o=- 565 1 IN IP4 172.16.16.155 (27) [Dec 27 18:30:59] DEBUG[9953]: chan_sip.c:4559 parse_request: Line: s=Cisco SDP 0 (13) [Dec 27 18:30:59] DEBUG[9953]: chan_sip.c:4559 parse_request: Line: c=IN IP4 172.16.16.155 (19) [Dec 27 18:30:59] DEBUG[9953]: chan_sip.c:4559 parse_request: Line: t=0 0 (5) [Dec 27 18:30:59] DEBUG[9953]: chan_sip.c:4559 parse_request: Line: m=image 16004 udptl t38 (23) [Dec 27 18:30:59] DEBUG[9953]: chan_sip.c:4559 parse_request: Line: a=X-sqn:0 (9) [Dec 27 18:30:59] DEBUG[9953]: chan_sip.c:4559 parse_request: Line: a=X-cap: 1 audio RTP/AVP 100 (28) [Dec 27 18:30:59] DEBUG[9953]: chan_sip.c:4559 parse_request: Line: a=X-cpar: a=rtpmap:100 X-NSE/8000 (33) [Dec 27 18:30:59] DEBUG[9953]: chan_sip.c:4559 parse_request: Line: a=X-cpar: a=fmtp:100 200-202 (28) [Dec 27 18:30:59] DEBUG[9953]: chan_sip.c:4559 parse_request: Line: a=X-cap: 2 image udptl t38 (26) --- (12 headers 11 lines) --- [Dec 27 18:30:59] DEBUG[9953]: chan_sip.c:14453 handle_request: **** Received INVITE (5) - Command in SIP INVITE [Dec 27 18:30:59] DEBUG[9953]: chan_sip.c:1662 parse_sip_options: Begin: parsing SIP "Supported: timer" [Dec 27 18:30:59] DEBUG[9953]: chan_sip.c:1670 parse_sip_options: Found SIP option: -timer- [Dec 27 18:30:59] DEBUG[9953]: chan_sip.c:1676 parse_sip_options: Matched SIP option: timer Sending to 172.16.16.155 : 5060 (no NAT) Got T.38 offer in SDP in dialog 237a442d0168be044b12b5b00c645a30@172.16.16.111 [Dec 27 18:30:59] DEBUG[9953]: chan_sip.c:4784 process_sdp: T38 state changed to 4 on channel SIP/172.16.16.155-081e29c0 [Dec 27 18:30:59] DEBUG[9953]: chan_sip.c:4834 process_sdp: Peer T.38 UDPTL is at port 172.16.16.155:16004 Got T.38 Re-invite without audio. Keeping RTP active during T.38 session. Callid 237a442d0168be044b12b5b00c645a30@172.16.16.111 [Dec 27 18:30:59] DEBUG[9953]: chan_sip.c:5061 process_sdp: Our T38 capability = (3840), peer T38 capability (0), joint T38 capability (3840) Capabilities: us - 0x40a (gsm|alaw|ilbc), peer - audio=0x0 (nothing)/video=0x0 (nothing), combined - 0x0 (nothing) Non-codec capabilities (dtmf): us - 0x1 (telephone-event), peer - 0x0 (nothing), combined - 0x0 (nothing) [Dec 27 18:30:59] DEBUG[9953]: chan_sip.c:5103 process_sdp: Have T.38 but no audio codecs, accepting offer anyway [Dec 27 18:30:59] DEBUG[9953]: chan_sip.c:13288 handle_request_invite: Got a SIP re-invite for call 237a442d0168be044b12b5b00c645a30@172.16.16.111 [Dec 27 18:30:59] DEBUG[9953]: chan_sip.c:13383 handle_request_invite: SIP/172.16.16.155-081e29c0: This call is UP.... [Dec 27 18:30:59] DEBUG[9953]: chan_sip.c:16558 sip_handle_t38_reinvite: Sending reinvite on SIP '4f322544-84e21c91@192.168.20.12' - It's UDPTL soon redirected to IP 172.16.16.155:16004 [Dec 27 18:30:59] DEBUG[9953]: chan_sip.c:5576 reqprep: Strict routing enforced for session 4f322544-84e21c91@192.168.20.12 set_destination: Parsing for address/port to send to set_destination: set destination to 10.10.10.23, port 7261 [Dec 27 18:30:59] DEBUG[9953]: chan_sip.c:5975 add_t38_sdp: T.38 UDPTL is at 172.16.16.111 port 4630 [Dec 27 18:30:59] DEBUG[9953]: chan_sip.c:5981 add_t38_sdp: Our T38 capability (3840), peer T38 capability (3840), joint capability (3840) [Dec 27 18:30:59] DEBUG[9953]: chan_sip.c:5908 t38_get_rate: T38MaxFaxRate 9600 found [Dec 27 18:30:59] DEBUG[9953]: chan_sip.c:1603 initialize_initreq: Initializing already initialized SIP dialog 4f322544-84e21c91@192.168.20.12 (presumably reinvite) [Dec 27 18:30:59] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 0: INVITE sip:123456789@10.10.10.23:7261 SIP/2.0 (45) [Dec 27 18:30:59] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 1: Via: SIP/2.0/UDP 172.16.16.111:5060;branch=z9hG4bK3b734b52;rport (61) [Dec 27 18:30:59] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 2: From: ;tag=as46f4d96c (48) [Dec 27 18:30:59] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 3: To: fax ;tag=dd3745a828a6d77do1 (57) [Dec 27 18:30:59] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 4: Contact: (36) [Dec 27 18:30:59] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 5: Call-ID: 4f322544-84e21c91@192.168.20.12 (40) [Dec 27 18:30:59] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 6: CSeq: 102 INVITE (16) [Dec 27 18:30:59] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 7: User-Agent: blabla.ch 1.4PBX (26) [Dec 27 18:30:59] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 8: Max-Forwards: 70 (16) [Dec 27 18:30:59] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 9: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY (66) [Dec 27 18:30:59] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 10: Supported: replaces (19) [Dec 27 18:30:59] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 11: X-asterisk-info: SIP re-invite (T38 switchover) (47) [Dec 27 18:30:59] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 12: Content-Type: application/sdp (29) [Dec 27 18:30:59] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 13: Content-Length: 253 (19) [Dec 27 18:30:59] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 14: (0) [Dec 27 18:30:59] DEBUG[9953]: chan_sip.c:4559 parse_request: Line: v=0 (3) [Dec 27 18:30:59] DEBUG[9953]: chan_sip.c:4559 parse_request: Line: o=root 9983 9986 IN IP4 172.16.16.155 (34) [Dec 27 18:30:59] DEBUG[9953]: chan_sip.c:4559 parse_request: Line: s=session (9) [Dec 27 18:30:59] DEBUG[9953]: chan_sip.c:4559 parse_request: Line: c=IN IP4 172.16.16.155 (19) [Dec 27 18:30:59] DEBUG[9953]: chan_sip.c:4559 parse_request: Line: t=0 0 (5) [Dec 27 18:30:59] DEBUG[9953]: chan_sip.c:4559 parse_request: Line: m=image 16004 udptl t38 (23) [Dec 27 18:30:59] DEBUG[9953]: chan_sip.c:4559 parse_request: Line: a=T38FaxVersion:0 (17) [Dec 27 18:30:59] DEBUG[9953]: chan_sip.c:4559 parse_request: Line: a=T38MaxBitRate:9600 (20) [Dec 27 18:30:59] DEBUG[9953]: chan_sip.c:4559 parse_request: Line: a=T38FaxFillBitRemoval:0 (24) [Dec 27 18:30:59] DEBUG[9953]: chan_sip.c:4559 parse_request: Line: a=T38FaxTranscodingMMR:0 (24) [Dec 27 18:30:59] DEBUG[9953]: chan_sip.c:4559 parse_request: Line: a=T38FaxTranscodingJBIG:0 (25) [Dec 27 18:30:59] DEBUG[9953]: chan_sip.c:4559 parse_request: Line: a=T38FaxRateManagement:transferredTCF (37) [Dec 27 18:30:59] DEBUG[9953]: chan_sip.c:4559 parse_request: Line: a=T38FaxMaxBuffer:0 (19) [Dec 27 18:30:59] DEBUG[9953]: chan_sip.c:4559 parse_request: Line: a=T38FaxMaxDatagram:0 (21) [Dec 27 18:30:59] DEBUG[9953]: chan_sip.c:4559 parse_request: Line: a=T38FaxUdpEC:t38UDPFEC (23) Reliably Transmitting (no NAT) to 10.10.10.23:7261: INVITE sip:123456789@10.10.10.23:7261 SIP/2.0 Via: SIP/2.0/UDP 172.16.16.111:5060;branch=z9hG4bK3b734b52;rport From: ;tag=as46f4d96c To: fax ;tag=dd3745a828a6d77do1 Contact: Call-ID: 4f322544-84e21c91@192.168.20.12 CSeq: 102 INVITE User-Agent: blabla.ch 1.4PBX Max-Forwards: 70 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces X-asterisk-info: SIP re-invite (T38 switchover) Content-Type: application/sdp Content-Length: 253 v=0 o=root 9983 9986 IN IP4 172.16.16.155 s=session c=IN IP4 172.16.16.155 t=0 0 m=image 16004 udptl t38 a=T38FaxVersion:0 a=T38MaxBitRate:9600 a=T38FaxFillBitRemoval:0 a=T38FaxTranscodingMMR:0 a=T38FaxTranscodingJBIG:0 a=T38FaxRateManagement:transferredTCF a=T38FaxMaxBuffer:0 a=T38FaxMaxDatagram:0 a=T38FaxUdpEC:t38UDPFEC --- [Dec 27 18:30:59] DEBUG[9953]: chan_sip.c:1957 __sip_reliable_xmit: *** SIP TIMER: Initalizing retransmit timer on packet: Id #24 uboot*CLI> <--- SIP read from 10.10.10.23:7261 ---> SIP/2.0 488 Not Acceptable Here To: fax ;tag=dd3745a828a6d77do1 From: ;tag=as46f4d96c Call-ID: 4f322544-84e21c91@192.168.20.12 CSeq: 102 INVITE Via: SIP/2.0/UDP 172.16.16.111:5060;branch=z9hG4bK3b734b52 Contact: fax Warning: 304 spa "Media type not available" Server: Linksys/SPA2102-3.3.6 Content-Length: 0 <-------------> [Dec 27 18:30:59] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 0: SIP/2.0 488 Not Acceptable Here (31) [Dec 27 18:30:59] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 1: To: fax ;tag=dd3745a828a6d77do1 (57) [Dec 27 18:30:59] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 2: From: ;tag=as46f4d96c (48) [Dec 27 18:30:59] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 3: Call-ID: 4f322544-84e21c91@192.168.20.12 (40) [Dec 27 18:30:59] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 4: CSeq: 102 INVITE (16) [Dec 27 18:30:59] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 5: Via: SIP/2.0/UDP 172.16.16.111:5060;branch=z9hG4bK3b734b52 (55) [Dec 27 18:30:59] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 6: Contact: fax (45) [Dec 27 18:30:59] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 7: Warning: 304 spa "Media type not available" (43) [Dec 27 18:30:59] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 8: Server: Linksys/SPA2102-3.3.6 (29) [Dec 27 18:30:59] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 9: Content-Length: 0 (17) [Dec 27 18:30:59] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 10: (0) --- (10 headers 0 lines) --- [Dec 27 18:30:59] DEBUG[9953]: chan_sip.c:2053 __sip_ack: Acked pending invite 102 [Dec 27 18:30:59] DEBUG[9953]: chan_sip.c:2061 __sip_ack: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #24 [Dec 27 18:30:59] DEBUG[9953]: chan_sip.c:2071 __sip_ack: Stopping retransmission on '4f322544-84e21c91@192.168.20.12' of Request 102: Match Not Found -- Got SIP response 488 "Not Acceptable Here" back from 10.10.10.23 [Dec 27 18:30:59] DEBUG[9953]: chan_sip.c:5576 reqprep: Strict routing enforced for session 4f322544-84e21c91@192.168.20.12 set_destination: Parsing for address/port to send to set_destination: set destination to 10.10.10.23, port 7261 Transmitting (no NAT) to 10.10.10.23:7261: ACK sip:123456789@10.10.10.23:7261 SIP/2.0 Via: SIP/2.0/UDP 172.16.16.111:5060;branch=z9hG4bK3b734b52;rport From: ;tag=as46f4d96c To: fax ;tag=dd3745a828a6d77do1 Contact: Call-ID: 4f322544-84e21c91@192.168.20.12 CSeq: 102 ACK User-Agent: blabla.ch 1.4PBX Max-Forwards: 70 Content-Length: 0 --- [Dec 27 18:30:59] DEBUG[9953]: chan_sip.c:1615 sip_alreadygone: Setting SIP_ALREADYGONE on dialog 4f322544-84e21c91@192.168.20.12 [Dec 27 18:30:59] DEBUG[9983]: rtp.c:2849 bridge_native_loop: Got a FRAME_CONTROL (8) frame on channel SIP/123456789-081ddcc0 [Dec 27 18:30:59] DEBUG[9983]: channel.c:3924 ast_channel_bridge: Returning from native bridge, channels: SIP/123456789-081ddcc0, SIP/172.16.16.155-081e29c0 [Dec 27 18:30:59] DEBUG[9983]: channel.c:1606 ast_hangup: Hanging up channel 'SIP/172.16.16.155-081e29c0' [Dec 27 18:30:59] DEBUG[9983]: chan_sip.c:3278 sip_hangup: Hangup call SIP/172.16.16.155-081e29c0, SIP callid 237a442d0168be044b12b5b00c645a30@172.16.16.111) [Dec 27 18:30:59] DEBUG[9983]: chan_sip.c:3286 sip_hangup: update_call_counter(0123123123) - decrement call limit counter on hangup [Dec 27 18:30:59] DEBUG[9983]: chan_sip.c:2978 update_call_counter: Updating call counter for incoming call Scheduling destruction of SIP dialog '237a442d0168be044b12b5b00c645a30@172.16.16.111' in 32000 ms (Method: INVITE) [Dec 27 18:30:59] DEBUG[9983]: devicestate.c:303 __ast_device_state_changed_literal: Notification of state change to be queued on device/channel SIP/172.16.16.155-081e29c0 [Dec 27 18:30:59] DEBUG[9983]: rtp.c:1465 ast_rtp_early_bridge: Channel '' has no RTP, not doing anything [Dec 27 18:30:59] DEBUG[9930]: devicestate.c:161 ast_device_state: No provider found, checking channel drivers for SIP - 172.16.16.155 [Dec 27 18:30:59] DEBUG[9930]: chan_sip.c:15048 sip_devicestate: Checking device state for peer 172.16.16.155 [Dec 27 18:30:59] DEBUG[9930]: devicestate.c:287 do_state_change: Changing state for SIP/172.16.16.155 - state 1 (Not in use) [Dec 27 18:30:59] DEBUG[9988]: app_queue.c:546 changethread: Device 'SIP/172.16.16.155' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. [Dec 27 18:30:59] DEBUG[9983]: app_dial.c:1643 dial_exec_full: Exiting with DIALSTATUS=ANSWER. [Dec 27 18:30:59] DEBUG[9983]: pbx.c:2363 __ast_pbx_run: Spawn extension (privileged,0123123123,1) exited non-zero on 'SIP/123456789-081ddcc0' == Spawn extension (privileged, 0123123123, 1) exited non-zero on 'SIP/123456789-081ddcc0' [Dec 27 18:30:59] DEBUG[9983]: pbx.c:1621 pbx_substitute_variables_helper_full: Function result is '"7960@Lab" <0123456789>' [Dec 27 18:30:59] DEBUG[9983]: pbx.c:1621 pbx_substitute_variables_helper_full: Function result is '0123456789' [Dec 27 18:30:59] DEBUG[9983]: pbx.c:1621 pbx_substitute_variables_helper_full: Function result is '0123123123' [Dec 27 18:30:59] DEBUG[9983]: pbx.c:1621 pbx_substitute_variables_helper_full: Function result is 'privileged' [Dec 27 18:30:59] DEBUG[9983]: pbx.c:1621 pbx_substitute_variables_helper_full: Function result is 'SIP/123456789-081ddcc0' [Dec 27 18:30:59] DEBUG[9983]: pbx.c:1621 pbx_substitute_variables_helper_full: Function result is 'SIP/172.16.16.155-081e29c0' [Dec 27 18:30:59] DEBUG[9983]: pbx.c:1621 pbx_substitute_variables_helper_full: Function result is 'Dial' [Dec 27 18:30:59] DEBUG[9983]: pbx.c:1621 pbx_substitute_variables_helper_full: Function result is 'SIP/0123123123@172.16.16.155' [Dec 27 18:30:59] DEBUG[9983]: pbx.c:1621 pbx_substitute_variables_helper_full: Function result is '2006-12-27 18:30:43' [Dec 27 18:30:59] DEBUG[9983]: pbx.c:1621 pbx_substitute_variables_helper_full: Function result is '2006-12-27 18:30:51' [Dec 27 18:30:59] DEBUG[9983]: pbx.c:1621 pbx_substitute_variables_helper_full: Function result is '2006-12-27 18:30:59' [Dec 27 18:30:59] DEBUG[9983]: pbx.c:1621 pbx_substitute_variables_helper_full: Function result is '16' [Dec 27 18:30:59] DEBUG[9983]: pbx.c:1621 pbx_substitute_variables_helper_full: Function result is '8' [Dec 27 18:30:59] DEBUG[9983]: pbx.c:1621 pbx_substitute_variables_helper_full: Function result is 'ANSWERED' [Dec 27 18:30:59] DEBUG[9983]: pbx.c:1621 pbx_substitute_variables_helper_full: Function result is 'DOCUMENTATION' [Dec 27 18:30:59] DEBUG[9983]: pbx.c:1621 pbx_substitute_variables_helper_full: Function result is '' [Dec 27 18:30:59] DEBUG[9983]: pbx.c:1621 pbx_substitute_variables_helper_full: Function result is '1167240643.0' [Dec 27 18:30:59] DEBUG[9983]: pbx.c:1621 pbx_substitute_variables_helper_full: Function result is '' [Dec 27 18:30:59] DEBUG[9983]: channel.c:1606 ast_hangup: Hanging up channel 'SIP/123456789-081ddcc0' [Dec 27 18:30:59] DEBUG[9983]: chan_sip.c:3278 sip_hangup: Hangup call SIP/123456789-081ddcc0, SIP callid 4f322544-84e21c91@192.168.20.12) [Dec 27 18:30:59] DEBUG[9983]: chan_sip.c:3286 sip_hangup: update_call_counter(123456789) - decrement call limit counter on hangup [Dec 27 18:30:59] DEBUG[9983]: chan_sip.c:2978 update_call_counter: Updating call counter for outgoing call [Dec 27 18:30:59] DEBUG[9983]: devicestate.c:303 __ast_device_state_changed_literal: Notification of state change to be queued on device/channel SIP/123456789-081ddcc0 [Dec 27 18:30:59] DEBUG[9930]: devicestate.c:161 ast_device_state: No provider found, checking channel drivers for SIP - 123456789 [Dec 27 18:30:59] DEBUG[9930]: chan_sip.c:15048 sip_devicestate: Checking device state for peer 123456789 [Dec 27 18:30:59] DEBUG[9930]: devicestate.c:287 do_state_change: Changing state for SIP/123456789 - state 1 (Not in use) [Dec 27 18:30:59] DEBUG[9989]: app_queue.c:546 changethread: Device 'SIP/123456789' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. [Dec 27 18:30:59] DEBUG[9930]: devicestate.c:161 ast_device_state: No provider found, checking channel drivers for SIP - 123456789 [Dec 27 18:30:59] DEBUG[9930]: chan_sip.c:15048 sip_devicestate: Checking device state for peer 123456789 uboot*CLI> <--- SIP read from 172.16.16.155:5060 ---> INVITE sip:0123456789@172.16.16.111 SIP/2.0 Via: SIP/2.0/UDP 172.16.16.155:5060;branch=z9hG4bK7g18dd3020tgub8ib5k0sb0000g00.1 From: ;tag=1017816372 To: "7960@Lab" ;tag=as77ca8eeb Call-ID: 237a442d0168be044b12b5b00c645a30@172.16.16.111 CSeq: 1 INVITE Supported: timer Contact: Allow: INVITE,ACK,PRACK,SUBSCRIBE,BYE,CANCEL,NOTIFY,INFO,REFER,UPDATE Max-Forwards: 69 ontent-Type: application/sdp Content-Length: 236 v=0 o=- 565 1 IN IP4 172.16.16.155 s=Cisco SDP 0 c=IN IP4 172.16.16.155 t=0 0 m=image 16004 udptl t38 a=X-sqn:0 a=X-cap: 1 audio RTP/AVP 100 a=X-cpar: a=rtpmap:100 X-NSE/8000 a=X-cpar: a=fmtp:100 200-202 a=X-cap: 2 image udptl t38 <-------------> [Dec 27 18:31:00] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 0: INVITE sip:0123456789@172.16.16.111 SIP/2.0 (40) [Dec 27 18:31:00] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 1: Via: SIP/2.0/UDP 172.16.16.155:5060;branch=z9hG4bK7g18dd3020tgub8ib5k0sb0000g00.1 (78) [Dec 27 18:31:00] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 2: From: ;tag=1017816372 (48) [Dec 27 18:31:00] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 3: To: "7960@Lab" ;tag=as77ca8eeb (57) [Dec 27 18:31:00] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 4: Call-ID: 237a442d0168be044b12b5b00c645a30@172.16.16.111 (52) [Dec 27 18:31:00] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 5: CSeq: 1 INVITE (14) [Dec 27 18:31:00] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 6: Supported: timer (16) [Dec 27 18:31:00] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 7: Contact: (59) [Dec 27 18:31:00] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 8: Allow: INVITE,ACK,PRACK,SUBSCRIBE,BYE,CANCEL,NOTIFY,INFO,REFER,UPDATE (69) [Dec 27 18:31:00] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 9: Max-Forwards: 69 (16) [Dec 27 18:31:00] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 10: Content-Type: application/sdp (29) [Dec 27 18:31:00] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 11: Content-Length: 236 (19) [Dec 27 18:31:00] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 12: (0) [Dec 27 18:31:00] DEBUG[9953]: chan_sip.c:4559 parse_request: Line: v=0 (3) [Dec 27 18:31:00] DEBUG[9953]: chan_sip.c:4559 parse_request: Line: o=- 565 1 IN IP4 172.16.16.155 (27) [Dec 27 18:31:00] DEBUG[9953]: chan_sip.c:4559 parse_request: Line: s=Cisco SDP 0 (13) [Dec 27 18:31:00] DEBUG[9953]: chan_sip.c:4559 parse_request: Line: c=IN IP4 172.16.16.155 (19) [Dec 27 18:31:00] DEBUG[9953]: chan_sip.c:4559 parse_request: Line: t=0 0 (5) [Dec 27 18:31:00] DEBUG[9953]: chan_sip.c:4559 parse_request: Line: m=image 16004 udptl t38 (23) [Dec 27 18:31:00] DEBUG[9953]: chan_sip.c:4559 parse_request: Line: a=X-sqn:0 (9) [Dec 27 18:31:00] DEBUG[9953]: chan_sip.c:4559 parse_request: Line: a=X-cap: 1 audio RTP/AVP 100 (28) [Dec 27 18:31:00] DEBUG[9953]: chan_sip.c:4559 parse_request: Line: a=X-cpar: a=rtpmap:100 X-NSE/8000 (33) [Dec 27 18:31:00] DEBUG[9953]: chan_sip.c:4559 parse_request: Line: a=X-cpar: a=fmtp:100 200-202 (28) [Dec 27 18:31:00] DEBUG[9953]: chan_sip.c:4559 parse_request: Line: a=X-cap: 2 image udptl t38 (26) --- (12 headers 11 lines) --- [Dec 27 18:31:00] DEBUG[9953]: chan_sip.c:14453 handle_request: **** Received INVITE (5) - Command in SIP INVITE [Dec 27 18:31:00] DEBUG[9953]: chan_sip.c:14472 handle_request: Ignoring SIP message because of retransmit (INVITE Seqno 1, ours 1) Ignoring this INVITE request [Dec 27 18:31:00] DEBUG[9953]: chan_sip.c:13290 handle_request_invite: Got a SIP re-transmit of INVITE for call 237a442d0168be044b12b5b00c645a30@172.16.16.111 [Dec 27 18:31:00] NOTICE[9953]: chan_sip.c:13479 handle_request_invite: Unable to create/find SIP channel for this INVITE uboot*CLI> <--- Transmitting (no NAT) to 172.16.16.155:5060 ---> SIP/2.0 503 Unavailable Via: SIP/2.0/UDP 172.16.16.155:5060;branch=z9hG4bK7g18dd3020tgub8ib5k0sb0000g00.1;received=172.16.16.155 From: ;tag=1017816372 To: "7960@Lab" ;tag=as77ca8eeb Call-ID: 237a442d0168be044b12b5b00c645a30@172.16.16.111 CSeq: 1 INVITE User-Agent: blabla.ch 1.4PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Contact: Content-Length: 0 <------------> Scheduling destruction of SIP dialog '237a442d0168be044b12b5b00c645a30@172.16.16.111' in 32000 ms (Method: INVITE) Really destroying SIP dialog '4f322544-84e21c91@192.168.20.12' Method: ACK uboot*CLI> <--- SIP read from 172.16.16.155:5060 ---> ACK sip:0123456789@172.16.16.111 SIP/2.0 Via: SIP/2.0/UDP 172.16.16.155:5060;branch=z9hG4bK7g18dd3020tgub8ib5k0sb0000g00.1 CSeq: 1 ACK From: ;tag=1017816372 To: "7960@Lab" ;tag=as77ca8eeb Call-ID: 237a442d0168be044b12b5b00c645a30@172.16.16.111 Max-Forwards: 69 Content-Length: 0 <-------------> [Dec 27 18:31:00] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 0: ACK sip:0123456789@172.16.16.111 SIP/2.0 (37) [Dec 27 18:31:00] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 1: Via: SIP/2.0/UDP 172.16.16.155:5060;branch=z9hG4bK7g18dd3020tgub8ib5k0sb0000g00.1 (78) [Dec 27 18:31:00] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 2: CSeq: 1 ACK (11) [Dec 27 18:31:00] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 3: From: ;tag=1017816372 (48) [Dec 27 18:31:00] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 4: To: "7960@Lab" ;tag=as77ca8eeb (57) [Dec 27 18:31:00] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 5: Call-ID: 237a442d0168be044b12b5b00c645a30@172.16.16.111 (52) [Dec 27 18:31:00] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 6: Max-Forwards: 69 (16) [Dec 27 18:31:00] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 7: Content-Length: 0 (17) [Dec 27 18:31:00] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 8: (0) --- (8 headers 0 lines) --- [Dec 27 18:31:00] DEBUG[9953]: chan_sip.c:14453 handle_request: **** Received ACK (6) - Command in SIP ACK [Dec 27 18:31:00] DEBUG[9953]: chan_sip.c:2071 __sip_ack: Stopping retransmission on '237a442d0168be044b12b5b00c645a30@172.16.16.111' of Response 1: Match Found [Dec 27 18:31:00] DEBUG[9953]: chan_sip.c:5576 reqprep: Strict routing enforced for session 237a442d0168be044b12b5b00c645a30@172.16.16.111 set_destination: Parsing for address/port to send to set_destination: set destination to 172.16.16.155, port 5060 Reliably Transmitting (no NAT) to 172.16.16.155:5060: BYE sip:0123123123@172.16.16.155:5060;transport=udp SIP/2.0 Via: SIP/2.0/UDP 172.16.16.111:5060;branch=z9hG4bK0b9ae2ac;rport From: "7960@Lab" ;tag=as77ca8eeb To: ;tag=1017816372 Call-ID: 237a442d0168be044b12b5b00c645a30@172.16.16.111 CSeq: 103 BYE User-Agent: blabla.ch 1.4PBX Max-Forwards: 70 Content-Length: 0 --- [Dec 27 18:31:00] DEBUG[9953]: chan_sip.c:1957 __sip_reliable_xmit: *** SIP TIMER: Initalizing retransmit timer on packet: Id #27 Scheduling destruction of SIP dialog '237a442d0168be044b12b5b00c645a30@172.16.16.111' in 32000 ms (Method: ACK) [Dec 27 18:31:01] DEBUG[9953]: chan_sip.c:1848 retrans_pkt: SIP TIMER: Rescheduling retransmission #27 (1) BYE - 8 [Dec 27 18:31:01] DEBUG[9953]: chan_sip.c:1862 retrans_pkt: ** SIP timers: Rescheduling retransmission 2 to 1000 ms (t1 500 ms (Retrans id #27)) Retransmitting #1 (no NAT) to 172.16.16.155:5060: BYE sip:0123123123@172.16.16.155:5060;transport=udp SIP/2.0 Via: SIP/2.0/UDP 172.16.16.111:5060;branch=z9hG4bK0b9ae2ac;rport From: "7960@Lab" ;tag=as77ca8eeb To: ;tag=1017816372 Call-ID: 237a442d0168be044b12b5b00c645a30@172.16.16.111 CSeq: 103 BYE User-Agent: blabla.ch 1.4PBX Max-Forwards: 70 Content-Length: 0 --- uboot*CLI> <--- SIP read from 172.16.16.155:5060 ---> SIP/2.0 200 Ok Via: SIP/2.0/UDP 172.16.16.111:5060;received=172.16.16.111;branch=z9hG4bK0b9ae2ac;rport=5060 From: "7960@Lab" ;tag=as77ca8eeb To: ;tag=1017816372 Call-ID: 237a442d0168be044b12b5b00c645a30@172.16.16.111 CSeq: 103 BYE Content-Length: 0 <-------------> [Dec 27 18:31:01] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 0: SIP/2.0 200 Ok (14) [Dec 27 18:31:01] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 1: Via: SIP/2.0/UDP 172.16.16.111:5060;received=172.16.16.111;branch=z9hG4bK0b9ae2ac;rport=5060 (86) [Dec 27 18:31:01] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 2: From: "7960@Lab" ;tag=as77ca8eeb (59) [Dec 27 18:31:01] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 3: To: ;tag=1017816372 (46) [Dec 27 18:31:01] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 4: Call-ID: 237a442d0168be044b12b5b00c645a30@172.16.16.111 (52) [Dec 27 18:31:01] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 5: CSeq: 103 BYE (13) [Dec 27 18:31:01] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 6: Content-Length: 0 (17) [Dec 27 18:31:01] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 7: (0) --- (7 headers 0 lines) --- [Dec 27 18:31:01] DEBUG[9953]: chan_sip.c:2061 __sip_ack: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #27 [Dec 27 18:31:01] DEBUG[9953]: chan_sip.c:2071 __sip_ack: Stopping retransmission on '237a442d0168be044b12b5b00c645a30@172.16.16.111' of Request 103: Match Not Found SIP Response message for INCOMING dialog BYE arrived Really destroying SIP dialog '237a442d0168be044b12b5b00c645a30@172.16.16.111' Method: ACK uboot*CLI> <--- SIP read from 10.10.10.23:7250 ---> NOTIFY sip:172.16.16.111 SIP/2.0 Via: SIP/2.0/UDP 10.10.10.23:7251;branch=z9hG4bK-3c34fa7e From: alx ;tag=dc778fd1839473do0 To: Call-ID: 75ea1f55-8603187f@192.168.20.12 CSeq: 4033 NOTIFY Max-Forwards: 70 Event: keep-alive User-Agent: Linksys/SPA2102-3.3.6 Content-Length: 0 <-------------> [Dec 27 18:31:05] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 0: NOTIFY sip:172.16.16.111 SIP/2.0 (29) [Dec 27 18:31:05] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 1: Via: SIP/2.0/UDP 10.10.10.23:7251;branch=z9hG4bK-3c34fa7e (57) [Dec 27 18:31:05] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 2: From: alx ;tag=dc778fd1839473do0 (58) [Dec 27 18:31:05] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 3: To: (20) [Dec 27 18:31:05] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 4: Call-ID: 75ea1f55-8603187f@192.168.20.12 (40) [Dec 27 18:31:05] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 5: CSeq: 4033 NOTIFY (17) [Dec 27 18:31:05] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 6: Max-Forwards: 70 (16) [Dec 27 18:31:05] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 7: Event: keep-alive (17) [Dec 27 18:31:05] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 8: User-Agent: Linksys/SPA2102-3.3.6 (33) [Dec 27 18:31:05] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 9: Content-Length: 0 (17) [Dec 27 18:31:05] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 10: (0) --- (10 headers 0 lines) --- uboot*CLI> <--- Transmitting (no NAT) to 10.10.10.23:7250 ---> SIP/2.0 489 Bad event Via: SIP/2.0/UDP 10.10.10.23:7251;branch=z9hG4bK-3c34fa7e;received=10.10.10.23 From: alx ;tag=dc778fd1839473do0 To: ;tag=as120cbf84 Call-ID: 75ea1f55-8603187f@192.168.20.12 CSeq: 4033 NOTIFY User-Agent: blabla.ch 1.4PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Content-Length: 0 <------------> [Dec 27 18:31:05] DEBUG[9953]: chan_sip.c:14631 sipsock_read: Invalid SIP message - rejected , no callid, len 326 uboot*CLI> <--- SIP read from 10.10.10.23:7261 ---> NOTIFY sip:172.16.16.111 SIP/2.0 Via: SIP/2.0/UDP 10.10.10.23:7261;branch=z9hG4bK-4615c15a From: fax ;tag=268feafdffbb7d3do1 To: Call-ID: 75bd615d-2ad57e17@192.168.20.12 CSeq: 4033 NOTIFY Max-Forwards: 70 Event: keep-alive User-Agent: Linksys/SPA2102-3.3.6 Content-Length: 0 <-------------> [Dec 27 18:31:05] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 0: NOTIFY sip:172.16.16.111 SIP/2.0 (29) [Dec 27 18:31:05] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 1: Via: SIP/2.0/UDP 10.10.10.23:7261;branch=z9hG4bK-4615c15a (57) [Dec 27 18:31:05] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 2: From: fax ;tag=268feafdffbb7d3do1 (59) [Dec 27 18:31:05] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 3: To: (20) [Dec 27 18:31:05] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 4: Call-ID: 75bd615d-2ad57e17@192.168.20.12 (40) [Dec 27 18:31:05] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 5: CSeq: 4033 NOTIFY (17) [Dec 27 18:31:05] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 6: Max-Forwards: 70 (16) [Dec 27 18:31:05] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 7: Event: keep-alive (17) [Dec 27 18:31:05] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 8: User-Agent: Linksys/SPA2102-3.3.6 (33) [Dec 27 18:31:05] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 9: Content-Length: 0 (17) [Dec 27 18:31:05] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 10: (0) --- (10 headers 0 lines) --- <--- Transmitting (no NAT) to 10.10.10.23:7261 ---> SIP/2.0 489 Bad event Via: SIP/2.0/UDP 10.10.10.23:7261;branch=z9hG4bK-4615c15a;received=10.10.10.23 From: fax ;tag=268feafdffbb7d3do1 To: ;tag=as2adaa94e Call-ID: 75bd615d-2ad57e17@192.168.20.12 CSeq: 4033 NOTIFY User-Agent: blabla.ch 1.4PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Content-Length: 0 <------------> [Dec 27 18:31:05] DEBUG[9953]: chan_sip.c:14631 sipsock_read: Invalid SIP message - rejected , no callid, len 327 uboot*CLI> <--- SIP read from 10.10.10.23:7250 ---> NOTIFY sip:172.16.16.111 SIP/2.0 Via: SIP/2.0/UDP 10.10.10.23:7251;branch=z9hG4bK-a4b24df4 From: alx ;tag=dc778fd1839473do0 To: Call-ID: 75ea1f55-8603187f@192.168.20.12 CSeq: 4034 NOTIFY Max-Forwards: 70 Event: keep-alive User-Agent: Linksys/SPA2102-3.3.6 Content-Length: 0 <-------------> [Dec 27 18:31:20] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 0: NOTIFY sip:172.16.16.111 SIP/2.0 (29) [Dec 27 18:31:20] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 1: Via: SIP/2.0/UDP 10.10.10.23:7251;branch=z9hG4bK-a4b24df4 (57) [Dec 27 18:31:20] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 2: From: alx ;tag=dc778fd1839473do0 (58) [Dec 27 18:31:20] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 3: To: (20) [Dec 27 18:31:20] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 4: Call-ID: 75ea1f55-8603187f@192.168.20.12 (40) [Dec 27 18:31:20] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 5: CSeq: 4034 NOTIFY (17) [Dec 27 18:31:20] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 6: Max-Forwards: 70 (16) [Dec 27 18:31:20] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 7: Event: keep-alive (17) [Dec 27 18:31:20] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 8: User-Agent: Linksys/SPA2102-3.3.6 (33) [Dec 27 18:31:20] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 9: Content-Length: 0 (17) [Dec 27 18:31:20] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 10: (0) --- (10 headers 0 lines) --- uboot*CLI> <--- Transmitting (no NAT) to 10.10.10.23:7250 ---> SIP/2.0 489 Bad event Via: SIP/2.0/UDP 10.10.10.23:7251;branch=z9hG4bK-a4b24df4;received=10.10.10.23 From: alx ;tag=dc778fd1839473do0 To: ;tag=as0fee648e Call-ID: 75ea1f55-8603187f@192.168.20.12 CSeq: 4034 NOTIFY User-Agent: blabla.ch 1.4PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Content-Length: 0 <------------> [Dec 27 18:31:20] DEBUG[9953]: chan_sip.c:14631 sipsock_read: Invalid SIP message - rejected , no callid, len 326 uboot*CLI> <--- SIP read from 10.10.10.23:7261 ---> NOTIFY sip:172.16.16.111 SIP/2.0 Via: SIP/2.0/UDP 10.10.10.23:7261;branch=z9hG4bK-83189a3c From: fax ;tag=268feafdffbb7d3do1 To: Call-ID: 75bd615d-2ad57e17@192.168.20.12 CSeq: 4034 NOTIFY Max-Forwards: 70 Event: keep-alive User-Agent: Linksys/SPA2102-3.3.6 Content-Length: 0 <-------------> [Dec 27 18:31:20] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 0: NOTIFY sip:172.16.16.111 SIP/2.0 (29) [Dec 27 18:31:20] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 1: Via: SIP/2.0/UDP 10.10.10.23:7261;branch=z9hG4bK-83189a3c (57) [Dec 27 18:31:20] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 2: From: fax ;tag=268feafdffbb7d3do1 (59) [Dec 27 18:31:20] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 3: To: (20) [Dec 27 18:31:20] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 4: Call-ID: 75bd615d-2ad57e17@192.168.20.12 (40) [Dec 27 18:31:20] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 5: CSeq: 4034 NOTIFY (17) [Dec 27 18:31:20] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 6: Max-Forwards: 70 (16) [Dec 27 18:31:20] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 7: Event: keep-alive (17) [Dec 27 18:31:20] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 8: User-Agent: Linksys/SPA2102-3.3.6 (33) [Dec 27 18:31:20] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 9: Content-Length: 0 (17) [Dec 27 18:31:20] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 10: (0) --- (10 headers 0 lines) --- <--- Transmitting (no NAT) to 10.10.10.23:7261 ---> SIP/2.0 489 Bad event Via: SIP/2.0/UDP 10.10.10.23:7261;branch=z9hG4bK-83189a3c;received=10.10.10.23 From: fax ;tag=268feafdffbb7d3do1 To: ;tag=as55f43668 Call-ID: 75bd615d-2ad57e17@192.168.20.12 CSeq: 4034 NOTIFY User-Agent: blabla.ch 1.4PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Content-Length: 0 <------------> [Dec 27 18:31:20] DEBUG[9953]: chan_sip.c:14631 sipsock_read: Invalid SIP message - rejected , no callid, len 327 uboot*CLI> <--- SIP read from 10.10.10.23:7250 ---> NOTIFY sip:172.16.16.111 SIP/2.0 Via: SIP/2.0/UDP 10.10.10.23:7251;branch=z9hG4bK-c149805a From: alx ;tag=dc778fd1839473do0 To: Call-ID: 75ea1f55-8603187f@192.168.20.12 CSeq: 4035 NOTIFY Max-Forwards: 70 Event: keep-alive User-Agent: Linksys/SPA2102-3.3.6 Content-Length: 0 <-------------> [Dec 27 18:31:35] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 0: NOTIFY sip:172.16.16.111 SIP/2.0 (29) [Dec 27 18:31:35] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 1: Via: SIP/2.0/UDP 10.10.10.23:7251;branch=z9hG4bK-c149805a (57) [Dec 27 18:31:35] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 2: From: alx ;tag=dc778fd1839473do0 (58) [Dec 27 18:31:35] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 3: To: (20) [Dec 27 18:31:35] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 4: Call-ID: 75ea1f55-8603187f@192.168.20.12 (40) [Dec 27 18:31:35] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 5: CSeq: 4035 NOTIFY (17) [Dec 27 18:31:35] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 6: Max-Forwards: 70 (16) [Dec 27 18:31:35] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 7: Event: keep-alive (17) [Dec 27 18:31:35] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 8: User-Agent: Linksys/SPA2102-3.3.6 (33) [Dec 27 18:31:35] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 9: Content-Length: 0 (17) [Dec 27 18:31:35] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 10: (0) --- (10 headers 0 lines) --- <--- Transmitting (no NAT) to 10.10.10.23:7250 ---> SIP/2.0 489 Bad event Via: SIP/2.0/UDP 10.10.10.23:7251;branch=z9hG4bK-c149805a;received=10.10.10.23 From: alx ;tag=dc778fd1839473do0 To: ;tag=as7edf0a6b Call-ID: 75ea1f55-8603187f@192.168.20.12 CSeq: 4035 NOTIFY User-Agent: blabla.ch 1.4PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Content-Length: 0 <------------> [Dec 27 18:31:35] DEBUG[9953]: chan_sip.c:14631 sipsock_read: Invalid SIP message - rejected , no callid, len 326 uboot*CLI> <--- SIP read from 10.10.10.23:7261 ---> BYE sip:0123123123@172.16.16.111 SIP/2.0 Via: SIP/2.0/UDP 10.10.10.23:7261;branch=z9hG4bK-b179a0d4 From: fax ;tag=dd3745a828a6d77do1 To: ;tag=as46f4d96c Call-ID: 4f322544-84e21c91@192.168.20.12 CSeq: 104 BYE Max-Forwards: 70 Proxy-Authorization: Digest username="123456789",realm="blabla.ch",nonce="11068d8b",uri="sip:0123123123@172.16.16.111",algorithm=MD5,response="0b7730bad18830d2258194efbb9f2d02" User-Agent: Linksys/SPA2102-3.3.6 Content-Length: 0 <-------------> [Dec 27 18:31:35] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 0: BYE sip:0123123123@172.16.16.111 SIP/2.0 (37) [Dec 27 18:31:35] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 1: Via: SIP/2.0/UDP 10.10.10.23:7261;branch=z9hG4bK-b179a0d4 (57) [Dec 27 18:31:35] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 2: From: fax ;tag=dd3745a828a6d77do1 (59) [Dec 27 18:31:35] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 3: To: ;tag=as46f4d96c (46) [Dec 27 18:31:35] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 4: Call-ID: 4f322544-84e21c91@192.168.20.12 (40) [Dec 27 18:31:35] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 5: CSeq: 104 BYE (13) [Dec 27 18:31:35] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 6: Max-Forwards: 70 (16) [Dec 27 18:31:35] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 7: Proxy-Authorization: Digest username="123456789",realm="blabla.ch",nonce="11068d8b",uri="sip:0123123123@172.16.16.111",algorithm=MD5,response="0b7730bad18830d2258194efbb9f2d02" (171) [Dec 27 18:31:35] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 8: User-Agent: Linksys/SPA2102-3.3.6 (33) [Dec 27 18:31:35] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 9: Content-Length: 0 (17) [Dec 27 18:31:35] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 10: (0) --- (10 headers 0 lines) --- <--- Transmitting (no NAT) to 10.10.10.23:7261 ---> SIP/2.0 481 Call leg/transaction does not exist Via: SIP/2.0/UDP 10.10.10.23:7261;branch=z9hG4bK-b179a0d4;received=10.10.10.23 From: fax ;tag=dd3745a828a6d77do1 To: ;tag=as46f4d96c Call-ID: 4f322544-84e21c91@192.168.20.12 CSeq: 104 BYE User-Agent: blabla.ch 1.4PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Content-Length: 0 <------------> [Dec 27 18:31:35] DEBUG[9953]: chan_sip.c:14631 sipsock_read: Invalid SIP message - rejected , no callid, len 511 uboot*CLI> <--- SIP read from 10.10.10.23:7261 ---> NOTIFY sip:172.16.16.111 SIP/2.0 Via: SIP/2.0/UDP 10.10.10.23:7261;branch=z9hG4bK-31ce6abb From: fax ;tag=268feafdffbb7d3do1 To: Call-ID: 75bd615d-2ad57e17@192.168.20.12 CSeq: 4035 NOTIFY Max-Forwards: 70 Event: keep-alive User-Agent: Linksys/SPA2102-3.3.6 Content-Length: 0 <-------------> [Dec 27 18:31:35] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 0: NOTIFY sip:172.16.16.111 SIP/2.0 (29) [Dec 27 18:31:35] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 1: Via: SIP/2.0/UDP 10.10.10.23:7261;branch=z9hG4bK-31ce6abb (57) [Dec 27 18:31:35] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 2: From: fax ;tag=268feafdffbb7d3do1 (59) [Dec 27 18:31:35] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 3: To: (20) [Dec 27 18:31:35] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 4: Call-ID: 75bd615d-2ad57e17@192.168.20.12 (40) [Dec 27 18:31:35] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 5: CSeq: 4035 NOTIFY (17) [Dec 27 18:31:35] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 6: Max-Forwards: 70 (16) [Dec 27 18:31:35] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 7: Event: keep-alive (17) [Dec 27 18:31:35] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 8: User-Agent: Linksys/SPA2102-3.3.6 (33) [Dec 27 18:31:35] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 9: Content-Length: 0 (17) [Dec 27 18:31:35] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 10: (0) --- (10 headers 0 lines) --- <--- Transmitting (no NAT) to 10.10.10.23:7261 ---> SIP/2.0 489 Bad event Via: SIP/2.0/UDP 10.10.10.23:7261;branch=z9hG4bK-31ce6abb;received=10.10.10.23 From: fax ;tag=268feafdffbb7d3do1 To: ;tag=as7762da30 Call-ID: 75bd615d-2ad57e17@192.168.20.12 CSeq: 4035 NOTIFY User-Agent: blabla.ch 1.4PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Content-Length: 0 <------------> [Dec 27 18:31:35] DEBUG[9953]: chan_sip.c:14631 sipsock_read: Invalid SIP message - rejected , no callid, len 327 uboot*CLI> <--- SIP read from 10.10.10.23:7250 ---> NOTIFY sip:172.16.16.111 SIP/2.0 Via: SIP/2.0/UDP 10.10.10.23:7251;branch=z9hG4bK-17ae57c5 From: alx ;tag=dc778fd1839473do0 To: Call-ID: 75ea1f55-8603187f@192.168.20.12 CSeq: 4036 NOTIFY Max-Forwards: 70 Event: keep-alive User-Agent: Linksys/SPA2102-3.3.6 Content-Length: 0 <-------------> [Dec 27 18:31:50] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 0: NOTIFY sip:172.16.16.111 SIP/2.0 (29) [Dec 27 18:31:50] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 1: Via: SIP/2.0/UDP 10.10.10.23:7251;branch=z9hG4bK-17ae57c5 (57) [Dec 27 18:31:50] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 2: From: alx ;tag=dc778fd1839473do0 (58) [Dec 27 18:31:50] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 3: To: (20) [Dec 27 18:31:50] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 4: Call-ID: 75ea1f55-8603187f@192.168.20.12 (40) [Dec 27 18:31:50] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 5: CSeq: 4036 NOTIFY (17) [Dec 27 18:31:50] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 6: Max-Forwards: 70 (16) [Dec 27 18:31:50] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 7: Event: keep-alive (17) [Dec 27 18:31:50] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 8: User-Agent: Linksys/SPA2102-3.3.6 (33) [Dec 27 18:31:50] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 9: Content-Length: 0 (17) [Dec 27 18:31:50] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 10: (0) --- (10 headers 0 lines) --- <--- Transmitting (no NAT) to 10.10.10.23:7250 ---> SIP/2.0 489 Bad event Via: SIP/2.0/UDP 10.10.10.23:7251;branch=z9hG4bK-17ae57c5;received=10.10.10.23 From: alx ;tag=dc778fd1839473do0 To: ;tag=as03fbd85d Call-ID: 75ea1f55-8603187f@192.168.20.12 CSeq: 4036 NOTIFY User-Agent: blabla.ch 1.4PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Content-Length: 0 <------------> [Dec 27 18:31:50] DEBUG[9953]: chan_sip.c:14631 sipsock_read: Invalid SIP message - rejected , no callid, len 326 uboot*CLI> <--- SIP read from 10.10.10.23:7261 ---> NOTIFY sip:172.16.16.111 SIP/2.0 Via: SIP/2.0/UDP 10.10.10.23:7261;branch=z9hG4bK-d3bb3e5c From: fax ;tag=268feafdffbb7d3do1 To: Call-ID: 75bd615d-2ad57e17@192.168.20.12 CSeq: 4036 NOTIFY Max-Forwards: 70 Event: keep-alive User-Agent: Linksys/SPA2102-3.3.6 Content-Length: 0 <-------------> [Dec 27 18:31:50] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 0: NOTIFY sip:172.16.16.111 SIP/2.0 (29) [Dec 27 18:31:50] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 1: Via: SIP/2.0/UDP 10.10.10.23:7261;branch=z9hG4bK-d3bb3e5c (57) [Dec 27 18:31:50] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 2: From: fax ;tag=268feafdffbb7d3do1 (59) [Dec 27 18:31:50] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 3: To: (20) [Dec 27 18:31:50] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 4: Call-ID: 75bd615d-2ad57e17@192.168.20.12 (40) [Dec 27 18:31:50] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 5: CSeq: 4036 NOTIFY (17) [Dec 27 18:31:50] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 6: Max-Forwards: 70 (16) [Dec 27 18:31:50] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 7: Event: keep-alive (17) [Dec 27 18:31:50] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 8: User-Agent: Linksys/SPA2102-3.3.6 (33) [Dec 27 18:31:50] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 9: Content-Length: 0 (17) [Dec 27 18:31:50] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 10: (0) --- (10 headers 0 lines) --- uboot*CLI> <--- Transmitting (no NAT) to 10.10.10.23:7261 ---> SIP/2.0 489 Bad event Via: SIP/2.0/UDP 10.10.10.23:7261;branch=z9hG4bK-d3bb3e5c;received=10.10.10.23 From: fax ;tag=268feafdffbb7d3do1 To: ;tag=as0f26032a Call-ID: 75bd615d-2ad57e17@192.168.20.12 CSeq: 4036 NOTIFY User-Agent: blabla.ch 1.4PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Content-Length: 0 <------------> [Dec 27 18:31:50] DEBUG[9953]: chan_sip.c:14631 sipsock_read: Invalid SIP message - rejected , no callid, len 327 uboot*CLI> <--- SIP read from 10.10.10.23:7250 ---> NOTIFY sip:172.16.16.111 SIP/2.0 Via: SIP/2.0/UDP 10.10.10.23:7251;branch=z9hG4bK-3bda3676 From: alx ;tag=dc778fd1839473do0 To: Call-ID: 75ea1f55-8603187f@192.168.20.12 CSeq: 4037 NOTIFY Max-Forwards: 70 Event: keep-alive User-Agent: Linksys/SPA2102-3.3.6 Content-Length: 0 <-------------> [Dec 27 18:32:05] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 0: NOTIFY sip:172.16.16.111 SIP/2.0 (29) [Dec 27 18:32:05] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 1: Via: SIP/2.0/UDP 10.10.10.23:7251;branch=z9hG4bK-3bda3676 (57) [Dec 27 18:32:05] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 2: From: alx ;tag=dc778fd1839473do0 (58) [Dec 27 18:32:05] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 3: To: (20) [Dec 27 18:32:05] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 4: Call-ID: 75ea1f55-8603187f@192.168.20.12 (40) [Dec 27 18:32:05] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 5: CSeq: 4037 NOTIFY (17) [Dec 27 18:32:05] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 6: Max-Forwards: 70 (16) [Dec 27 18:32:05] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 7: Event: keep-alive (17) [Dec 27 18:32:05] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 8: User-Agent: Linksys/SPA2102-3.3.6 (33) [Dec 27 18:32:05] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 9: Content-Length: 0 (17) [Dec 27 18:32:05] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 10: (0) --- (10 headers 0 lines) --- uboot*CLI> <--- Transmitting (no NAT) to 10.10.10.23:7250 ---> SIP/2.0 489 Bad event Via: SIP/2.0/UDP 10.10.10.23:7251;branch=z9hG4bK-3bda3676;received=10.10.10.23 From: alx ;tag=dc778fd1839473do0 To: ;tag=as0d5286b2 Call-ID: 75ea1f55-8603187f@192.168.20.12 CSeq: 4037 NOTIFY User-Agent: blabla.ch 1.4PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Content-Length: 0 <------------> [Dec 27 18:32:05] DEBUG[9953]: chan_sip.c:14631 sipsock_read: Invalid SIP message - rejected , no callid, len 326 uboot*CLI> <--- SIP read from 10.10.10.23:7261 ---> NOTIFY sip:172.16.16.111 SIP/2.0 Via: SIP/2.0/UDP 10.10.10.23:7261;branch=z9hG4bK-5cf50d3e From: fax ;tag=268feafdffbb7d3do1 To: Call-ID: 75bd615d-2ad57e17@192.168.20.12 CSeq: 4037 NOTIFY Max-Forwards: 70 Event: keep-alive User-Agent: Linksys/SPA2102-3.3.6 Content-Length: 0 <-------------> [Dec 27 18:32:05] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 0: NOTIFY sip:172.16.16.111 SIP/2.0 (29) [Dec 27 18:32:05] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 1: Via: SIP/2.0/UDP 10.10.10.23:7261;branch=z9hG4bK-5cf50d3e (57) [Dec 27 18:32:05] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 2: From: fax ;tag=268feafdffbb7d3do1 (59) [Dec 27 18:32:05] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 3: To: (20) [Dec 27 18:32:05] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 4: Call-ID: 75bd615d-2ad57e17@192.168.20.12 (40) [Dec 27 18:32:05] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 5: CSeq: 4037 NOTIFY (17) [Dec 27 18:32:05] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 6: Max-Forwards: 70 (16) [Dec 27 18:32:05] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 7: Event: keep-alive (17) [Dec 27 18:32:05] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 8: User-Agent: Linksys/SPA2102-3.3.6 (33) [Dec 27 18:32:05] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 9: Content-Length: 0 (17) [Dec 27 18:32:05] DEBUG[9953]: chan_sip.c:4527 parse_request: Header 10: (0) --- (10 headers 0 lines) --- <--- Transmitting (no NAT) to 10.10.10.23:7261 ---> SIP/2.0 489 Bad event Via: SIP/2.0/UDP 10.10.10.23:7261;branch=z9hG4bK-5cf50d3e;received=10.10.10.23 From: fax ;tag=268feafdffbb7d3do1 To: ;tag=as3f0690fe Call-ID: 75bd615d-2ad57e17@192.168.20.12 CSeq: 4037 NOTIFY User-Agent: blabla.ch 1.4PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Content-Length: 0 <------------> [Dec 27 18:32:05] DEBUG[9953]: chan_sip.c:14631 sipsock_read: Invalid SIP message - rejected , no callid, len 327 uboot*CLI> sip no debug SIP Debugging Disabled The 'sip no debug' command is deprecated and will be removed in a future release. Please use 'sip set debug off' instead. uboot*CLI> set debug 0 Core debug is now OFF uboot*CLI>