[Dec 20 10:26:40] DEBUG[17064]: chan_sip.c:3961 __sip_autodestruct: Auto destroying SIP dialog '34a5a4a2353cb2dc691e46206bc58a59@127.0.0.1' [Dec 20 10:26:40] DEBUG[17064]: chan_sip.c:6160 sip_destroy: Destroying SIP dialog 34a5a4a2353cb2dc691e46206bc58a59@127.0.0.1 [Dec 20 10:26:47] DEBUG[17064]: acl.c:736 ast_ouraddrfor: For destination '10.10.2.110', our source address is '10.10.2.146'. [Dec 20 10:26:47] DEBUG[17064]: chan_sip.c:3573 ast_sip_ouraddrfor: Setting SIP_TRANSPORT_UDP with address 10.10.2.146:5060 [Dec 20 10:26:47] DEBUG[17064]: chan_sip.c:7960 sip_alloc: Allocating new SIP dialog for 155cde7a93856bc5@10.10.2.110 - INVITE (No RTP) [Dec 20 10:26:47] DEBUG[17064]: sip/reqresp_parser.c:1550 parse_sip_options: Begin: parsing SIP "Supported: timer" [Dec 20 10:26:47] DEBUG[17064]: sip/reqresp_parser.c:1566 parse_sip_options: Found SIP option: -timer- [Dec 20 10:26:47] DEBUG[17064]: sip/reqresp_parser.c:1574 parse_sip_options: Matched SIP option: timer [Dec 20 10:26:47] DEBUG[17064]: rtp_engine.c:276 ast_rtp_instance_new: Using engine 'asterisk' for RTP instance '0xa1c3670' [Dec 20 10:26:47] DEBUG[17064]: res_rtp_asterisk.c:557 ast_rtp_new: Allocated port 12762 for RTP instance '0xa1c3670' [Dec 20 10:26:47] DEBUG[17064]: rtp_engine.c:285 ast_rtp_instance_new: RTP instance '0xa1c3670' is setup and ready to go [Dec 20 10:26:47] DEBUG[17064]: res_rtp_asterisk.c:2539 ast_rtp_prop_set: Setup RTCP on RTP instance '0xa1c3670' == Using SIP RTP CoS mark 5 [Dec 20 10:26:47] DEBUG[17064]: chan_sip.c:5190 do_setnat: Setting NAT on RTP to Off [Dec 20 10:26:47] DEBUG[17064]: chan_sip.c:9163 process_sdp: Processing session-level SDP v=0... UNSUPPORTED OR FAILED. [Dec 20 10:26:47] DEBUG[17064]: chan_sip.c:9163 process_sdp: Processing session-level SDP o=- 211649939 211649939 IN IP4 10.10.2.110... UNSUPPORTED OR FAILED. [Dec 20 10:26:47] DEBUG[17064]: chan_sip.c:9163 process_sdp: Processing session-level SDP s=-... UNSUPPORTED OR FAILED. [Dec 20 10:26:47] DEBUG[17064]: chan_sip.c:9163 process_sdp: Processing session-level SDP c=IN IP4 10.10.2.110... OK. [Dec 20 10:26:47] DEBUG[17064]: chan_sip.c:9163 process_sdp: Processing session-level SDP t=0 0... UNSUPPORTED OR FAILED. [Dec 20 10:26:47] DEBUG[17064]: rtp_engine.c:477 ast_rtp_codecs_payloads_set_m_type: Setting payload 18 based on m type on 0xb7910ec8 [Dec 20 10:26:47] DEBUG[17064]: rtp_engine.c:477 ast_rtp_codecs_payloads_set_m_type: Setting payload 8 based on m type on 0xb7910ec8 [Dec 20 10:26:47] DEBUG[17064]: rtp_engine.c:477 ast_rtp_codecs_payloads_set_m_type: Setting payload 101 based on m type on 0xb7910ec8 [Dec 20 10:26:47] DEBUG[17064]: chan_sip.c:9451 process_sdp: Processing media-level (audio) SDP a=rtpmap:18 G729/8000... OK. [Dec 20 10:26:47] DEBUG[17064]: chan_sip.c:9451 process_sdp: Processing media-level (audio) SDP a=rtpmap:8 PCMA/8000... OK. [Dec 20 10:26:47] DEBUG[17064]: chan_sip.c:9451 process_sdp: Processing media-level (audio) SDP a=rtpmap:101 telephone-event/8000... OK. [Dec 20 10:26:47] DEBUG[17064]: chan_sip.c:9451 process_sdp: Processing media-level (audio) SDP a=fmtp:101 0-15... UNSUPPORTED OR FAILED. [Dec 20 10:26:47] DEBUG[17064]: chan_sip.c:9451 process_sdp: Processing media-level (audio) SDP a=ptime:30... OK. [Dec 20 10:26:47] DEBUG[17064]: rtp_engine.c:598 ast_rtp_codecs_payload_formats: Incorporating payload 8 on 0xb7910ec8 [Dec 20 10:26:47] DEBUG[17064]: rtp_engine.c:598 ast_rtp_codecs_payload_formats: Incorporating payload 18 on 0xb7910ec8 [Dec 20 10:26:47] DEBUG[17064]: rtp_engine.c:598 ast_rtp_codecs_payload_formats: Incorporating payload 101 on 0xb7910ec8 [Dec 20 10:26:47] DEBUG[17064]: res_rtp_asterisk.c:2579 ast_rtp_remote_address_set: Setting RTCP address on RTP instance '0xa1c3670' [Dec 20 10:26:47] DEBUG[17064]: rtp_engine.c:453 ast_rtp_codecs_payloads_copy: Copying payload 8 from 0xb7910ec8 to 0xa1c381c [Dec 20 10:26:47] DEBUG[17064]: rtp_engine.c:453 ast_rtp_codecs_payloads_copy: Copying payload 18 from 0xb7910ec8 to 0xa1c381c [Dec 20 10:26:47] DEBUG[17064]: rtp_engine.c:453 ast_rtp_codecs_payloads_copy: Copying payload 101 from 0xb7910ec8 to 0xa1c381c [Dec 20 10:26:47] DEBUG[17064]: res_rtp_asterisk.c:2505 ast_rtp_prop_set: Ignoring duplicate RTCP property on RTP instance '0xa1c3670' [Dec 20 10:26:47] DEBUG[17064]: chan_sip.c:9710 process_sdp: We're settling with these formats: (g729) [Dec 20 10:26:47] DEBUG[17064]: chan_sip.c:23530 handle_request_invite: Checking SIP call limits for device 2222 [Dec 20 10:26:47] DEBUG[17064]: chan_sip.c:6012 update_call_counter: Updating call counter for incoming call [Dec 20 10:26:47] DEBUG[17064]: chan_sip.c:7198 sip_new: *** Our native formats are (g729) [Dec 20 10:26:47] DEBUG[17064]: chan_sip.c:7199 sip_new: *** Joint capabilities are (g729) [Dec 20 10:26:47] DEBUG[17064]: chan_sip.c:7200 sip_new: *** Our capabilities are (g729|ilbc) [Dec 20 10:26:47] DEBUG[17064]: chan_sip.c:7201 sip_new: *** AST_CODEC_CHOOSE formats are g729 [Dec 20 10:26:47] DEBUG[17064]: chan_sip.c:7229 sip_new: This channel will not be able to handle video. [Dec 20 10:26:47] DEBUG[17064]: chan_sip.c:14805 build_route: build_route: Contact hop: 2222 [Dec 20 10:26:47] DEBUG[17064]: chan_sip.c:23645 handle_request_invite: Incoming INVITE with 'timer' option supported and "Session-Expires" header. [Dec 20 10:26:47] DEBUG[17064]: chan_sip.c:27208 parse_session_expires: Session-Expires: 30 [Dec 20 10:26:47] DEBUG[17064]: chan_sip.c:27221 parse_session_expires: Refresher: UAC [Dec 20 10:26:47] DEBUG[17064]: chan_sip.c:3421 __sip_xmit: Trying to put 'SIP/2.0 422' onto UDP socket destined for 10.10.2.110:5060 [Dec 20 10:26:47] DEBUG[17064]: chan_sip.c:26467 handle_request_do: SIP message could not be handled, bad request: 155cde7a93856bc5@10.10.2.110 [Dec 20 10:26:47] DEBUG[17064]: chan_sip.c:4105 __sip_ack: Stopping retransmission on '155cde7a93856bc5@10.10.2.110' of Response 1: Match Not Found [Dec 20 10:26:47] DEBUG[17064]: chan_sip.c:9163 process_sdp: Processing session-level SDP v=0... UNSUPPORTED OR FAILED. [Dec 20 10:26:47] DEBUG[17064]: chan_sip.c:9833 process_sdp_o: Call 155cde7a93856bc5@10.10.2.110 responded to our reinvite without changing SDP version; ignoring SDP. [Dec 20 10:26:47] DEBUG[17064]: chan_sip.c:23645 handle_request_invite: Incoming INVITE with 'timer' option supported and "Session-Expires" header. [Dec 20 10:26:47] DEBUG[17064]: chan_sip.c:27208 parse_session_expires: Session-Expires: 90 [Dec 20 10:26:47] DEBUG[17064]: chan_sip.c:27221 parse_session_expires: Refresher: UAC [Dec 20 10:26:47] DEBUG[17064]: chan_sip.c:27178 parse_minse: Received Min-SE: 90 [Dec 20 10:26:47] DEBUG[17064]: chan_sip.c:23897 handle_request_invite: SIP/2222-00000000: This call is UP.... [Dec 20 10:26:47] DEBUG[17064]: chan_sip.c:3421 __sip_xmit: Trying to put 'SIP/2.0 100' onto UDP socket destined for 10.10.2.110:5060 [Dec 20 10:26:47] DEBUG[17064]: chan_sip.c:12259 transmit_response_with_sdp: Setting framing from config on incoming call [Dec 20 10:26:47] DEBUG[17064]: chan_sip.c:11835 add_sdp: ** Our capability: (g729) Video flag: True Text flag: True [Dec 20 10:26:47] DEBUG[17064]: chan_sip.c:11836 add_sdp: ** Our prefcodec: (nothing) [Dec 20 10:26:47] DEBUG[17064]: chan_sip.c:11955 add_sdp: -- Done with adding codecs to SDP [Dec 20 10:26:47] DEBUG[17064]: chan_sip.c:12150 add_sdp: Done building SDP. Settling with this capability: (g729) [Dec 20 10:26:47] DEBUG[17064]: chan_sip.c:3421 __sip_xmit: Trying to put 'SIP/2.0 200' onto UDP socket destined for 10.10.2.110:5060 [Dec 20 10:26:47] DEBUG[17064]: chan_sip.c:4105 __sip_ack: Stopping retransmission on '155cde7a93856bc5@10.10.2.110' of Response 2: Match Found AlogSP_Atento*CLI> core show channels Channel Location State Application(Data) SIP/2222-00000000 21262758@saida:1 Down (None) 1 active channel 0 active calls 0 calls processed [Dec 20 10:27:06] DEBUG[17064]: chan_sip.c:3110 sip_alreadygone: Setting SIP_ALREADYGONE on dialog 155cde7a93856bc5@10.10.2.110 [Dec 20 10:27:06] DEBUG[17064]: res_rtp_asterisk.c:2579 ast_rtp_remote_address_set: Setting RTCP address on RTP instance '0xa1c3670' [Dec 20 10:27:06] DEBUG[17064]: chan_sip.c:24810 handle_request_bye: Received bye, issuing owner hangup [Dec 20 10:27:06] DEBUG[17064]: chan_sip.c:3421 __sip_xmit: Trying to put 'SIP/2.0 200' onto UDP socket destined for 10.10.2.110:5060 AlogSP_Atento*CLI> core show channels Channel Location State Application(Data) SIP/2222-00000000 21262758@saida:1 Down (None) 1 active channel 0 active calls 0 calls processed [Dec 20 10:27:38] WARNING[17064]: chan_sip.c:3947 __sip_autodestruct: Autodestruct on dialog '155cde7a93856bc5@10.10.2.110' with owner SIP/2222-00000000 in place (Method: BYE). Rescheduling destruction for 10000 ms AlogSP_Atento*CLI> core show channels Channel Location State Application(Data) SIP/2222-00000000 21262758@saida:1 Down (None) 1 active channel 0 active calls 0 calls processed [Dec 20 10:27:53] DEBUG[17064]: chan_sip.c:3056 registry_addref: SIP Registry sip2.tellfree.net: refcount now 3 [Dec 20 10:27:53] DEBUG[17064]: chan_sip.c:3048 registry_unref: SIP Registry sip2.tellfree.net: refcount now 2 [Dec 20 10:27:53] DEBUG[17064]: chan_sip.c:7960 sip_alloc: Allocating new SIP dialog for 34a5a4a2353cb2dc691e46206bc58a59@127.0.0.1 - REGISTER (No RTP) [Dec 20 10:27:53] DEBUG[17064]: chan_sip.c:3056 registry_addref: SIP Registry sip2.tellfree.net: refcount now 3 [Dec 20 10:27:53] DEBUG[17064]: acl.c:736 ast_ouraddrfor: For destination '201.33.209.42', our source address is '10.10.2.146'. [Dec 20 10:27:53] DEBUG[17064]: chan_sip.c:3573 ast_sip_ouraddrfor: Setting SIP_TRANSPORT_UDP with address 10.10.2.146:5060 [Dec 20 10:27:53] DEBUG[17064]: chan_sip.c:3056 registry_addref: SIP Registry sip2.tellfree.net: refcount now 4 [Dec 20 10:27:53] DEBUG[17064]: chan_sip.c:13867 transmit_register: Scheduled a registration timeout for sip2.tellfree.net id #29 [Dec 20 10:27:53] DEBUG[17064]: chan_sip.c:3097 initialize_initreq: Initializing initreq for method REGISTER - callid 34a5a4a2353cb2dc691e46206bc58a59@127.0.0.1 [Dec 20 10:27:53] DEBUG[17064]: chan_sip.c:3421 __sip_xmit: Trying to put 'REGISTER si' onto UDP socket destined for 201.33.209.42:5060 [Dec 20 10:27:53] DEBUG[17064]: chan_sip.c:3048 registry_unref: SIP Registry sip2.tellfree.net: refcount now 3 [Dec 20 10:27:53] DEBUG[17064]: chan_sip.c:4105 __sip_ack: Stopping retransmission on '34a5a4a2353cb2dc691e46206bc58a59@127.0.0.1' of Request 108: Match Found [Dec 20 10:27:53] DEBUG[17064]: chan_sip.c:3056 registry_addref: SIP Registry sip2.tellfree.net: refcount now 4 [Dec 20 10:27:53] DEBUG[17064]: chan_sip.c:3048 registry_unref: SIP Registry sip2.tellfree.net: refcount now 3 [Dec 20 10:27:53] DEBUG[17064]: chan_sip.c:3095 initialize_initreq: Initializing already initialized SIP dialog 34a5a4a2353cb2dc691e46206bc58a59@127.0.0.1 (presumably reinvite) [Dec 20 10:27:53] DEBUG[17064]: chan_sip.c:3421 __sip_xmit: Trying to put 'REGISTER si' onto UDP socket destined for 201.33.209.42:5060 [Dec 20 10:27:53] DEBUG[17064]: chan_sip.c:4105 __sip_ack: Stopping retransmission on '34a5a4a2353cb2dc691e46206bc58a59@127.0.0.1' of Request 109: Match Found [Dec 20 10:27:53] DEBUG[17064]: chan_sip.c:21543 handle_response_register: Registration successful [Dec 20 10:27:53] DEBUG[17064]: chan_sip.c:21545 handle_response_register: Cancelling timeout 29 [Dec 20 10:27:53] DEBUG[17064]: chan_sip.c:3048 registry_unref: SIP Registry sip2.tellfree.net: refcount now 2 [Dec 20 10:27:53] DEBUG[17064]: chan_sip.c:3048 registry_unref: SIP Registry sip2.tellfree.net: refcount now 1 [Dec 20 10:27:53] DEBUG[17064]: chan_sip.c:3056 registry_addref: SIP Registry sip2.tellfree.net: refcount now 2 AlogSP_Atento*CLI> core show channels Channel Location State Application(Data) SIP/2222-00000000 21262758@saida:1 Down (None) 1 active channel 0 active calls 0 calls processed [Dec 20 10:28:10] WARNING[17064]: chan_sip.c:3947 __sip_autodestruct: Autodestruct on dialog '155cde7a93856bc5@10.10.2.110' with owner SIP/2222-00000000 in place (Method: BYE). Rescheduling destruction for 10000 ms [Dec 20 10:28:25] DEBUG[17064]: chan_sip.c:3961 __sip_autodestruct: Auto destroying SIP dialog '34a5a4a2353cb2dc691e46206bc58a59@127.0.0.1' [Dec 20 10:28:25] DEBUG[17064]: chan_sip.c:6160 sip_destroy: Destroying SIP dialog 34a5a4a2353cb2dc691e46206bc58a59@127.0.0.1 [Dec 20 10:28:42] WARNING[17064]: chan_sip.c:3947 __sip_autodestruct: Autodestruct on dialog '155cde7a93856bc5@10.10.2.110' with owner SIP/2222-00000000 in place (Method: BYE). Rescheduling destruction for 10000 ms [Dec 20 10:29:14] WARNING[17064]: chan_sip.c:3947 __sip_autodestruct: Autodestruct on dialog '155cde7a93856bc5@10.10.2.110' with owner SIP/2222-00000000 in place (Method: BYE). Rescheduling destruction for 10000 ms AlogSP_Atento*CLI> core show channels Channel Location State Application(Data) SIP/2222-00000000 21262758@saida:1 Down (None) 1 active channel 0 active calls 0 calls processed AlogSP_Atento*CLI>