Asterisk SVN-trunk-r58995, Copyright (C) 1999 - 2006 Digium, Inc. and others. ... Asterisk Ready. [Mar 18 13:57:25] DEBUG[15762]: chan_sip.c:4665 sip_alloc: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP) [Mar 18 13:57:25] DEBUG[15762]: chan_sip.c:1720 initialize_initreq: Initializing initreq for method OPTIONS - callid 4fd2cccb044ae47b3e716efb2dc7fa8f@MY.IP.ADDRESS [Mar 18 13:57:25] DEBUG[15762]: chan_sip.c:4943 parse_request: Header 0 [ 34]: OPTIONS sip:REMOTE.IP.ADDRESS SIP/2.0 [Mar 18 13:57:25] DEBUG[15762]: chan_sip.c:4943 parse_request: Header 1 [ 63]: Via: SIP/2.0/UDP MY.IP.ADDRESS:5060;branch=z9hG4bK5a62e536;rport [Mar 18 13:57:25] DEBUG[15762]: chan_sip.c:4943 parse_request: Header 2 [ 16]: Max-Forwards: 70 [Mar 18 13:57:25] DEBUG[15762]: chan_sip.c:4943 parse_request: Header 3 [ 59]: From: "asterisk" ;tag=as5e1d7f6c [Mar 18 13:57:25] DEBUG[15762]: chan_sip.c:4943 parse_request: Header 4 [ 24]: To: [Mar 18 13:57:25] DEBUG[15762]: chan_sip.c:4943 parse_request: Header 5 [ 36]: Contact: [Mar 18 13:57:25] DEBUG[15762]: chan_sip.c:4943 parse_request: Header 6 [ 54]: Call-ID: 4fd2cccb044ae47b3e716efb2dc7fa8f@MY.IP.ADDRESS [Mar 18 13:57:25] DEBUG[15762]: chan_sip.c:4943 parse_request: Header 7 [ 17]: CSeq: 102 OPTIONS [Mar 18 13:57:25] DEBUG[15762]: chan_sip.c:4943 parse_request: Header 8 [ 41]: User-Agent: Asterisk PBX SVN-trunk-r58995 [Mar 18 13:57:25] DEBUG[15762]: chan_sip.c:4943 parse_request: Header 9 [ 35]: Date: Sun, 18 Mar 2007 11:57:25 GMT [Mar 18 13:57:25] DEBUG[15762]: chan_sip.c:4943 parse_request: Header 10 [ 66]: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY [Mar 18 13:57:25] DEBUG[15762]: chan_sip.c:4943 parse_request: Header 11 [ 19]: Supported: replaces [Mar 18 13:57:25] DEBUG[15762]: chan_sip.c:4943 parse_request: Header 12 [ 17]: Content-Length: 0 Reliably Transmitting (no NAT) to REMOTE.IP.ADDRESS:5060: OPTIONS sip:REMOTE.IP.ADDRESS SIP/2.0 Via: SIP/2.0/UDP MY.IP.ADDRESS:5060;branch=z9hG4bK5a62e536;rport Max-Forwards: 70 From: "asterisk" ;tag=as5e1d7f6c To: Contact: Call-ID: 4fd2cccb044ae47b3e716efb2dc7fa8f@MY.IP.ADDRESS CSeq: 102 OPTIONS User-Agent: Asterisk PBX SVN-trunk-r58995 Date: Sun, 18 Mar 2007 11:57:25 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Content-Length: 0 --- [Mar 18 13:57:25] DEBUG[15762]: chan_sip.c:4943 parse_request: Header 0 [ 34]: OPTIONS sip:REMOTE.IP.ADDRESS SIP/2.0 [Mar 18 13:57:25] DEBUG[15762]: chan_sip.c:4943 parse_request: Header 1 [ 63]: Via: SIP/2.0/UDP MY.IP.ADDRESS:5060;branch=z9hG4bK5a62e536;rport [Mar 18 13:57:25] DEBUG[15762]: chan_sip.c:4943 parse_request: Header 2 [ 16]: Max-Forwards: 70 [Mar 18 13:57:25] DEBUG[15762]: chan_sip.c:4943 parse_request: Header 3 [ 59]: From: "asterisk" ;tag=as5e1d7f6c [Mar 18 13:57:25] DEBUG[15762]: chan_sip.c:4943 parse_request: Header 4 [ 24]: To: [Mar 18 13:57:25] DEBUG[15762]: chan_sip.c:4943 parse_request: Header 5 [ 36]: Contact: [Mar 18 13:57:25] DEBUG[15762]: chan_sip.c:4943 parse_request: Header 6 [ 54]: Call-ID: 4fd2cccb044ae47b3e716efb2dc7fa8f@MY.IP.ADDRESS [Mar 18 13:57:25] DEBUG[15762]: chan_sip.c:4943 parse_request: Header 7 [ 17]: CSeq: 102 OPTIONS [Mar 18 13:57:25] DEBUG[15762]: chan_sip.c:4943 parse_request: Header 8 [ 41]: User-Agent: Asterisk PBX SVN-trunk-r58995 [Mar 18 13:57:25] DEBUG[15762]: chan_sip.c:4943 parse_request: Header 9 [ 35]: Date: Sun, 18 Mar 2007 11:57:25 GMT [Mar 18 13:57:25] DEBUG[15762]: chan_sip.c:4943 parse_request: Header 10 [ 66]: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY [Mar 18 13:57:25] DEBUG[15762]: chan_sip.c:4943 parse_request: Header 11 [ 19]: Supported: replaces [Mar 18 13:57:25] DEBUG[15762]: chan_sip.c:4943 parse_request: Header 12 [ 17]: Content-Length: 0 [Mar 18 13:57:25] DEBUG[15762]: chan_sip.c:4943 parse_request: Header 13 [ 0]: [Mar 18 13:57:25] DEBUG[15762]: chan_sip.c:2117 __sip_reliable_xmit: *** SIP TIMER: Initalizing retransmit timer on packet: Id #2 <--- SIP read from REMOTE.IP.ADDRESS:5060 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP MY.IP.ADDRESS:5060;branch=z9hG4bK5a62e536;rport From: "asterisk" ;tag=as5e1d7f6c To: ;tag=0088-0021-E43B7E41 Call-ID: 4fd2cccb044ae47b3e716efb2dc7fa8f@MY.IP.ADDRESS CSeq: 102 OPTIONS Contact: Allow: INVITE,ACK,BYE,CANCEL,INFO,NOTIFY,OPTIONS,REFER Supported: replaces Accept: application/sdp, application/none Accept-Language: en User-Agent: VEGA400/10.02.07.2xS019 Content-Length: 0 <-------------> [Mar 18 13:57:25] DEBUG[15762]: chan_sip.c:4943 parse_request: Header 0 [ 14]: SIP/2.0 200 OK [Mar 18 13:57:25] DEBUG[15762]: chan_sip.c:4943 parse_request: Header 1 [ 63]: Via: SIP/2.0/UDP MY.IP.ADDRESS:5060;branch=z9hG4bK5a62e536;rport [Mar 18 13:57:25] DEBUG[15762]: chan_sip.c:4943 parse_request: Header 2 [ 59]: From: "asterisk" ;tag=as5e1d7f6c [Mar 18 13:57:25] DEBUG[15762]: chan_sip.c:4943 parse_request: Header 3 [ 47]: To: ;tag=0088-0021-E43B7E41 [Mar 18 13:57:25] DEBUG[15762]: chan_sip.c:4943 parse_request: Header 4 [ 54]: Call-ID: 4fd2cccb044ae47b3e716efb2dc7fa8f@MY.IP.ADDRESS [Mar 18 13:57:25] DEBUG[15762]: chan_sip.c:4943 parse_request: Header 5 [ 17]: CSeq: 102 OPTIONS [Mar 18 13:57:25] DEBUG[15762]: chan_sip.c:4943 parse_request: Header 6 [ 34]: Contact: [Mar 18 13:57:25] DEBUG[15762]: chan_sip.c:4943 parse_request: Header 7 [ 54]: Allow: INVITE,ACK,BYE,CANCEL,INFO,NOTIFY,OPTIONS,REFER [Mar 18 13:57:25] DEBUG[15762]: chan_sip.c:4943 parse_request: Header 8 [ 19]: Supported: replaces [Mar 18 13:57:25] DEBUG[15762]: chan_sip.c:4943 parse_request: Header 9 [ 41]: Accept: application/sdp, application/none [Mar 18 13:57:25] DEBUG[15762]: chan_sip.c:4943 parse_request: Header 10 [ 19]: Accept-Language: en [Mar 18 13:57:25] DEBUG[15762]: chan_sip.c:4943 parse_request: Header 11 [ 35]: User-Agent: VEGA400/10.02.07.2xS019 [Mar 18 13:57:25] DEBUG[15762]: chan_sip.c:4943 parse_request: Header 12 [ 17]: Content-Length: 0 [Mar 18 13:57:25] DEBUG[15762]: chan_sip.c:4943 parse_request: Header 13 [ 0]: --- (13 headers 0 lines) --- [Mar 18 13:57:25] DEBUG[15762]: chan_sip.c:4729 find_call: = Found Their Call ID: 4fd2cccb044ae47b3e716efb2dc7fa8f@MY.IP.ADDRESS Their Tag Our tag: as5e1d7f6c [Mar 18 13:57:25] DEBUG[15762]: chan_sip.c:2225 __sip_ack: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #2 [Mar 18 13:57:25] DEBUG[15762]: chan_sip.c:2236 __sip_ack: Stopping retransmission on '4fd2cccb044ae47b3e716efb2dc7fa8f@MY.IP.ADDRESS' of Request 102: Match Found [Mar 18 13:57:25] NOTICE[15762]: chan_sip.c:12816 handle_response_peerpoke: Peer 'orbi' is now Reachable. (22ms / 2000ms) [Mar 18 13:57:25] DEBUG[15762]: devicestate.c:444 __ast_device_state_changed_literal: Notification of state change to be queued on device/channel SIP/orbi Really destroying SIP dialog '4fd2cccb044ae47b3e716efb2dc7fa8f@MY.IP.ADDRESS' Method: OPTIONS [Mar 18 13:57:25] DEBUG[15762]: chan_sip.c:11411 sip_dump_history: ---------- SIP HISTORY for '4fd2cccb044ae47b3e716efb2dc7fa8f@MY.IP.ADDRESS' [Mar 18 13:57:25] DEBUG[15762]: chan_sip.c:11415 sip_dump_history: * SIP Call [Mar 18 13:57:25] DEBUG[15762]: chan_sip.c:11418 sip_dump_history: 001. TxReqRel OPTIONS / 102 OPTIONS - -UNKNOWN- [Mar 18 13:57:25] DEBUG[15762]: chan_sip.c:11418 sip_dump_history: 002. Rx SIP/2.0 / 102 OPTIONS / 200 OK [Mar 18 13:57:25] DEBUG[15762]: chan_sip.c:11421 sip_dump_history: ---------- END SIP HISTORY for '4fd2cccb044ae47b3e716efb2dc7fa8f@MY.IP.ADDRESS' [Mar 18 13:57:25] DEBUG[15762]: devicestate.c:302 ast_device_state: No provider found, checking channel drivers for SIP - orbi [Mar 18 13:57:25] DEBUG[15762]: chan_sip.c:15944 sip_devicestate: Checking device state for peer orbi [Mar 18 13:57:25] DEBUG[15762]: devicestate.c:428 do_state_change: Changing state for SIP/orbi - state 1 (Not in use) *CLI> core set debug 4 Core debug was 5 and is now 4 *CLI> sip set debug peer orbi SIP Debugging Enabled for IP: REMOTE.IP.ADDRESS:5060 *CLI> [Mar 18 13:58:25] DEBUG[15762]: chan_sip.c:4665 sip_alloc: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP) [Mar 18 13:58:25] DEBUG[15762]: chan_sip.c:1720 initialize_initreq: Initializing initreq for method OPTIONS - callid 78c6e4af7921c30167b9cf8a574221a1@MY.IP.ADDRESS [Mar 18 13:58:25] DEBUG[15762]: chan_sip.c:4943 parse_request: Header 0 [ 34]: OPTIONS sip:REMOTE.IP.ADDRESS SIP/2.0 [Mar 18 13:58:25] DEBUG[15762]: chan_sip.c:4943 parse_request: Header 1 [ 63]: Via: SIP/2.0/UDP MY.IP.ADDRESS:5060;branch=z9hG4bK2bcf5e31;rport [Mar 18 13:58:25] DEBUG[15762]: chan_sip.c:4943 parse_request: Header 2 [ 16]: Max-Forwards: 70 [Mar 18 13:58:25] DEBUG[15762]: chan_sip.c:4943 parse_request: Header 3 [ 59]: From: "asterisk" ;tag=as3af21212 [Mar 18 13:58:25] DEBUG[15762]: chan_sip.c:4943 parse_request: Header 4 [ 24]: To: [Mar 18 13:58:25] DEBUG[15762]: chan_sip.c:4943 parse_request: Header 5 [ 36]: Contact: [Mar 18 13:58:25] DEBUG[15762]: chan_sip.c:4943 parse_request: Header 6 [ 54]: Call-ID: 78c6e4af7921c30167b9cf8a574221a1@MY.IP.ADDRESS [Mar 18 13:58:25] DEBUG[15762]: chan_sip.c:4943 parse_request: Header 7 [ 17]: CSeq: 102 OPTIONS [Mar 18 13:58:25] DEBUG[15762]: chan_sip.c:4943 parse_request: Header 8 [ 41]: User-Agent: Asterisk PBX SVN-trunk-r58995 [Mar 18 13:58:25] DEBUG[15762]: chan_sip.c:4943 parse_request: Header 9 [ 35]: Date: Sun, 18 Mar 2007 11:58:25 GMT [Mar 18 13:58:25] DEBUG[15762]: chan_sip.c:4943 parse_request: Header 10 [ 66]: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY [Mar 18 13:58:25] DEBUG[15762]: chan_sip.c:4943 parse_request: Header 11 [ 19]: Supported: replaces [Mar 18 13:58:25] DEBUG[15762]: chan_sip.c:4943 parse_request: Header 12 [ 17]: Content-Length: 0 Reliably Transmitting (no NAT) to REMOTE.IP.ADDRESS:5060: OPTIONS sip:REMOTE.IP.ADDRESS SIP/2.0 Via: SIP/2.0/UDP MY.IP.ADDRESS:5060;branch=z9hG4bK2bcf5e31;rport Max-Forwards: 70 From: "asterisk" ;tag=as3af21212 To: Contact: Call-ID: 78c6e4af7921c30167b9cf8a574221a1@MY.IP.ADDRESS CSeq: 102 OPTIONS User-Agent: Asterisk PBX SVN-trunk-r58995 Date: Sun, 18 Mar 2007 11:58:25 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Content-Length: 0 --- [Mar 18 13:58:25] DEBUG[15762]: chan_sip.c:4943 parse_request: Header 0 [ 34]: OPTIONS sip:REMOTE.IP.ADDRESS SIP/2.0 [Mar 18 13:58:25] DEBUG[15762]: chan_sip.c:4943 parse_request: Header 1 [ 63]: Via: SIP/2.0/UDP MY.IP.ADDRESS:5060;branch=z9hG4bK2bcf5e31;rport [Mar 18 13:58:25] DEBUG[15762]: chan_sip.c:4943 parse_request: Header 2 [ 16]: Max-Forwards: 70 [Mar 18 13:58:25] DEBUG[15762]: chan_sip.c:4943 parse_request: Header 3 [ 59]: From: "asterisk" ;tag=as3af21212 [Mar 18 13:58:25] DEBUG[15762]: chan_sip.c:4943 parse_request: Header 4 [ 24]: To: [Mar 18 13:58:25] DEBUG[15762]: chan_sip.c:4943 parse_request: Header 5 [ 36]: Contact: [Mar 18 13:58:25] DEBUG[15762]: chan_sip.c:4943 parse_request: Header 6 [ 54]: Call-ID: 78c6e4af7921c30167b9cf8a574221a1@MY.IP.ADDRESS [Mar 18 13:58:25] DEBUG[15762]: chan_sip.c:4943 parse_request: Header 7 [ 17]: CSeq: 102 OPTIONS [Mar 18 13:58:25] DEBUG[15762]: chan_sip.c:4943 parse_request: Header 8 [ 41]: User-Agent: Asterisk PBX SVN-trunk-r58995 [Mar 18 13:58:25] DEBUG[15762]: chan_sip.c:4943 parse_request: Header 9 [ 35]: Date: Sun, 18 Mar 2007 11:58:25 GMT [Mar 18 13:58:25] DEBUG[15762]: chan_sip.c:4943 parse_request: Header 10 [ 66]: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY [Mar 18 13:58:25] DEBUG[15762]: chan_sip.c:4943 parse_request: Header 11 [ 19]: Supported: replaces [Mar 18 13:58:25] DEBUG[15762]: chan_sip.c:4943 parse_request: Header 12 [ 17]: Content-Length: 0 [Mar 18 13:58:25] DEBUG[15762]: chan_sip.c:4943 parse_request: Header 13 [ 0]: [Mar 18 13:58:25] DEBUG[15762]: chan_sip.c:2117 __sip_reliable_xmit: *** SIP TIMER: Initalizing retransmit timer on packet: Id #5 <--- SIP read from REMOTE.IP.ADDRESS:5060 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP MY.IP.ADDRESS:5060;branch=z9hG4bK2bcf5e31;rport From: "asterisk" ;tag=as3af21212 To: ;tag=008C-0002-7BE2832B Call-ID: 78c6e4af7921c30167b9cf8a574221a1@MY.IP.ADDRESS CSeq: 102 OPTIONS Contact: Allow: INVITE,ACK,BYE,CANCEL,INFO,NOTIFY,OPTIONS,REFER Supported: replaces Accept: application/sdp, application/none Accept-Language: en User-Agent: VEGA400/10.02.07.2xS019 Content-Length: 0 <-------------> [Mar 18 13:58:25] DEBUG[15762]: chan_sip.c:4943 parse_request: Header 0 [ 14]: SIP/2.0 200 OK [Mar 18 13:58:25] DEBUG[15762]: chan_sip.c:4943 parse_request: Header 1 [ 63]: Via: SIP/2.0/UDP MY.IP.ADDRESS:5060;branch=z9hG4bK2bcf5e31;rport [Mar 18 13:58:25] DEBUG[15762]: chan_sip.c:4943 parse_request: Header 2 [ 59]: From: "asterisk" ;tag=as3af21212 [Mar 18 13:58:25] DEBUG[15762]: chan_sip.c:4943 parse_request: Header 3 [ 47]: To: ;tag=008C-0002-7BE2832B [Mar 18 13:58:25] DEBUG[15762]: chan_sip.c:4943 parse_request: Header 4 [ 54]: Call-ID: 78c6e4af7921c30167b9cf8a574221a1@MY.IP.ADDRESS [Mar 18 13:58:25] DEBUG[15762]: chan_sip.c:4943 parse_request: Header 5 [ 17]: CSeq: 102 OPTIONS [Mar 18 13:58:25] DEBUG[15762]: chan_sip.c:4943 parse_request: Header 6 [ 34]: Contact: [Mar 18 13:58:25] DEBUG[15762]: chan_sip.c:4943 parse_request: Header 7 [ 54]: Allow: INVITE,ACK,BYE,CANCEL,INFO,NOTIFY,OPTIONS,REFER [Mar 18 13:58:25] DEBUG[15762]: chan_sip.c:4943 parse_request: Header 8 [ 19]: Supported: replaces [Mar 18 13:58:25] DEBUG[15762]: chan_sip.c:4943 parse_request: Header 9 [ 41]: Accept: application/sdp, application/none [Mar 18 13:58:25] DEBUG[15762]: chan_sip.c:4943 parse_request: Header 10 [ 19]: Accept-Language: en [Mar 18 13:58:25] DEBUG[15762]: chan_sip.c:4943 parse_request: Header 11 [ 35]: User-Agent: VEGA400/10.02.07.2xS019 [Mar 18 13:58:25] DEBUG[15762]: chan_sip.c:4943 parse_request: Header 12 [ 17]: Content-Length: 0 [Mar 18 13:58:25] DEBUG[15762]: chan_sip.c:4943 parse_request: Header 13 [ 0]: --- (13 headers 0 lines) --- [Mar 18 13:58:25] DEBUG[15762]: chan_sip.c:2225 __sip_ack: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #5 [Mar 18 13:58:25] DEBUG[15762]: chan_sip.c:2236 __sip_ack: Stopping retransmission on '78c6e4af7921c30167b9cf8a574221a1@MY.IP.ADDRESS' of Request 102: Match Found Really destroying SIP dialog '78c6e4af7921c30167b9cf8a574221a1@MY.IP.ADDRESS' Method: OPTIONS [Mar 18 13:58:25] DEBUG[15762]: chan_sip.c:11411 sip_dump_history: ---------- SIP HISTORY for '78c6e4af7921c30167b9cf8a574221a1@MY.IP.ADDRESS' [Mar 18 13:58:25] DEBUG[15762]: chan_sip.c:11415 sip_dump_history: * SIP Call [Mar 18 13:58:25] DEBUG[15762]: chan_sip.c:11418 sip_dump_history: 001. TxReqRel OPTIONS / 102 OPTIONS - -UNKNOWN- [Mar 18 13:58:25] DEBUG[15762]: chan_sip.c:11418 sip_dump_history: 002. Rx SIP/2.0 / 102 OPTIONS / 200 OK [Mar 18 13:58:25] DEBUG[15762]: chan_sip.c:11421 sip_dump_history: ---------- END SIP HISTORY for '78c6e4af7921c30167b9cf8a574221a1@MY.IP.ADDRESS' -- Attempting call on SIP/CALLED.NUMBER@orbi for 999@orbi:1 (Retry 1) [Mar 18 13:58:49] DEBUG[15762]: chan_sip.c:16013 sip_request_call: Asked to create a SIP channel with formats: 0x40 (slin) [Mar 18 13:58:49] DEBUG[15762]: chan_sip.c:4665 sip_alloc: Allocating new SIP dialog for (No Call-ID) - INVITE (With RTP) [Mar 18 13:58:49] DEBUG[15762]: chan_sip.c:2844 do_setnat: Setting NAT on RTP to Off [Mar 18 13:58:49] DEBUG[15762]: chan_sip.c:1785 obproxy_get: OBPROXY: Not applying OBproxy to this call [Mar 18 13:58:49] DEBUG[15762]: frame.c:1157 ast_codec_choose: Could not find preferred codec - Going for the best codec [Mar 18 13:58:49] DEBUG[15762]: chan_sip.c:4128 sip_new: *** Our native formats are 0x40 (slin) [Mar 18 13:58:49] DEBUG[15762]: chan_sip.c:4129 sip_new: *** Joint capabilities are 0x40 (slin) [Mar 18 13:58:49] DEBUG[15762]: chan_sip.c:4130 sip_new: *** Our capabilities are 0x4 (ulaw) [Mar 18 13:58:49] DEBUG[15762]: frame.c:1157 ast_codec_choose: Could not find preferred codec - Going for the best codec [Mar 18 13:58:49] DEBUG[15762]: chan_sip.c:4131 sip_new: *** AST_CODEC_CHOOSE formats are 0x40 (slin) [Mar 18 13:58:49] DEBUG[15762]: chan_sip.c:4133 sip_new: *** Our preferred formats from the incoming channel are 0x40 (slin) [Mar 18 13:58:49] DEBUG[15762]: chan_sip.c:4161 sip_new: This channel will not be able to handle video. [Mar 18 13:58:49] DEBUG[15762]: chan_sip.c:3124 sip_call: Outgoing Call for CALLED.NUMBER [Mar 18 13:58:49] DEBUG[15762]: chan_sip.c:3307 update_call_counter: Updating call counter for outgoing call [Mar 18 13:58:49] DEBUG[15762]: chan_sip.c:3142 sip_call: Our T38 capability (0), joint T38 capability (0) [Mar 18 13:58:49] DEBUG[15762]: chan_sip.c:6682 add_sdp: ** Our capability: 0x4 (ulaw) Video flag: False Text flag: False [Mar 18 13:58:49] DEBUG[15762]: chan_sip.c:6684 add_sdp: ** Our prefcodec: 0x40 (slin) Audio is at MY.IP.ADDRESS port 8248 Adding codec 0x4 (ulaw) to SDP Adding non-codec 0x1 (telephone-event) to SDP [Mar 18 13:58:49] DEBUG[15762]: chan_sip.c:6845 add_sdp: -- Done with adding codecs to SDP [Mar 18 13:58:49] DEBUG[15762]: chan_sip.c:6905 add_sdp: Done building SDP. Settling with this capability: 0x4 (ulaw) [Mar 18 13:58:49] DEBUG[15762]: chan_sip.c:1720 initialize_initreq: Initializing initreq for method INVITE - callid 24af28367cd0ecde5b7a96052eb21459@MY.IP.ADDRESS [Mar 18 13:58:49] DEBUG[15762]: chan_sip.c:4943 parse_request: Header 0 [ 44]: INVITE sip:CALLED.NUMBER@REMOTE.IP.ADDRESS SIP/2.0 [Mar 18 13:58:49] DEBUG[15762]: chan_sip.c:4943 parse_request: Header 1 [ 63]: Via: SIP/2.0/UDP MY.IP.ADDRESS:5060;branch=z9hG4bK168a60bf;rport [Mar 18 13:58:49] DEBUG[15762]: chan_sip.c:4943 parse_request: Header 2 [ 16]: Max-Forwards: 70 [Mar 18 13:58:49] DEBUG[15762]: chan_sip.c:4943 parse_request: Header 3 [ 77]: From: "CALLING.NUMBER" ;tag=as1b4993a5 [Mar 18 13:58:49] DEBUG[15762]: chan_sip.c:4943 parse_request: Header 4 [ 35]: To: [Mar 18 13:58:49] DEBUG[15762]: chan_sip.c:4943 parse_request: Header 5 [ 45]: Contact: [Mar 18 13:58:49] DEBUG[15762]: chan_sip.c:4943 parse_request: Header 6 [ 54]: Call-ID: 24af28367cd0ecde5b7a96052eb21459@MY.IP.ADDRESS [Mar 18 13:58:49] DEBUG[15762]: chan_sip.c:4943 parse_request: Header 7 [ 16]: CSeq: 102 INVITE [Mar 18 13:58:49] DEBUG[15762]: chan_sip.c:4943 parse_request: Header 8 [ 41]: User-Agent: Asterisk PBX SVN-trunk-r58995 [Mar 18 13:58:49] DEBUG[15762]: chan_sip.c:4943 parse_request: Header 9 [ 35]: Date: Sun, 18 Mar 2007 11:58:49 GMT [Mar 18 13:58:49] DEBUG[15762]: chan_sip.c:4943 parse_request: Header 10 [ 66]: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY [Mar 18 13:58:49] DEBUG[15762]: chan_sip.c:4943 parse_request: Header 11 [ 19]: Supported: replaces [Mar 18 13:58:49] DEBUG[15762]: chan_sip.c:4943 parse_request: Header 12 [ 29]: Content-Type: application/sdp [Mar 18 13:58:49] DEBUG[15762]: chan_sip.c:4943 parse_request: Header 13 [ 19]: Content-Length: 239 [Mar 18 13:58:49] DEBUG[15762]: chan_sip.c:4943 parse_request: Header 14 [ 0]: [Mar 18 13:58:49] DEBUG[15762]: chan_sip.c:4943 parse_request: Body 0 [ 3]: v=0 [Mar 18 13:58:49] DEBUG[15762]: chan_sip.c:4943 parse_request: Body 1 [ 38]: o=root 15758 15758 IN IP4 MY.IP.ADDRESS [Mar 18 13:58:49] DEBUG[15762]: chan_sip.c:4943 parse_request: Body 2 [ 9]: s=session [Mar 18 13:58:49] DEBUG[15762]: chan_sip.c:4943 parse_request: Body 3 [ 21]: c=IN IP4 MY.IP.ADDRESS [Mar 18 13:58:49] DEBUG[15762]: chan_sip.c:4943 parse_request: Body 4 [ 5]: t=0 0 [Mar 18 13:58:49] DEBUG[15762]: chan_sip.c:4943 parse_request: Body 5 [ 26]: m=audio 8248 RTP/AVP 0 101 [Mar 18 13:58:49] DEBUG[15762]: chan_sip.c:4943 parse_request: Body 6 [ 20]: a=rtpmap:0 PCMU/8000 [Mar 18 13:58:49] DEBUG[15762]: chan_sip.c:4943 parse_request: Body 7 [ 33]: a=rtpmap:101 telephone-event/8000 [Mar 18 13:58:49] DEBUG[15762]: chan_sip.c:4943 parse_request: Body 8 [ 15]: a=fmtp:101 0-16 [Mar 18 13:58:49] DEBUG[15762]: chan_sip.c:4943 parse_request: Body 9 [ 25]: a=silenceSupp:off - - - - [Mar 18 13:58:49] DEBUG[15762]: chan_sip.c:4943 parse_request: Body 10 [ 10]: a=ptime:20 [Mar 18 13:58:49] DEBUG[15762]: chan_sip.c:4943 parse_request: Body 11 [ 10]: a=sendrecv Reliably Transmitting (no NAT) to REMOTE.IP.ADDRESS:5060: INVITE sip:CALLED.NUMBER@REMOTE.IP.ADDRESS SIP/2.0 Via: SIP/2.0/UDP MY.IP.ADDRESS:5060;branch=z9hG4bK168a60bf;rport Max-Forwards: 70 From: "CALLING.NUMBER" ;tag=as1b4993a5 To: Contact: Call-ID: 24af28367cd0ecde5b7a96052eb21459@MY.IP.ADDRESS CSeq: 102 INVITE User-Agent: Asterisk PBX SVN-trunk-r58995 Date: Sun, 18 Mar 2007 11:58:49 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Supported: replaces Content-Type: application/sdp Content-Length: 239 v=0 o=root 15758 15758 IN IP4 MY.IP.ADDRESS s=session c=IN IP4 MY.IP.ADDRESS t=0 0 m=audio 8248 RTP/AVP 0 101 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=silenceSupp:off - - - - a=ptime:20 a=sendrecv --- [Mar 18 13:58:49] DEBUG[15762]: chan_sip.c:4943 parse_request: Header 0 [ 44]: INVITE sip:CALLED.NUMBER@REMOTE.IP.ADDRESS SIP/2.0 [Mar 18 13:58:49] DEBUG[15762]: chan_sip.c:4943 parse_request: Header 1 [ 63]: Via: SIP/2.0/UDP MY.IP.ADDRESS:5060;branch=z9hG4bK168a60bf;rport [Mar 18 13:58:49] DEBUG[15762]: chan_sip.c:4943 parse_request: Header 2 [ 16]: Max-Forwards: 70 [Mar 18 13:58:49] DEBUG[15762]: chan_sip.c:4943 parse_request: Header 3 [ 77]: From: "CALLING.NUMBER" ;tag=as1b4993a5 [Mar 18 13:58:49] DEBUG[15762]: chan_sip.c:4943 parse_request: Header 4 [ 35]: To: [Mar 18 13:58:49] DEBUG[15762]: chan_sip.c:4943 parse_request: Header 5 [ 45]: Contact: [Mar 18 13:58:49] DEBUG[15762]: chan_sip.c:4943 parse_request: Header 6 [ 54]: Call-ID: 24af28367cd0ecde5b7a96052eb21459@MY.IP.ADDRESS [Mar 18 13:58:49] DEBUG[15762]: chan_sip.c:4943 parse_request: Header 7 [ 16]: CSeq: 102 INVITE [Mar 18 13:58:49] DEBUG[15762]: chan_sip.c:4943 parse_request: Header 8 [ 41]: User-Agent: Asterisk PBX SVN-trunk-r58995 [Mar 18 13:58:49] DEBUG[15762]: chan_sip.c:4943 parse_request: Header 9 [ 35]: Date: Sun, 18 Mar 2007 11:58:49 GMT [Mar 18 13:58:49] DEBUG[15762]: chan_sip.c:4943 parse_request: Header 10 [ 66]: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY [Mar 18 13:58:49] DEBUG[15762]: chan_sip.c:4943 parse_request: Header 11 [ 19]: Supported: replaces [Mar 18 13:58:49] DEBUG[15762]: chan_sip.c:4943 parse_request: Header 12 [ 29]: Content-Type: application/sdp [Mar 18 13:58:49] DEBUG[15762]: chan_sip.c:4943 parse_request: Header 13 [ 19]: Content-Length: 239 [Mar 18 13:58:49] DEBUG[15762]: chan_sip.c:4943 parse_request: Header 14 [ 0]: [Mar 18 13:58:49] DEBUG[15762]: chan_sip.c:4943 parse_request: Body 0 [ 3]: v=0 [Mar 18 13:58:49] DEBUG[15762]: chan_sip.c:4943 parse_request: Body 1 [ 38]: o=root 15758 15758 IN IP4 MY.IP.ADDRESS [Mar 18 13:58:49] DEBUG[15762]: chan_sip.c:4943 parse_request: Body 2 [ 9]: s=session [Mar 18 13:58:49] DEBUG[15762]: chan_sip.c:4943 parse_request: Body 3 [ 21]: c=IN IP4 MY.IP.ADDRESS [Mar 18 13:58:49] DEBUG[15762]: chan_sip.c:4943 parse_request: Body 4 [ 5]: t=0 0 [Mar 18 13:58:49] DEBUG[15762]: chan_sip.c:4943 parse_request: Body 5 [ 26]: m=audio 8248 RTP/AVP 0 101 [Mar 18 13:58:49] DEBUG[15762]: chan_sip.c:4943 parse_request: Body 6 [ 20]: a=rtpmap:0 PCMU/8000 [Mar 18 13:58:49] DEBUG[15762]: chan_sip.c:4943 parse_request: Body 7 [ 33]: a=rtpmap:101 telephone-event/8000 [Mar 18 13:58:49] DEBUG[15762]: chan_sip.c:4943 parse_request: Body 8 [ 15]: a=fmtp:101 0-16 [Mar 18 13:58:49] DEBUG[15762]: chan_sip.c:4943 parse_request: Body 9 [ 25]: a=silenceSupp:off - - - - [Mar 18 13:58:49] DEBUG[15762]: chan_sip.c:4943 parse_request: Body 10 [ 10]: a=ptime:20 [Mar 18 13:58:49] DEBUG[15762]: chan_sip.c:4943 parse_request: Body 11 [ 10]: a=sendrecv [Mar 18 13:58:49] DEBUG[15762]: chan_sip.c:2117 __sip_reliable_xmit: *** SIP TIMER: Initalizing retransmit timer on packet: Id #8 <--- SIP read from REMOTE.IP.ADDRESS:5060 ---> SIP/2.0 100 Trying Via: SIP/2.0/UDP MY.IP.ADDRESS:5060;branch=z9hG4bK168a60bf;rport From: "CALLING.NUMBER" ;tag=as1b4993a5 To: ;tag=0088-0022-B05F1358 Call-ID: 24af28367cd0ecde5b7a96052eb21459@MY.IP.ADDRESS CSeq: 102 INVITE Contact: User-Agent: VEGA400/10.02.07.2xS019 Content-Length: 0 <-------------> [Mar 18 13:58:49] DEBUG[15762]: chan_sip.c:4943 parse_request: Header 0 [ 18]: SIP/2.0 100 Trying [Mar 18 13:58:49] DEBUG[15762]: chan_sip.c:4943 parse_request: Header 1 [ 63]: Via: SIP/2.0/UDP MY.IP.ADDRESS:5060;branch=z9hG4bK168a60bf;rport [Mar 18 13:58:49] DEBUG[15762]: chan_sip.c:4943 parse_request: Header 2 [ 77]: From: "CALLING.NUMBER" ;tag=as1b4993a5 [Mar 18 13:58:49] DEBUG[15762]: chan_sip.c:4943 parse_request: Header 3 [ 58]: To: ;tag=0088-0022-B05F1358 [Mar 18 13:58:49] DEBUG[15762]: chan_sip.c:4943 parse_request: Header 4 [ 54]: Call-ID: 24af28367cd0ecde5b7a96052eb21459@MY.IP.ADDRESS [Mar 18 13:58:49] DEBUG[15762]: chan_sip.c:4943 parse_request: Header 5 [ 16]: CSeq: 102 INVITE [Mar 18 13:58:49] DEBUG[15762]: chan_sip.c:4943 parse_request: Header 6 [ 45]: Contact: [Mar 18 13:58:49] DEBUG[15762]: chan_sip.c:4943 parse_request: Header 7 [ 35]: User-Agent: VEGA400/10.02.07.2xS019 [Mar 18 13:58:49] DEBUG[15762]: chan_sip.c:4943 parse_request: Header 8 [ 17]: Content-Length: 0 [Mar 18 13:58:49] DEBUG[15762]: chan_sip.c:4943 parse_request: Header 9 [ 0]: --- (9 headers 0 lines) --- [Mar 18 13:58:49] DEBUG[15762]: chan_sip.c:2270 __sip_semi_ack: *** SIP TIMER: Cancelling retransmission #8 - INVITE (got response) [Mar 18 13:58:49] DEBUG[15762]: chan_sip.c:2279 __sip_semi_ack: (Provisional) Stopping retransmission (but retaining packet) on '24af28367cd0ecde5b7a96052eb21459@MY.IP.ADDRESS' Request 102: Found [Mar 18 13:58:49] DEBUG[15762]: chan_sip.c:12309 handle_response_invite: SIP response 100 to standard invite <--- SIP read from REMOTE.IP.ADDRESS:5060 ---> SIP/2.0 183 Progress With Media Via: SIP/2.0/UDP MY.IP.ADDRESS:5060;branch=z9hG4bK168a60bf;rport From: "CALLING.NUMBER" ;tag=as1b4993a5 To: ;tag=0088-0022-B05F1358 Call-ID: 24af28367cd0ecde5b7a96052eb21459@MY.IP.ADDRESS CSeq: 102 INVITE Contact: User-Agent: VEGA400/10.02.07.2xS019 Content-Type: application/sdp Content-Length: 202 v=0 o=Vega 325 325 IN IP4 REMOTE.IP.ADDRESS s=Sip Call c=IN IP4 REMOTE.IP.ADDRESS t=0 0 m=audio 10048 RTP/AVP 0 96 a=sendrecv a=rtpmap:0 PCMU/8000 a=rtpmap:96 telephone-event/8000 a=fmtp:96 0-15,16 <-------------> [Mar 18 13:58:56] DEBUG[15762]: chan_sip.c:4943 parse_request: Header 0 [ 31]: SIP/2.0 183 Progress With Media [Mar 18 13:58:56] DEBUG[15762]: chan_sip.c:4943 parse_request: Header 1 [ 63]: Via: SIP/2.0/UDP MY.IP.ADDRESS:5060;branch=z9hG4bK168a60bf;rport [Mar 18 13:58:56] DEBUG[15762]: chan_sip.c:4943 parse_request: Header 2 [ 77]: From: "CALLING.NUMBER" ;tag=as1b4993a5 [Mar 18 13:58:56] DEBUG[15762]: chan_sip.c:4943 parse_request: Header 3 [ 58]: To: ;tag=0088-0022-B05F1358 [Mar 18 13:58:56] DEBUG[15762]: chan_sip.c:4943 parse_request: Header 4 [ 54]: Call-ID: 24af28367cd0ecde5b7a96052eb21459@MY.IP.ADDRESS [Mar 18 13:58:56] DEBUG[15762]: chan_sip.c:4943 parse_request: Header 5 [ 16]: CSeq: 102 INVITE [Mar 18 13:58:56] DEBUG[15762]: chan_sip.c:4943 parse_request: Header 6 [ 45]: Contact: [Mar 18 13:58:56] DEBUG[15762]: chan_sip.c:4943 parse_request: Header 7 [ 35]: User-Agent: VEGA400/10.02.07.2xS019 [Mar 18 13:58:56] DEBUG[15762]: chan_sip.c:4943 parse_request: Header 8 [ 29]: Content-Type: application/sdp [Mar 18 13:58:56] DEBUG[15762]: chan_sip.c:4943 parse_request: Header 9 [ 19]: Content-Length: 202 [Mar 18 13:58:56] DEBUG[15762]: chan_sip.c:4943 parse_request: Header 10 [ 0]: [Mar 18 13:58:56] DEBUG[15762]: chan_sip.c:4943 parse_request: Body 0 [ 3]: v=0 [Mar 18 13:58:56] DEBUG[15762]: chan_sip.c:4943 parse_request: Body 1 [ 36]: o=Vega 325 325 IN IP4 REMOTE.IP.ADDRESS [Mar 18 13:58:56] DEBUG[15762]: chan_sip.c:4943 parse_request: Body 2 [ 10]: s=Sip Call [Mar 18 13:58:56] DEBUG[15762]: chan_sip.c:4943 parse_request: Body 3 [ 23]: c=IN IP4 REMOTE.IP.ADDRESS [Mar 18 13:58:56] DEBUG[15762]: chan_sip.c:4943 parse_request: Body 4 [ 5]: t=0 0 [Mar 18 13:58:56] DEBUG[15762]: chan_sip.c:4943 parse_request: Body 5 [ 26]: m=audio 10048 RTP/AVP 0 96 [Mar 18 13:58:56] DEBUG[15762]: chan_sip.c:4943 parse_request: Body 6 [ 10]: a=sendrecv [Mar 18 13:58:56] DEBUG[15762]: chan_sip.c:4943 parse_request: Body 7 [ 20]: a=rtpmap:0 PCMU/8000 [Mar 18 13:58:56] DEBUG[15762]: chan_sip.c:4943 parse_request: Body 8 [ 32]: a=rtpmap:96 telephone-event/8000 [Mar 18 13:58:56] DEBUG[15762]: chan_sip.c:4943 parse_request: Body 9 [ 17]: a=fmtp:96 0-15,16 --- (10 headers 10 lines) --- [Mar 18 13:58:56] DEBUG[15762]: chan_sip.c:2279 __sip_semi_ack: (Provisional) Stopping retransmission (but retaining packet) on '24af28367cd0ecde5b7a96052eb21459@MY.IP.ADDRESS' Request 102: Found [Mar 18 13:58:56] DEBUG[15762]: chan_sip.c:12309 handle_response_invite: SIP response 183 to standard invite Found RTP audio format 0 Found RTP audio format 96 Peer audio RTP is at port REMOTE.IP.ADDRESS:10048 Found description format PCMU for ID 0 Found description format telephone-event for ID 96 Got unsupported a:fmtp in SDP offer [Mar 18 13:58:56] DEBUG[15762]: chan_sip.c:5532 process_sdp: T38 state changed to 0 on channel SIP/orbi-081e05a8 Capabilities: us - 0x4 (ulaw), peer - audio=0x4 (ulaw)/video=0x0 (nothing)/text=0x0 (nothing), combined - 0x4 (ulaw) Non-codec capabilities (dtmf): us - 0x1 (telephone-event), peer - 0x1 (telephone-event), combined - 0x1 (telephone-event) Peer audio RTP is at port REMOTE.IP.ADDRESS:10048 [Mar 18 13:58:56] DEBUG[15762]: chan_sip.c:5623 process_sdp: We're settling with these formats: 0x4 (ulaw) [Mar 18 13:58:56] DEBUG[15762]: chan_sip.c:5630 process_sdp: We have an owner, now see if we need to change this call [Mar 18 13:58:56] DEBUG[15762]: chan_sip.c:5635 process_sdp: Oooh, we need to change our audio formats since our peer supports only 0x4 (ulaw) and not 0x40 (slin) [Mar 18 13:58:56] DEBUG[15762]: channel.c:2791 set_format: Set channel SIP/orbi-081e05a8 to read format slin [Mar 18 13:58:56] DEBUG[15762]: channel.c:2791 set_format: Set channel SIP/orbi-081e05a8 to write format slin