<--- SIP read from UDP:192.168.17.141:5060 ---> INVITE sip:*1@kerrfamily.org;user=phone SIP/2.0 Via: SIP/2.0/UDP 192.168.17.141:5060;branch=z9hG4bK936897524699b92cd57262cc79e62d98;rport From: "Cordless 1" ;tag=2709006301 To: Call-ID: 2496317345@192_168_17_141 CSeq: 2 INVITE Contact: Max-Forwards: 70 User-Agent: C610A IP/42.072.00.000.000 Supported: replaces Allow-Events: message-summary, refer, ua-profile Allow: INVITE, ACK, CANCEL, BYE, OPTIONS, INFO, REFER, SUBSCRIBE, NOTIFY Content-Type: application/sdp Content-Length: 384 v=0 o=111 5012 18 IN IP4 192.168.17.141 s=Mapping c=IN IP4 192.168.17.141 t=0 0 m=audio 5012 RTP/AVP 9 0 8 96 97 2 18 101 a=rtpmap:9 G722/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:96 G726-32/8000 a=rtpmap:97 AAL2-G726-32/8000 a=rtpmap:2 G726-32/8000 a=rtpmap:18 G729/8000 a=fmtp:18 annexb=no a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 <-------------> [Sep 23 11:47:32] DEBUG[840]: chan_sip.c:8521 parse_request: Header 0 [ 47]: INVITE sip:*1@kerrfamily.org;user=phone SIP/2.0 [Sep 23 11:47:32] DEBUG[840]: chan_sip.c:8521 parse_request: Header 1 [ 89]: Via: SIP/2.0/UDP 192.168.17.141:5060;branch=z9hG4bK936897524699b92cd57262cc79e62d98;rport [Sep 23 11:47:32] DEBUG[840]: chan_sip.c:8521 parse_request: Header 2 [ 58]: From: "Cordless 1" ;tag=2709006301 [Sep 23 11:47:32] DEBUG[840]: chan_sip.c:8521 parse_request: Header 3 [ 38]: To: [Sep 23 11:47:32] DEBUG[840]: chan_sip.c:8521 parse_request: Header 4 [ 34]: Call-ID: 2496317345@192_168_17_141 [Sep 23 11:47:32] DEBUG[840]: chan_sip.c:8521 parse_request: Header 5 [ 14]: CSeq: 2 INVITE [Sep 23 11:47:32] DEBUG[840]: chan_sip.c:8521 parse_request: Header 6 [ 38]: Contact: [Sep 23 11:47:32] DEBUG[840]: chan_sip.c:8521 parse_request: Header 7 [ 16]: Max-Forwards: 70 [Sep 23 11:47:32] DEBUG[840]: chan_sip.c:8521 parse_request: Header 8 [ 38]: User-Agent: C610A IP/42.072.00.000.000 [Sep 23 11:47:32] DEBUG[840]: chan_sip.c:8521 parse_request: Header 9 [ 19]: Supported: replaces [Sep 23 11:47:32] DEBUG[840]: chan_sip.c:8521 parse_request: Header 10 [ 48]: Allow-Events: message-summary, refer, ua-profile [Sep 23 11:47:32] DEBUG[840]: chan_sip.c:8521 parse_request: Header 11 [ 72]: Allow: INVITE, ACK, CANCEL, BYE, OPTIONS, INFO, REFER, SUBSCRIBE, NOTIFY [Sep 23 11:47:32] DEBUG[840]: chan_sip.c:8521 parse_request: Header 12 [ 29]: Content-Type: application/sdp [Sep 23 11:47:32] DEBUG[840]: chan_sip.c:8521 parse_request: Header 13 [ 19]: Content-Length: 384 [Sep 23 11:47:32] DEBUG[840]: chan_sip.c:8521 parse_request: Header 14 [ 0]: [Sep 23 11:47:32] DEBUG[840]: chan_sip.c:8521 parse_request: Body 0 [ 3]: v=0 [Sep 23 11:47:32] DEBUG[840]: chan_sip.c:8521 parse_request: Body 1 [ 35]: o=111 5012 18 IN IP4 192.168.17.141 [Sep 23 11:47:32] DEBUG[840]: chan_sip.c:8521 parse_request: Body 2 [ 9]: s=Mapping [Sep 23 11:47:32] DEBUG[840]: chan_sip.c:8521 parse_request: Body 3 [ 23]: c=IN IP4 192.168.17.141 [Sep 23 11:47:32] DEBUG[840]: chan_sip.c:8521 parse_request: Body 4 [ 5]: t=0 0 [Sep 23 11:47:32] DEBUG[840]: chan_sip.c:8521 parse_request: Body 5 [ 41]: m=audio 5012 RTP/AVP 9 0 8 96 97 2 18 101 [Sep 23 11:47:32] DEBUG[840]: chan_sip.c:8521 parse_request: Body 6 [ 20]: a=rtpmap:9 G722/8000 [Sep 23 11:47:32] DEBUG[840]: chan_sip.c:8521 parse_request: Body 7 [ 20]: a=rtpmap:0 PCMU/8000 [Sep 23 11:47:32] DEBUG[840]: chan_sip.c:8521 parse_request: Body 8 [ 20]: a=rtpmap:8 PCMA/8000 [Sep 23 11:47:32] DEBUG[840]: chan_sip.c:8521 parse_request: Body 9 [ 24]: a=rtpmap:96 G726-32/8000 [Sep 23 11:47:32] DEBUG[840]: chan_sip.c:8521 parse_request: Body 10 [ 29]: a=rtpmap:97 AAL2-G726-32/8000 [Sep 23 11:47:32] DEBUG[840]: chan_sip.c:8521 parse_request: Body 11 [ 23]: a=rtpmap:2 G726-32/8000 [Sep 23 11:47:32] DEBUG[840]: chan_sip.c:8521 parse_request: Body 12 [ 21]: a=rtpmap:18 G729/8000 [Sep 23 11:47:32] DEBUG[840]: chan_sip.c:8521 parse_request: Body 13 [ 19]: a=fmtp:18 annexb=no [Sep 23 11:47:32] DEBUG[840]: chan_sip.c:8521 parse_request: Body 14 [ 33]: a=rtpmap:101 telephone-event/8000 [Sep 23 11:47:32] DEBUG[840]: chan_sip.c:8521 parse_request: Body 15 [ 15]: a=fmtp:101 0-16 [Sep 23 11:47:32] DEBUG[840]: chan_sip.c:8558 parse_request: Body 16 [ 10]: a=ptime:20 --- (14 headers 17 lines) --- [Sep 23 11:47:32] DEBUG[840]: chan_sip.c:8115 find_call: = Looking for Call ID: 2496317345@192_168_17_141 (Checking From) --From tag 2709006301 --To-tag [Sep 23 11:47:32] DEBUG[840]: acl.c:736 ast_ouraddrfor: For destination '192.168.17.141', our source address is '192.168.17.1'. [Sep 23 11:47:32] DEBUG[840]: chan_sip.c:3498 ast_sip_ouraddrfor: Setting SIP_TRANSPORT_UDP with address 192.168.17.1:5060 [Sep 23 11:47:32] DEBUG[840]: chan_sip.c:7795 sip_alloc: Allocating new SIP dialog for 2496317345@192_168_17_141 - INVITE (No RTP) [Sep 23 11:47:32] DEBUG[840]: chan_sip.c:25254 handle_incoming: **** Received INVITE (5) - Command in SIP INVITE [Sep 23 11:47:32] DEBUG[840]: sip/reqresp_parser.c:1550 parse_sip_options: Begin: parsing SIP "Supported: replaces" [Sep 23 11:47:32] DEBUG[840]: sip/reqresp_parser.c:1566 parse_sip_options: Found SIP option: -replaces- [Sep 23 11:47:32] DEBUG[840]: sip/reqresp_parser.c:1574 parse_sip_options: Matched SIP option: replaces [Sep 23 11:47:32] DEBUG[840]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting '192.168.17.141:5060' into... [Sep 23 11:47:32] DEBUG[840]: netsock2.c:192 ast_sockaddr_split_hostport: ...host '192.168.17.141' and port '5060'. Sending to 192.168.17.141:5060 (NAT) [Sep 23 11:47:32] DEBUG[840]: chan_sip.c:22643 handle_request_invite: Initializing initreq for method INVITE - callid 2496317345@192_168_17_141 Using INVITE request as basis request - 2496317345@192_168_17_141 [Sep 23 11:47:32] DEBUG[840]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting 'kerrfamily.org' into... [Sep 23 11:47:32] DEBUG[840]: netsock2.c:192 ast_sockaddr_split_hostport: ...host 'kerrfamily.org' and port ''. Found peer '111' for '111' from 192.168.17.141:5060 [Sep 23 11:47:32] DEBUG[840]: rtp_engine.c:350 ast_rtp_instance_new: Using engine 'asterisk' for RTP instance '0x8896740' [Sep 23 11:47:32] DEBUG[840]: res_rtp_asterisk.c:556 ast_rtp_new: Allocated port 16590 for RTP instance '0x8896740' [Sep 23 11:47:32] DEBUG[840]: rtp_engine.c:359 ast_rtp_instance_new: RTP instance '0x8896740' is setup and ready to go [Sep 23 11:47:32] DEBUG[840]: res_rtp_asterisk.c:2523 ast_rtp_prop_set: Setup RTCP on RTP instance '0x8896740' == Using SIP RTP CoS mark 5 [Sep 23 11:47:32] DEBUG[840]: chan_sip.c:5093 do_setnat: Setting NAT on RTP to On [Sep 23 11:47:32] DEBUG[840]: chan_sip.c:8908 process_sdp: Processing session-level SDP v=0... UNSUPPORTED OR FAILED. [Sep 23 11:47:32] DEBUG[840]: chan_sip.c:8908 process_sdp: Processing session-level SDP o=111 5012 18 IN IP4 192.168.17.141... UNSUPPORTED OR FAILED. [Sep 23 11:47:32] DEBUG[840]: chan_sip.c:8908 process_sdp: Processing session-level SDP s=Mapping... UNSUPPORTED OR FAILED. [Sep 23 11:47:32] DEBUG[840]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting '192.168.17.141' into... [Sep 23 11:47:32] DEBUG[840]: netsock2.c:192 ast_sockaddr_split_hostport: ...host '192.168.17.141' and port ''. [Sep 23 11:47:32] DEBUG[840]: chan_sip.c:8908 process_sdp: Processing session-level SDP c=IN IP4 192.168.17.141... OK. [Sep 23 11:47:32] DEBUG[840]: chan_sip.c:8908 process_sdp: Processing session-level SDP t=0 0... UNSUPPORTED OR FAILED. Found RTP audio format 9 [Sep 23 11:47:32] DEBUG[840]: rtp_engine.c:541 ast_rtp_codecs_payloads_set_m_type: Setting payload 9 based on m type on 0x41503e70 Found RTP audio format 0 [Sep 23 11:47:32] DEBUG[840]: rtp_engine.c:541 ast_rtp_codecs_payloads_set_m_type: Setting payload 0 based on m type on 0x41503e70 Found RTP audio format 8 [Sep 23 11:47:32] DEBUG[840]: rtp_engine.c:541 ast_rtp_codecs_payloads_set_m_type: Setting payload 8 based on m type on 0x41503e70 Found RTP audio format 96 Found RTP audio format 97 [Sep 23 11:47:32] DEBUG[840]: rtp_engine.c:541 ast_rtp_codecs_payloads_set_m_type: Setting payload 97 based on m type on 0x41503e70 Found RTP audio format 2 Found RTP audio format 18 [Sep 23 11:47:32] DEBUG[840]: rtp_engine.c:541 ast_rtp_codecs_payloads_set_m_type: Setting payload 18 based on m type on 0x41503e70 Found RTP audio format 101 [Sep 23 11:47:32] DEBUG[840]: rtp_engine.c:541 ast_rtp_codecs_payloads_set_m_type: Setting payload 101 based on m type on 0x41503e70 Found audio description format G722 for ID 9 [Sep 23 11:47:32] DEBUG[840]: chan_sip.c:9179 process_sdp: Processing media-level (audio) SDP a=rtpmap:9 G722/8000... OK. Found audio description format PCMU for ID 0 [Sep 23 11:47:32] DEBUG[840]: chan_sip.c:9179 process_sdp: Processing media-level (audio) SDP a=rtpmap:0 PCMU/8000... OK. Found audio description format PCMA for ID 8 [Sep 23 11:47:32] DEBUG[840]: chan_sip.c:9179 process_sdp: Processing media-level (audio) SDP a=rtpmap:8 PCMA/8000... OK. Found audio description format G726-32 for ID 96 [Sep 23 11:47:32] DEBUG[840]: chan_sip.c:9179 process_sdp: Processing media-level (audio) SDP a=rtpmap:96 G726-32/8000... OK. Found audio description format AAL2-G726-32 for ID 97 [Sep 23 11:47:32] DEBUG[840]: chan_sip.c:9179 process_sdp: Processing media-level (audio) SDP a=rtpmap:97 AAL2-G726-32/8000... OK. Found audio description format G726-32 for ID 2 [Sep 23 11:47:32] DEBUG[840]: chan_sip.c:9179 process_sdp: Processing media-level (audio) SDP a=rtpmap:2 G726-32/8000... OK. Found audio description format G729 for ID 18 [Sep 23 11:47:32] DEBUG[840]: chan_sip.c:9179 process_sdp: Processing media-level (audio) SDP a=rtpmap:18 G729/8000... OK. [Sep 23 11:47:32] DEBUG[840]: chan_sip.c:9179 process_sdp: Processing media-level (audio) SDP a=fmtp:18 annexb=no... UNSUPPORTED OR FAILED. Found audio description format telephone-event for ID 101 [Sep 23 11:47:32] DEBUG[840]: chan_sip.c:9179 process_sdp: Processing media-level (audio) SDP a=rtpmap:101 telephone-event/8000... OK. [Sep 23 11:47:32] DEBUG[840]: chan_sip.c:9179 process_sdp: Processing media-level (audio) SDP a=fmtp:101 0-16... UNSUPPORTED OR FAILED. [Sep 23 11:47:32] DEBUG[840]: chan_sip.c:9179 process_sdp: Processing media-level (audio) SDP a=ptime:20... OK. [Sep 23 11:47:32] DEBUG[840]: rtp_engine.c:644 ast_rtp_codecs_payload_formats: Incorporating payload 0 on 0x41503e70 [Sep 23 11:47:32] DEBUG[840]: rtp_engine.c:644 ast_rtp_codecs_payload_formats: Incorporating payload 2 on 0x41503e70 [Sep 23 11:47:32] DEBUG[840]: rtp_engine.c:644 ast_rtp_codecs_payload_formats: Incorporating payload 8 on 0x41503e70 [Sep 23 11:47:32] DEBUG[840]: rtp_engine.c:644 ast_rtp_codecs_payload_formats: Incorporating payload 9 on 0x41503e70 [Sep 23 11:47:32] DEBUG[840]: rtp_engine.c:644 ast_rtp_codecs_payload_formats: Incorporating payload 18 on 0x41503e70 [Sep 23 11:47:32] DEBUG[840]: rtp_engine.c:644 ast_rtp_codecs_payload_formats: Incorporating payload 96 on 0x41503e70 [Sep 23 11:47:32] DEBUG[840]: rtp_engine.c:644 ast_rtp_codecs_payload_formats: Incorporating payload 97 on 0x41503e70 [Sep 23 11:47:32] DEBUG[840]: rtp_engine.c:644 ast_rtp_codecs_payload_formats: Incorporating payload 101 on 0x41503e70 Capabilities: us - 0x1104 (ulaw|g729|g722), peer - audio=0x191c (ulaw|alaw|g726|g729|g726aal2|g722)/video=0x0 (nothing)/text=0x0 (nothing), combined - 0x1104 (ulaw|g729|g722) Non-codec capabilities (dtmf): us - 0x1 (telephone-event|), peer - 0x1 (telephone-event|), combined - 0x1 (telephone-event|) [Sep 23 11:47:32] DEBUG[840]: res_rtp_asterisk.c:2563 ast_rtp_remote_address_set: Setting RTCP address on RTP instance '0x8896740' Peer audio RTP is at port 192.168.17.141:5012 [Sep 23 11:47:32] DEBUG[840]: rtp_engine.c:522 ast_rtp_codecs_payloads_copy: Copying payload 0 from 0x41503e70 to 0x88968ec [Sep 23 11:47:32] DEBUG[840]: rtp_engine.c:522 ast_rtp_codecs_payloads_copy: Copying payload 2 from 0x41503e70 to 0x88968ec [Sep 23 11:47:32] DEBUG[840]: rtp_engine.c:522 ast_rtp_codecs_payloads_copy: Copying payload 8 from 0x41503e70 to 0x88968ec [Sep 23 11:47:32] DEBUG[840]: rtp_engine.c:522 ast_rtp_codecs_payloads_copy: Copying payload 9 from 0x41503e70 to 0x88968ec [Sep 23 11:47:32] DEBUG[840]: rtp_engine.c:522 ast_rtp_codecs_payloads_copy: Copying payload 18 from 0x41503e70 to 0x88968ec [Sep 23 11:47:32] DEBUG[840]: rtp_engine.c:522 ast_rtp_codecs_payloads_copy: Copying payload 96 from 0x41503e70 to 0x88968ec [Sep 23 11:47:32] DEBUG[840]: rtp_engine.c:522 ast_rtp_codecs_payloads_copy: Copying payload 97 from 0x41503e70 to 0x88968ec [Sep 23 11:47:32] DEBUG[840]: rtp_engine.c:522 ast_rtp_codecs_payloads_copy: Copying payload 101 from 0x41503e70 to 0x88968ec [Sep 23 11:47:32] DEBUG[840]: res_rtp_asterisk.c:2489 ast_rtp_prop_set: Ignoring duplicate RTCP property on RTP instance '0x8896740' [Sep 23 11:47:32] DEBUG[840]: chan_sip.c:9424 process_sdp: We're settling with these formats: 0x1104 (ulaw|g729|g722) [Sep 23 11:47:32] DEBUG[840]: chan_sip.c:22780 handle_request_invite: Checking SIP call limits for device 111 [Sep 23 11:47:32] DEBUG[840]: chan_sip.c:5905 update_call_counter: Updating call counter for incoming call [Sep 23 11:47:32] DEBUG[840]: chan_sip.c:6010 update_call_counter: Call from peer '111' is 1 out of 10 [Sep 23 11:47:32] DEBUG[840]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting 'kerrfamily.org' into... [Sep 23 11:47:32] DEBUG[840]: netsock2.c:192 ast_sockaddr_split_hostport: ...host 'kerrfamily.org' and port ''. [Sep 23 11:47:32] DEBUG[840]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting 'kerrfamily.org' into... [Sep 23 11:47:32] DEBUG[840]: netsock2.c:192 ast_sockaddr_split_hostport: ...host 'kerrfamily.org' and port ''. Looking for *1 in DialPlanSLA (domain kerrfamily.org) [Sep 23 11:47:32] DEBUG[840]: chan_sip.c:7091 sip_new: *** Our native formats are 0x1000 (g722) [Sep 23 11:47:32] DEBUG[840]: chan_sip.c:7092 sip_new: *** Joint capabilities are 0x1104 (ulaw|g729|g722) [Sep 23 11:47:32] DEBUG[840]: chan_sip.c:7093 sip_new: *** Our capabilities are 0x1104 (ulaw|g729|g722) [Sep 23 11:47:32] DEBUG[840]: chan_sip.c:7094 sip_new: *** AST_CODEC_CHOOSE formats are 0x1000 (g722) [Sep 23 11:47:32] DEBUG[840]: chan_sip.c:7124 sip_new: This channel will not be able to handle video. [Sep 23 11:47:32] DEBUG[840]: dsp.c:475 ast_tone_detect_init: Setup tone 1100 Hz, 500 ms, block_size=160, hits_required=21 [Sep 23 11:47:32] DEBUG[840]: dsp.c:475 ast_tone_detect_init: Setup tone 2100 Hz, 2600 ms, block_size=160, hits_required=116 [Sep 23 11:47:32] DEBUG[840]: chan_sip.c:14336 build_route: build_route: Contact hop: list_route: hop: [Sep 23 11:47:32] DEBUG[840]: chan_sip.c:23081 handle_request_invite: SIP/111-00000004: New call is still down.... Trying... <--- Transmitting (NAT) to 192.168.17.141:5060 ---> SIP/2.0 100 Trying Via: SIP/2.0/UDP 192.168.17.141:5060;branch=z9hG4bK936897524699b92cd57262cc79e62d98;received=192.168.17.141;rport=5060 From: "Cordless 1" ;tag=2709006301 To: Call-ID: 2496317345@192_168_17_141 CSeq: 2 INVITE Server: Asterisk PBX 1.8.15.0 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH Supported: replaces, timer Contact: Content-Length: 0 <------------> [Sep 23 11:47:32] DEBUG[840]: chan_sip.c:3346 __sip_xmit: Trying to put 'SIP/2.0 100' onto UDP socket destined for 192.168.17.141:5060 [Sep 23 11:47:32] DEBUG[822]: devicestate.c:342 _ast_device_state: No provider found, checking channel drivers for SIP - 111 [Sep 23 11:47:32] DEBUG[822]: chan_sip.c:26559 sip_devicestate: Checking device state for peer 111 [Sep 23 11:47:32] DEBUG[822]: devicestate.c:460 do_state_change: Changing state for SIP/111 - state 2 (In use) [Sep 23 11:47:32] DEBUG[822]: devicestate.c:440 devstate_event: device 'SIP/111' state '2' [Sep 23 11:47:32] DEBUG[822]: devicestate.c:342 _ast_device_state: No provider found, checking channel drivers for SIP - 111 [Sep 23 11:47:32] DEBUG[822]: chan_sip.c:26559 sip_devicestate: Checking device state for peer 111 [Sep 23 11:47:32] DEBUG[822]: devicestate.c:460 do_state_change: Changing state for SIP/111 - state 2 (In use) [Sep 23 11:47:32] DEBUG[822]: devicestate.c:440 devstate_event: device 'SIP/111' state '2' [Sep 23 11:47:32] DEBUG[1542]: pbx.c:4075 pbx_substitute_variables_helper_full: Function result is '111' [Sep 23 11:47:32] DEBUG[1542]: pbx.c:3256 ast_str_retrieve_variable: Result of 'EXTEN' is '*1' [Sep 23 11:47:32] DEBUG[1542]: pbx.c:4247 pbx_extension_helper: Launching 'SLAStation' -- Executing [*1@DialPlanSLA:1] SLAStation("SIP/111-00000004", "SLAphone111_SLAtrunk1") in new stack [Sep 23 11:47:32] DEBUG[1542]: devicestate.c:440 devstate_event: device 'SLA:SLAphone111_SLAtrunk1' state '2' [Sep 23 11:47:32] DEBUG[1542]: devicestate.c:440 devstate_event: device 'SLA:SLAphone112_SLAtrunk1' state '2' [Sep 23 11:47:32] DEBUG[1543]: channel.c:6152 ast_channel_inherit_variables: Not copying variable SIPCALLID. [Sep 23 11:47:32] DEBUG[1543]: channel.c:6152 ast_channel_inherit_variables: Not copying variable SIPDOMAIN. [Sep 23 11:47:32] DEBUG[1543]: channel.c:6152 ast_channel_inherit_variables: Not copying variable SIPURI. -- Called s@OutboundSLA [Sep 23 11:47:32] DEBUG[823]: app_queue.c:1585 extension_state_cb: Extension 'SLAphone111_SLAtrunk1@DialPlanSLA' changed to state '2' (In use) but we don't care because they're not a member of any queue. [Sep 23 11:47:32] DEBUG[823]: app_queue.c:1585 extension_state_cb: Extension 'SLAphone112_SLAtrunk1@DialPlanSLA' changed to state '2' (In use) but we don't care because they're not a member of any queue. [Sep 23 11:47:32] DEBUG[859]: app_queue.c:1490 handle_statechange: Device 'SIP/111' changed to state '2' (In use) but we don't care because they're not a member of any queue. [Sep 23 11:47:32] DEBUG[859]: app_queue.c:1490 handle_statechange: Device 'SIP/111' changed to state '2' (In use) but we don't care because they're not a member of any queue. [Sep 23 11:47:32] DEBUG[859]: app_queue.c:1490 handle_statechange: Device 'SLA:SLAphone111_SLAtrunk1' changed to state '2' (In use) but we don't care because they're not a member of any queue. [Sep 23 11:47:32] DEBUG[859]: app_queue.c:1490 handle_statechange: Device 'SLA:SLAphone112_SLAtrunk1' changed to state '2' (In use) but we don't care because they're not a member of any queue. [Sep 23 11:47:32] DEBUG[1544]: pbx.c:4247 pbx_extension_helper: Launching 'NoOp' -- Executing [s@OutboundSLA:1] NoOp("Local/s@OutboundSLA-b629;2", "SLA Outbound context") in new stack [Sep 23 11:47:32] DEBUG[1544]: pbx.c:3253 ast_str_retrieve_variable: Result of 'SLA_EXTEN' is NULL [Sep 23 11:47:32] DEBUG[1544]: pbx.c:4143 pbx_substitute_variables_helper_full: Expression result is '0' [Sep 23 11:47:32] DEBUG[1544]: pbx.c:3253 ast_str_retrieve_variable: Result of 'SLA_EXTEN' is NULL [Sep 23 11:47:32] DEBUG[1544]: pbx.c:4247 pbx_extension_helper: Launching 'GotoIf' -- Executing [s@OutboundSLA:2] GotoIf("Local/s@OutboundSLA-b629;2", "0?,1") in new stack [Sep 23 11:47:32] DEBUG[1544]: pbx.c:10179 pbx_builtin_gotoif: Not taking any branch [Sep 23 11:47:32] DEBUG[1544]: pbx.c:4247 pbx_extension_helper: Launching 'DISA' -- Executing [s@OutboundSLA:3] DISA("Local/s@OutboundSLA-b629;2", "no-password,OutboundSLA") in new stack [Sep 23 11:47:32] DEBUG[1544]: app_disa.c:173 disa_exec: Digittimeout: 5000 [Sep 23 11:47:32] DEBUG[1544]: app_disa.c:174 disa_exec: Responsetimeout: 10000 [Sep 23 11:47:32] DEBUG[1544]: app_disa.c:192 disa_exec: Mailbox: [Sep 23 11:47:32] DEBUG[1544]: chan_local.c:760 local_indicate: Blocked indication -1 -- Local/s@OutboundSLA-b629;1 answered [Sep 23 11:47:32] DEBUG[1543]: chan_dahdi.c:13646 dahdi_request: Using channel -2 [Sep 23 11:47:32] DEBUG[1543]: channel.c:5175 set_format: Set channel DAHDI/pseudo-84343286 to read format slin [Sep 23 11:47:32] DEBUG[1543]: channel.c:5175 set_format: Set channel DAHDI/pseudo-84343286 to write format slin -- Created MeetMe conference 1023 for conference 'SLA_SLAtrunk1' [Sep 23 11:47:32] DEBUG[1543]: devicestate.c:440 devstate_event: device 'meetme:SLA_SLAtrunk1' state '2' [Sep 23 11:47:32] DEBUG[1543]: chan_local.c:760 local_indicate: Blocked indication -1 [Sep 23 11:47:32] DEBUG[1543]: channel.c:5175 set_format: Set channel Local/s@OutboundSLA-b629;1 to write format slin [Sep 23 11:47:32] DEBUG[1543]: channel.c:5175 set_format: Set channel Local/s@OutboundSLA-b629;1 to read format slin [Sep 23 11:47:32] DEBUG[1543]: app_meetme.c:2683 conf_run: Placed channel Local/s@OutboundSLA-b629;1 in DAHDI conf 1023 [Sep 23 11:47:32] DEBUG[822]: devicestate.c:342 _ast_device_state: No provider found, checking channel drivers for Local - s@OutboundSLA [Sep 23 11:47:32] DEBUG[822]: chan_local.c:300 local_devicestate: Checking if extension s@OutboundSLA exists (devicestate) [Sep 23 11:47:32] DEBUG[822]: devicestate.c:460 do_state_change: Changing state for Local/s@OutboundSLA - state 2 (In use) [Sep 23 11:47:32] DEBUG[822]: devicestate.c:440 devstate_event: device 'Local/s@OutboundSLA' state '2' [Sep 23 11:47:32] DEBUG[822]: devicestate.c:342 _ast_device_state: No provider found, checking channel drivers for Local - s@OutboundSLA [Sep 23 11:47:32] DEBUG[822]: chan_local.c:300 local_devicestate: Checking if extension s@OutboundSLA exists (devicestate) [Sep 23 11:47:32] DEBUG[822]: devicestate.c:460 do_state_change: Changing state for Local/s@OutboundSLA - state 2 (In use) [Sep 23 11:47:32] DEBUG[822]: devicestate.c:440 devstate_event: device 'Local/s@OutboundSLA' state '2' [Sep 23 11:47:32] DEBUG[822]: devicestate.c:342 _ast_device_state: No provider found, checking channel drivers for DAHDI - pseudo [Sep 23 11:47:32] DEBUG[822]: devicestate.c:460 do_state_change: Changing state for DAHDI/pseudo - state 2 (In use) [Sep 23 11:47:32] DEBUG[822]: devicestate.c:440 devstate_event: device 'DAHDI/pseudo' state '2' [Sep 23 11:47:32] DEBUG[1543]: dsp.c:475 ast_tone_detect_init: Setup tone 1100 Hz, 500 ms, block_size=160, hits_required=21 [Sep 23 11:47:32] DEBUG[1543]: dsp.c:475 ast_tone_detect_init: Setup tone 2100 Hz, 2600 ms, block_size=160, hits_required=116 [Sep 23 11:47:32] DEBUG[859]: app_queue.c:1490 handle_statechange: Device 'meetme:SLA_SLAtrunk1' changed to state '2' (In use) but we don't care because they're not a member of any queue. [Sep 23 11:47:32] DEBUG[859]: app_queue.c:1490 handle_statechange: Device 'Local/s@OutboundSLA' changed to state '2' (In use) but we don't care because they're not a member of any queue. [Sep 23 11:47:32] DEBUG[859]: app_queue.c:1490 handle_statechange: Device 'Local/s@OutboundSLA' changed to state '2' (In use) but we don't care because they're not a member of any queue. [Sep 23 11:47:32] DEBUG[859]: app_queue.c:1490 handle_statechange: Device 'DAHDI/pseudo' changed to state '2' (In use) but we don't care because they're not a member of any queue. [Sep 23 11:47:32] DEBUG[1542]: chan_sip.c:6492 sip_answer: SIP answering channel: SIP/111-00000004 [Sep 23 11:47:32] DEBUG[1542]: res_rtp_asterisk.c:828 ast_rtp_update_source: Setting the marker bit due to a source update [Sep 23 11:47:32] DEBUG[1542]: chan_sip.c:11814 transmit_response_with_sdp: Setting framing from config on incoming call [Sep 23 11:47:32] DEBUG[1542]: chan_sip.c:11413 add_sdp: ** Our capability: 0x1104 (ulaw|g729|g722) Video flag: True Text flag: True [Sep 23 11:47:32] DEBUG[1542]: chan_sip.c:11414 add_sdp: ** Our prefcodec: 0x0 (nothing) Audio is at 16590 Adding codec 0x1000 (g722) to SDP Adding codec 0x4 (ulaw) to SDP Adding codec 0x100 (g729) to SDP Adding non-codec 0x1 (telephone-event) to SDP [Sep 23 11:47:32] DEBUG[1542]: chan_sip.c:11523 add_sdp: -- Done with adding codecs to SDP [Sep 23 11:47:32] DEBUG[1542]: chan_sip.c:11709 add_sdp: Done building SDP. Settling with this capability: 0x1104 (ulaw|g729|g722) <--- Reliably Transmitting (NAT) to 192.168.17.141:5060 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.17.141:5060;branch=z9hG4bK936897524699b92cd57262cc79e62d98;received=192.168.17.141;rport=5060 From: "Cordless 1" ;tag=2709006301 To: ;tag=as5a0c6d3f Call-ID: 2496317345@192_168_17_141 CSeq: 2 INVITE Server: Asterisk PBX 1.8.15.0 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH Supported: replaces, timer Contact: Content-Type: application/sdp Content-Length: 308 v=0 o=root 1603821960 1603821960 IN IP4 192.168.17.1 s=Asterisk PBX 1.8.15.0 c=IN IP4 192.168.17.1 t=0 0 m=audio 16590 RTP/AVP 9 0 18 101 a=rtpmap:9 G722/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:18 G729/8000 a=fmtp:18 annexb=no a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 a=sendrecv <------------> [Sep 23 11:47:32] DEBUG[1542]: chan_sip.c:3801 __sip_reliable_xmit: *** SIP TIMER: Initializing retransmit timer on packet: Id #153 [Sep 23 11:47:32] DEBUG[1542]: chan_sip.c:3346 __sip_xmit: Trying to put 'SIP/2.0 200' onto UDP socket destined for 192.168.17.141:5060 [Sep 23 11:47:32] DEBUG[822]: devicestate.c:342 _ast_device_state: No provider found, checking channel drivers for SIP - 111 [Sep 23 11:47:32] DEBUG[822]: chan_sip.c:26559 sip_devicestate: Checking device state for peer 111 [Sep 23 11:47:32] DEBUG[822]: devicestate.c:460 do_state_change: Changing state for SIP/111 - state 2 (In use) [Sep 23 11:47:32] DEBUG[822]: devicestate.c:440 devstate_event: device 'SIP/111' state '2' [Sep 23 11:47:32] DEBUG[859]: app_queue.c:1490 handle_statechange: Device 'SIP/111' changed to state '2' (In use) but we don't care because they're not a member of any queue. [Sep 23 11:47:32] DEBUG[1544]: app_disa.c:201 disa_exec: Context: OutboundSLA [Sep 23 11:47:32] DEBUG[1544]: app_disa.c:205 disa_exec: DISA no-password login success [Sep 23 11:47:32] DEBUG[1544]: channel.c:5175 set_format: Set channel Local/s@OutboundSLA-b629;2 to write format slin [Sep 23 11:47:32] DEBUG[1544]: channel.c:3516 ast_settimeout: Scheduling timer at (50 requested / 50 actual) timer ticks per second [Sep 23 11:47:32] DEBUG[1542]: channel.c:5175 set_format: Set channel SIP/111-00000004 to write format slin [Sep 23 11:47:32] DEBUG[1542]: channel.c:5175 set_format: Set channel SIP/111-00000004 to read format slin [Sep 23 11:47:32] DEBUG[1542]: app_meetme.c:2683 conf_run: Placed channel SIP/111-00000004 in DAHDI conf 1023 [Sep 23 11:47:32] DEBUG[1542]: dsp.c:475 ast_tone_detect_init: Setup tone 1100 Hz, 500 ms, block_size=160, hits_required=21 [Sep 23 11:47:32] DEBUG[1542]: dsp.c:475 ast_tone_detect_init: Setup tone 2100 Hz, 2600 ms, block_size=160, hits_required=116 [Sep 23 11:47:32] DEBUG[1542]: res_rtp_asterisk.c:1359 ast_rtp_write: Ooh, format changed from unknown to g722 [Sep 23 11:47:32] DEBUG[1542]: res_rtp_asterisk.c:1390 ast_rtp_write: Created smoother: format: g722 ms: 20 len: 160 [Sep 23 11:47:32] DEBUG[840]: chan_sip.c:3585 retrans_pkt: SIP TIMER: Rescheduling retransmission #153 (1) SIP/2.0 - 1 [Sep 23 11:47:32] DEBUG[840]: chan_sip.c:3605 retrans_pkt: ** SIP timers: Rescheduling retransmission 2 to 200 ms (t1 100 ms (Retrans id #153)) Retransmitting #1 (NAT) to 192.168.17.141:5060: SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.17.141:5060;branch=z9hG4bK936897524699b92cd57262cc79e62d98;received=192.168.17.141;rport=5060 From: "Cordless 1" ;tag=2709006301 To: ;tag=as5a0c6d3f Call-ID: 2496317345@192_168_17_141 CSeq: 2 INVITE Server: Asterisk PBX 1.8.15.0 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH Supported: replaces, timer Contact: Content-Type: application/sdp Content-Length: 308 v=0 o=root 1603821960 1603821960 IN IP4 192.168.17.1 s=Asterisk PBX 1.8.15.0 c=IN IP4 192.168.17.1 t=0 0 m=audio 16590 RTP/AVP 9 0 18 101 a=rtpmap:9 G722/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:18 G729/8000 a=fmtp:18 annexb=no a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 a=sendrecv --- [Sep 23 11:47:32] DEBUG[840]: chan_sip.c:3346 __sip_xmit: Trying to put 'SIP/2.0 200' onto UDP socket destined for 192.168.17.141:5060 <--- SIP read from UDP:192.168.17.141:5060 ---> ACK sip:*1@192.168.17.1:5060 SIP/2.0 Via: SIP/2.0/UDP 192.168.17.141:5060;branch=z9hG4bKdd88541d141bec1184ae6565b06f55e5;rport From: "Cordless 1" ;tag=2709006301 To: ;tag=as5a0c6d3f Call-ID: 2496317345@192_168_17_141 CSeq: 2 ACK Contact: Max-Forwards: 70 User-Agent: C610A IP/42.072.00.000.000 Content-Length: 0 <-------------> [Sep 23 11:47:32] DEBUG[840]: chan_sip.c:8521 parse_request: Header 0 [ 36]: ACK sip:*1@192.168.17.1:5060 SIP/2.0 [Sep 23 11:47:32] DEBUG[840]: chan_sip.c:8521 parse_request: Header 1 [ 89]: Via: SIP/2.0/UDP 192.168.17.141:5060;branch=z9hG4bKdd88541d141bec1184ae6565b06f55e5;rport [Sep 23 11:47:32] DEBUG[840]: chan_sip.c:8521 parse_request: Header 2 [ 58]: From: "Cordless 1" ;tag=2709006301 [Sep 23 11:47:32] DEBUG[840]: chan_sip.c:8521 parse_request: Header 3 [ 53]: To: ;tag=as5a0c6d3f [Sep 23 11:47:32] DEBUG[840]: chan_sip.c:8521 parse_request: Header 4 [ 34]: Call-ID: 2496317345@192_168_17_141 [Sep 23 11:47:32] DEBUG[840]: chan_sip.c:8521 parse_request: Header 5 [ 11]: CSeq: 2 ACK [Sep 23 11:47:32] DEBUG[840]: chan_sip.c:8521 parse_request: Header 6 [ 38]: Contact: [Sep 23 11:47:32] DEBUG[840]: chan_sip.c:8521 parse_request: Header 7 [ 16]: Max-Forwards: 70 [Sep 23 11:47:32] DEBUG[840]: chan_sip.c:8521 parse_request: Header 8 [ 38]: User-Agent: C610A IP/42.072.00.000.000 [Sep 23 11:47:32] DEBUG[840]: chan_sip.c:8521 parse_request: Header 9 [ 17]: Content-Length: 0 --- (10 headers 0 lines) --- [Sep 23 11:47:32] DEBUG[840]: chan_sip.c:8115 find_call: = Looking for Call ID: 2496317345@192_168_17_141 (Checking From) --From tag 2709006301 --To-tag as5a0c6d3f [Sep 23 11:47:32] DEBUG[840]: chan_sip.c:25254 handle_incoming: **** Received ACK (6) - Command in SIP ACK [Sep 23 11:47:32] DEBUG[840]: chan_sip.c:3997 __sip_ack: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #153 [Sep 23 11:47:32] DEBUG[840]: chan_sip.c:4030 __sip_ack: Stopping retransmission on '2496317345@192_168_17_141' of Response 2: Match Found <--- SIP read from UDP:192.168.17.141:5060 ---> ACK sip:*1@192.168.17.1:5060 SIP/2.0 Via: SIP/2.0/UDP 192.168.17.141:5060;branch=z9hG4bKeb61080b87b2223a6c7d0e6508aaa82;rport From: "Cordless 1" ;tag=2709006301 To: ;tag=as5a0c6d3f Call-ID: 2496317345@192_168_17_141 CSeq: 2 ACK Contact: Max-Forwards: 70 User-Agent: C610A IP/42.072.00.000.000 Content-Length: 0 <-------------> [Sep 23 11:47:32] DEBUG[840]: chan_sip.c:8521 parse_request: Header 0 [ 36]: ACK sip:*1@192.168.17.1:5060 SIP/2.0 [Sep 23 11:47:32] DEBUG[840]: chan_sip.c:8521 parse_request: Header 1 [ 88]: Via: SIP/2.0/UDP 192.168.17.141:5060;branch=z9hG4bKeb61080b87b2223a6c7d0e6508aaa82;rport [Sep 23 11:47:32] DEBUG[840]: chan_sip.c:8521 parse_request: Header 2 [ 58]: From: "Cordless 1" ;tag=2709006301 [Sep 23 11:47:32] DEBUG[840]: chan_sip.c:8521 parse_request: Header 3 [ 53]: To: ;tag=as5a0c6d3f [Sep 23 11:47:32] DEBUG[840]: chan_sip.c:8521 parse_request: Header 4 [ 34]: Call-ID: 2496317345@192_168_17_141 [Sep 23 11:47:32] DEBUG[840]: chan_sip.c:8521 parse_request: Header 5 [ 11]: CSeq: 2 ACK [Sep 23 11:47:32] DEBUG[840]: chan_sip.c:8521 parse_request: Header 6 [ 38]: Contact: [Sep 23 11:47:32] DEBUG[840]: chan_sip.c:8521 parse_request: Header 7 [ 16]: Max-Forwards: 70 [Sep 23 11:47:32] DEBUG[840]: chan_sip.c:8521 parse_request: Header 8 [ 38]: User-Agent: C610A IP/42.072.00.000.000 [Sep 23 11:47:32] DEBUG[840]: chan_sip.c:8521 parse_request: Header 9 [ 17]: Content-Length: 0 --- (10 headers 0 lines) --- [Sep 23 11:47:32] DEBUG[840]: chan_sip.c:8115 find_call: = Looking for Call ID: 2496317345@192_168_17_141 (Checking From) --From tag 2709006301 --To-tag as5a0c6d3f [Sep 23 11:47:32] DEBUG[840]: chan_sip.c:25254 handle_incoming: **** Received ACK (6) - Command in SIP ACK [Sep 23 11:47:34] DEBUG[1542]: res_rtp_asterisk.c:1542 process_dtmf_rfc2833: - RTP 2833 Event: 00000001 (len = 4) [Sep 23 11:47:34] DEBUG[1542]: res_rtp_asterisk.c:1498 create_dtmf_frame: Sending dtmf: 49 (1), at 192.168.17.141:5012 [Sep 23 11:47:34] DEBUG[1542]: channel.c:4856 ast_write: Thread 1100749680 Blocking 'Local/s@OutboundSLA-b629;1', already blocked by thread 1100995440 in procedure ast_waitfor_nandfds [Sep 23 11:47:34] DEBUG[1542]: res_rtp_asterisk.c:1542 process_dtmf_rfc2833: - RTP 2833 Event: 00000001 (len = 4) [Sep 23 11:47:34] DEBUG[1542]: res_rtp_asterisk.c:1542 process_dtmf_rfc2833: - RTP 2833 Event: 00000001 (len = 4) [Sep 23 11:47:34] DEBUG[1542]: res_rtp_asterisk.c:1542 process_dtmf_rfc2833: - RTP 2833 Event: 00000001 (len = 4) [Sep 23 11:47:34] DEBUG[1542]: res_rtp_asterisk.c:1542 process_dtmf_rfc2833: - RTP 2833 Event: 00000001 (len = 4) [Sep 23 11:47:34] DEBUG[1542]: res_rtp_asterisk.c:1498 create_dtmf_frame: Sending dtmf: 49 (1), at 192.168.17.141:5012 [Sep 23 11:47:34] DEBUG[1542]: channel.c:4856 ast_write: Thread 1100749680 Blocking 'Local/s@OutboundSLA-b629;1', already blocked by thread 1100995440 in procedure ast_waitfor_nandfds [Sep 23 11:47:34] DEBUG[1544]: channel.c:5175 set_format: Set channel Local/s@OutboundSLA-b629;2 to write format g722 [Sep 23 11:47:34] DEBUG[1544]: channel.c:3516 ast_settimeout: Scheduling timer at (0 requested / 0 actual) timer ticks per second [Sep 23 11:47:34] DEBUG[1542]: res_rtp_asterisk.c:1542 process_dtmf_rfc2833: - RTP 2833 Event: 00000001 (len = 4) [Sep 23 11:47:34] DEBUG[1542]: res_rtp_asterisk.c:1542 process_dtmf_rfc2833: - RTP 2833 Event: 00000001 (len = 4) [Sep 23 11:47:34] DEBUG[1542]: res_rtp_asterisk.c:1542 process_dtmf_rfc2833: - RTP 2833 Event: 00000000 (len = 4) [Sep 23 11:47:34] DEBUG[1542]: res_rtp_asterisk.c:1498 create_dtmf_frame: Sending dtmf: 48 (0), at 192.168.17.141:5012 [Sep 23 11:47:34] DEBUG[1542]: channel.c:4856 ast_write: Thread 1100749680 Blocking 'Local/s@OutboundSLA-b629;1', already blocked by thread 1100995440 in procedure ast_waitfor_nandfds [Sep 23 11:47:34] DEBUG[1542]: res_rtp_asterisk.c:1542 process_dtmf_rfc2833: - RTP 2833 Event: 00000000 (len = 4) [Sep 23 11:47:34] DEBUG[1542]: res_rtp_asterisk.c:1542 process_dtmf_rfc2833: - RTP 2833 Event: 00000000 (len = 4) [Sep 23 11:47:34] DEBUG[1542]: res_rtp_asterisk.c:1542 process_dtmf_rfc2833: - RTP 2833 Event: 00000000 (len = 4) [Sep 23 11:47:34] DEBUG[1542]: res_rtp_asterisk.c:1542 process_dtmf_rfc2833: - RTP 2833 Event: 00000000 (len = 4) [Sep 23 11:47:34] DEBUG[1542]: res_rtp_asterisk.c:1542 process_dtmf_rfc2833: - RTP 2833 Event: 00000000 (len = 4) [Sep 23 11:47:34] DEBUG[1542]: res_rtp_asterisk.c:1498 create_dtmf_frame: Sending dtmf: 48 (0), at 192.168.17.141:5012 [Sep 23 11:47:34] DEBUG[1542]: channel.c:4856 ast_write: Thread 1100749680 Blocking 'Local/s@OutboundSLA-b629;1', already blocked by thread 1100995440 in procedure ast_waitfor_nandfds [Sep 23 11:47:34] DEBUG[1542]: res_rtp_asterisk.c:1542 process_dtmf_rfc2833: - RTP 2833 Event: 00000000 (len = 4) [Sep 23 11:47:34] DEBUG[1542]: res_rtp_asterisk.c:1542 process_dtmf_rfc2833: - RTP 2833 Event: 00000000 (len = 4) [Sep 23 11:47:34] DEBUG[1542]: res_rtp_asterisk.c:1542 process_dtmf_rfc2833: - RTP 2833 Event: 00000001 (len = 4) [Sep 23 11:47:34] DEBUG[1542]: res_rtp_asterisk.c:1498 create_dtmf_frame: Sending dtmf: 49 (1), at 192.168.17.141:5012 [Sep 23 11:47:34] DEBUG[1542]: channel.c:4856 ast_write: Thread 1100749680 Blocking 'Local/s@OutboundSLA-b629;1', already blocked by thread 1100995440 in procedure ast_waitfor_nandfds [Sep 23 11:47:34] DEBUG[1542]: res_rtp_asterisk.c:1542 process_dtmf_rfc2833: - RTP 2833 Event: 00000001 (len = 4) [Sep 23 11:47:34] DEBUG[1542]: res_rtp_asterisk.c:1542 process_dtmf_rfc2833: - RTP 2833 Event: 00000001 (len = 4) [Sep 23 11:47:34] DEBUG[1542]: res_rtp_asterisk.c:1542 process_dtmf_rfc2833: - RTP 2833 Event: 00000001 (len = 4) [Sep 23 11:47:34] DEBUG[1542]: res_rtp_asterisk.c:1542 process_dtmf_rfc2833: - RTP 2833 Event: 00000001 (len = 4) [Sep 23 11:47:34] DEBUG[1542]: res_rtp_asterisk.c:1542 process_dtmf_rfc2833: - RTP 2833 Event: 00000001 (len = 4) [Sep 23 11:47:34] DEBUG[1542]: res_rtp_asterisk.c:1498 create_dtmf_frame: Sending dtmf: 49 (1), at 192.168.17.141:5012 [Sep 23 11:47:34] DEBUG[1542]: channel.c:4856 ast_write: Thread 1100749680 Blocking 'Local/s@OutboundSLA-b629;1', already blocked by thread 1100995440 in procedure ast_waitfor_nandfds [Sep 23 11:47:34] DEBUG[1544]: pbx.c:3256 ast_str_retrieve_variable: Result of 'EXTEN' is '101' [Sep 23 11:47:34] DEBUG[1544]: pbx.c:3256 ast_str_retrieve_variable: Result of 'RINGTIME' is '20' [Sep 23 11:47:34] DEBUG[1544]: pbx.c:4247 pbx_extension_helper: Launching 'Dial' -- Executing [101@OutboundSLA:1] Dial("Local/s@OutboundSLA-b629;2", "SIP/101,20,t") in new stack [Sep 23 11:47:34] DEBUG[1544]: chan_sip.c:26659 sip_request_call: Asked to create a SIP channel with formats: 0x1000 (g722) [Sep 23 11:47:34] DEBUG[1544]: chan_sip.c:7795 sip_alloc: Allocating new SIP dialog for 334ab05b0596fc026229273c04bc538f@192.168.17.1:5060 - INVITE (No RTP) [Sep 23 11:47:34] DEBUG[1544]: rtp_engine.c:350 ast_rtp_instance_new: Using engine 'asterisk' for RTP instance '0x88a5c30' [Sep 23 11:47:34] DEBUG[1544]: res_rtp_asterisk.c:556 ast_rtp_new: Allocated port 16390 for RTP instance '0x88a5c30' [Sep 23 11:47:34] DEBUG[1544]: rtp_engine.c:359 ast_rtp_instance_new: RTP instance '0x88a5c30' is setup and ready to go [Sep 23 11:47:34] DEBUG[1544]: res_rtp_asterisk.c:2523 ast_rtp_prop_set: Setup RTCP on RTP instance '0x88a5c30' == Using SIP RTP CoS mark 5 [Sep 23 11:47:34] DEBUG[1544]: chan_sip.c:5093 do_setnat: Setting NAT on RTP to On [Sep 23 11:47:34] DEBUG[1544]: chan_sip.c:3146 obproxy_get: OBPROXY: Not applying OBproxy to this call [Sep 23 11:47:34] DEBUG[1544]: acl.c:736 ast_ouraddrfor: For destination '192.168.17.4', our source address is '192.168.17.1'. [Sep 23 11:47:34] DEBUG[1544]: chan_sip.c:3498 ast_sip_ouraddrfor: Setting SIP_TRANSPORT_UDP with address 192.168.17.1:5060 [Sep 23 11:47:34] DEBUG[1544]: chan_sip.c:7091 sip_new: *** Our native formats are 0x4 (ulaw) [Sep 23 11:47:34] DEBUG[1544]: chan_sip.c:7092 sip_new: *** Joint capabilities are 0x0 (nothing) [Sep 23 11:47:34] DEBUG[1544]: chan_sip.c:7093 sip_new: *** Our capabilities are 0x4 (ulaw) [Sep 23 11:47:34] DEBUG[1544]: chan_sip.c:7094 sip_new: *** AST_CODEC_CHOOSE formats are 0x4 (ulaw) [Sep 23 11:47:34] DEBUG[1544]: chan_sip.c:7096 sip_new: *** Our preferred formats from the incoming channel are 0x1000 (g722) [Sep 23 11:47:34] DEBUG[1544]: chan_sip.c:7124 sip_new: This channel will not be able to handle video. [Sep 23 11:47:34] DEBUG[1544]: dsp.c:475 ast_tone_detect_init: Setup tone 1100 Hz, 500 ms, block_size=160, hits_required=21 [Sep 23 11:47:34] DEBUG[1544]: dsp.c:475 ast_tone_detect_init: Setup tone 2100 Hz, 2600 ms, block_size=160, hits_required=116 [Sep 23 11:47:34] DEBUG[1544]: rtp_engine.c:1412 ast_rtp_instance_early_bridge_make_compatible: Can't find native functions for channel 'Local/s@OutboundSLA-b629;2' [Sep 23 11:47:34] DEBUG[1544]: rtp_engine.c:1473 ast_rtp_instance_early_bridge_make_compatible: Seeded SDP of 'SIP/101-00000005' with that of 'Local/s@OutboundSLA-b629;2' [Sep 23 11:47:34] DEBUG[1544]: channel.c:6152 ast_channel_inherit_variables: Not copying variable DIALEDTIME. [Sep 23 11:47:34] DEBUG[1544]: channel.c:6152 ast_channel_inherit_variables: Not copying variable ANSWEREDTIME. [Sep 23 11:47:34] DEBUG[1544]: channel.c:6152 ast_channel_inherit_variables: Not copying variable DIALEDPEERNAME. [Sep 23 11:47:34] DEBUG[1544]: channel.c:6152 ast_channel_inherit_variables: Not copying variable DIALEDPEERNUMBER. [Sep 23 11:47:34] DEBUG[1544]: channel.c:6152 ast_channel_inherit_variables: Not copying variable DIALSTATUS. [Sep 23 11:47:34] DEBUG[1544]: chan_sip.c:5638 sip_call: Outgoing Call for 101 [Sep 23 11:47:34] DEBUG[1544]: chan_sip.c:5905 update_call_counter: Updating call counter for outgoing call [Sep 23 11:47:34] DEBUG[1544]: chan_sip.c:6010 update_call_counter: Call to peer '101' is 1 out of 10 [Sep 23 11:47:34] DEBUG[1544]: chan_sip.c:11413 add_sdp: ** Our capability: 0x4 (ulaw) Video flag: False Text flag: False [Sep 23 11:47:34] DEBUG[1544]: chan_sip.c:11414 add_sdp: ** Our prefcodec: 0x1000 (g722) Audio is at 16390 Adding codec 0x4 (ulaw) to SDP Adding non-codec 0x1 (telephone-event) to SDP [Sep 23 11:47:34] DEBUG[1544]: chan_sip.c:11523 add_sdp: -- Done with adding codecs to SDP Sep 23 11:47:34] DEBUG[1544]: chan_sip.c:11709 add_sdp: Done building SDP. Settling with this capability: 0x4 (ulaw) [Sep 23 11:47:34] DEBUG[1544]: chan_sip.c:3075 initialize_initreq: Initializing initreq for method INVITE - callid 0d642d503eb8e283036b65f8181d2d52@192.168.17.1:5060 [Sep 23 11:47:34] DEBUG[1544]: chan_sip.c:8521 parse_request: Header 0 [ 40]: INVITE sip:101@192.168.17.4:5060 SIP/2.0 [Sep 23 11:47:34] DEBUG[1544]: chan_sip.c:8521 parse_request: Header 1 [ 63]: Via: SIP/2.0/UDP 192.168.17.1:5060;branch=z9hG4bK7be19d57;rport [Sep 23 11:47:34] DEBUG[1544]: chan_sip.c:8521 parse_request: Header 2 [ 16]: Max-Forwards: 70 [Sep 23 11:47:34] DEBUG[1544]: chan_sip.c:8521 parse_request: Header 3 [ 56]: From: "Cordless 1" ;tag=as0da8de2e [Sep 23 11:47:34] DEBUG[1544]: chan_sip.c:8521 parse_request: Header 4 [ 31]: To: [Sep 23 11:47:34] DEBUG[1544]: chan_sip.c:8521 parse_request: Header 5 [ 36]: Contact: [Sep 23 11:47:34] DEBUG[1544]: chan_sip.c:8521 parse_request: Header 6 [ 59]: Call-ID: 0d642d503eb8e283036b65f8181d2d52@192.168.17.1:5060 [Sep 23 11:47:34] DEBUG[1544]: chan_sip.c:8521 parse_request: Header 7 [ 16]: CSeq: 102 INVITE [Sep 23 11:47:34] DEBUG[1544]: chan_sip.c:8521 parse_request: Header 8 [ 33]: User-Agent: Asterisk PBX 1.8.15.0 [Sep 23 11:47:34] DEBUG[1544]: chan_sip.c:8521 parse_request: Header 9 [ 35]: Date: Sun, 23 Sep 2012 15:47:34 GMT [Sep 23 11:47:34] DEBUG[1544]: chan_sip.c:8521 parse_request: Header 10 [ 81]: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH [Sep 23 11:47:34] DEBUG[1544]: chan_sip.c:8521 parse_request: Header 11 [ 26]: Supported: replaces, timer [Sep 23 11:47:34] DEBUG[1544]: chan_sip.c:8521 parse_request: Header 12 [ 29]: Content-Type: application/sdp Reliably Transmitting (NAT) to 192.168.17.4:5060: INVITE sip:101@192.168.17.4:5060 SIP/2.0 Via: SIP/2.0/UDP 192.168.17.1:5060;branch=z9hG4bK7be19d57;rport Max-Forwards: 70 From: "Cordless 1" ;tag=as0da8de2e To: Contact: Call-ID: 0d642d503eb8e283036b65f8181d2d52@192.168.17.1:5060 CSeq: 102 INVITE User-Agent: Asterisk PBX 1.8.15.0 Date: Sun, 23 Sep 2012 15:47:34 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH Supported: replaces, timer Content-Type: application/sdp Content-Length: 237 v=0 o=root 1183803358 1183803358 IN IP4 192.168.17.1 s=Asterisk PBX 1.8.15.0 c=IN IP4 192.168.17.1 t=0 0 m=audio 16390 RTP/AVP 0 101 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 a=sendrecv --- [Sep 23 11:47:34] DEBUG[1544]: chan_sip.c:3801 __sip_reliable_xmit: *** SIP TIMER: Initializing retransmit timer on packet: Id #155 [Sep 23 11:47:34] DEBUG[1544]: chan_sip.c:3346 __sip_xmit: Trying to put 'INVITE sip:' onto UDP socket destined for 192.168.17.4:5060 -- Called SIP/101 [Sep 23 11:47:34] DEBUG[1544]: channel.c:5175 set_format: Set channel SIP/101-00000005 to read format slin [Sep 23 11:47:34] DEBUG[1544]: channel.c:5175 set_format: Set channel Local/s@OutboundSLA-b629;2 to write format slin [Sep 23 11:47:34] DEBUG[1544]: channel.c:5175 set_format: Set channel Local/s@OutboundSLA-b629;2 to read format slin [Sep 23 11:47:34] DEBUG[1544]: channel.c:5175 set_format: Set channel SIP/101-00000005 to write format slin [Sep 23 11:47:34] DEBUG[822]: devicestate.c:342 _ast_device_state: No provider found, checking channel drivers for SIP - 101 [Sep 23 11:47:34] DEBUG[822]: chan_sip.c:26559 sip_devicestate: Checking device state for peer 101 [Sep 23 11:47:34] DEBUG[822]: devicestate.c:460 do_state_change: Changing state for SIP/101 - state 6 (Ringing) [Sep 23 11:47:34] DEBUG[822]: devicestate.c:440 devstate_event: device 'SIP/101' state '6' [Sep 23 11:47:34] DEBUG[1543]: app_meetme.c:3595 conf_run: Got ignored control frame on channel Local/s@OutboundSLA-b629;1, f->frametype=4,f->subclass=22 [Sep 23 11:47:34] DEBUG[1542]: res_rtp_asterisk.c:1542 process_dtmf_rfc2833: - RTP 2833 Event: 00000001 (len = 4) [Sep 23 11:47:34] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame <--- SIP read from UDP:192.168.17.4:5060 ---> SIP/2.0 100 Trying To: rom: "Cordless 1" ;tag=as0da8de2e Call-ID: 0d642d503eb8e283036b65f8181d2d52@192.168.17.1:5060 CSeq: 102 INVITE Via: SIP/2.0/UDP 192.168.17.1:5060;branch=z9hG4bK7be19d57 Server: Linksys/PAP2T-5.1.6(LS) Content-Length: 0 <-------------> [Sep 23 11:47:34] DEBUG[840]: chan_sip.c:8521 parse_request: Header 0 [ 18]: SIP/2.0 100 Trying [Sep 23 11:47:34] DEBUG[840]: chan_sip.c:8521 parse_request: Header 1 [ 31]: To: [Sep 23 11:47:34] DEBUG[840]: chan_sip.c:8521 parse_request: Header 2 [ 56]: From: "Cordless 1" ;tag=as0da8de2e [Sep 23 11:47:34] DEBUG[840]: chan_sip.c:8521 parse_request: Header 3 [ 59]: Call-ID: 0d642d503eb8e283036b65f8181d2d52@192.168.17.1:5060 [Sep 23 11:47:34] DEBUG[840]: chan_sip.c:8521 parse_request: Header 4 [ 16]: CSeq: 102 INVITE [Sep 23 11:47:34] DEBUG[840]: chan_sip.c:8521 parse_request: Header 5 [ 57]: Via: SIP/2.0/UDP 192.168.17.1:5060;branch=z9hG4bK7be19d57 [Sep 23 11:47:34] DEBUG[840]: chan_sip.c:8521 parse_request: Header 6 [ 31]: Server: Linksys/PAP2T-5.1.6(LS) [Sep 23 11:47:34] DEBUG[840]: chan_sip.c:8521 parse_request: Header 7 [ 17]: Content-Length: 0 --- (8 headers 0 lines) --- [Sep 23 11:47:34] DEBUG[840]: chan_sip.c:8115 find_call: = Looking for Call ID: 0d642d503eb8e283036b65f8181d2d52@192.168.17.1:5060 (Checking To) --From tag as0da8de2e --To-tag [Sep 23 11:47:34] DEBUG[840]: chan_sip.c:4064 __sip_semi_ack: *** SIP TIMER: Cancelling retransmission #155 - INVITE (got response) [Sep 23 11:47:34] DEBUG[840]: chan_sip.c:4071 __sip_semi_ack: (Provisional) Stopping retransmission (but retaining packet) on '0d642d503eb8e283036b65f8181d2d52@192.168.17.1:5060' Request 102: Found [Sep 23 11:47:34] DEBUG[840]: chan_sip.c:20102 handle_response_invite: SIP response 100 to standard invite [Sep 23 11:47:34] DEBUG[859]: app_queue.c:1490 handle_statechange: Device 'SIP/101' changed to state '6' (Ringing) but we don't care because they're not a member of any queue. [Sep 23 11:47:34] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame <--- SIP read from UDP:192.168.17.4:5060 ---> SIP/2.0 180 Ringing To: ;tag=cf9d85834754482ci0 From: "Cordless 1" ;tag=as0da8de2e Call-ID: 0d642d503eb8e283036b65f8181d2d52@192.168.17.1:5060 CSeq: 102 INVITE Via: SIP/2.0/UDP 192.168.17.1:5060;branch=z9hG4bK7be19d57 Server: Linksys/PAP2T-5.1.6(LS) Content-Length: 0 <-------------> [Sep 23 11:47:34] DEBUG[840]: chan_sip.c:8521 parse_request: Header 0 [ 19]: SIP/2.0 180 Ringing [Sep 23 11:47:34] DEBUG[840]: chan_sip.c:8521 parse_request: Header 1 [ 54]: To: ;tag=cf9d85834754482ci0 [Sep 23 11:47:34] DEBUG[840]: chan_sip.c:8521 parse_request: Header 2 [ 56]: From: "Cordless 1" ;tag=as0da8de2e [Sep 23 11:47:34] DEBUG[840]: chan_sip.c:8521 parse_request: Header 3 [ 59]: Call-ID: 0d642d503eb8e283036b65f8181d2d52@192.168.17.1:5060 [Sep 23 11:47:34] DEBUG[840]: chan_sip.c:8521 parse_request: Header 4 [ 16]: CSeq: 102 INVITE [Sep 23 11:47:34] DEBUG[840]: chan_sip.c:8521 parse_request: Header 5 [ 57]: Via: SIP/2.0/UDP 192.168.17.1:5060;branch=z9hG4bK7be19d57 [Sep 23 11:47:34] DEBUG[840]: chan_sip.c:8521 parse_request: Header 6 [ 31]: Server: Linksys/PAP2T-5.1.6(LS) [Sep 23 11:47:34] DEBUG[840]: chan_sip.c:8521 parse_request: Header 7 [ 17]: Content-Length: 0 --- (8 headers 0 lines) --- [Sep 23 11:47:34] DEBUG[840]: chan_sip.c:8115 find_call: = Looking for Call ID: 0d642d503eb8e283036b65f8181d2d52@192.168.17.1:5060 (Checking To) --From tag as0da8de2e --To-tag cf9d85834754482ci0 [Sep 23 11:47:34] DEBUG[840]: chan_sip.c:4071 __sip_semi_ack: (Provisional) Stopping retransmission (but retaining packet) on '0d642d503eb8e283036b65f8181d2d52@192.168.17.1:5060' Request 102: Found [Sep 23 11:47:34] DEBUG[840]: chan_sip.c:20102 handle_response_invite: SIP response 180 to standard invite list_route: no route -- SIP/101-00000005 is ringing [Sep 23 11:47:35] DEBUG[822]: devicestate.c:342 _ast_device_state: No provider found, checking channel drivers for SIP - 101 [Sep 23 11:47:35] DEBUG[822]: chan_sip.c:26559 sip_devicestate: Checking device state for peer 101 [Sep 23 11:47:35] DEBUG[822]: devicestate.c:460 do_state_change: Changing state for SIP/101 - state 6 (Ringing) [Sep 23 11:47:35] DEBUG[822]: devicestate.c:440 devstate_event: device 'SIP/101' state '6' [Sep 23 11:47:35] DEBUG[822]: devicestate.c:342 _ast_device_state: No provider found, checking channel drivers for Local - s@OutboundSLA [Sep 23 11:47:35] DEBUG[822]: chan_local.c:300 local_devicestate: Checking if extension s@OutboundSLA exists (devicestate) [Sep 23 11:47:35] DEBUG[822]: devicestate.c:460 do_state_change: Changing state for Local/s@OutboundSLA - state 2 (In use) [Sep 23 11:47:35] DEBUG[822]: devicestate.c:440 devstate_event: device 'Local/s@OutboundSLA' state '2' [Sep 23 11:47:35] DEBUG[1542]: res_rtp_asterisk.c:1542 process_dtmf_rfc2833: - RTP 2833 Event: 00000001 (len = 4) [Sep 23 11:47:35] DEBUG[1543]: app_meetme.c:3595 conf_run: Got ignored control frame on channel Local/s@OutboundSLA-b629;1, f->frametype=4,f->subclass=3 [Sep 23 11:47:35] DEBUG[859]: app_queue.c:1490 handle_statechange: Device 'SIP/101' changed to state '6' (Ringing) but we don't care because they're not a member of any queue. [Sep 23 11:47:35] DEBUG[859]: app_queue.c:1490 handle_statechange: Device 'Local/s@OutboundSLA' changed to state '2' (In use) but we don't care because they're not a member of any queue. [Sep 23 11:47:35] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:35] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:35] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:35] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:35] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:35] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:35] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:35] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:35] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:35] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:35] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:35] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:35] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:35] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:35] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:35] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:35] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:35] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:35] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:35] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:35] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:35] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:35] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:35] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:35] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:35] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:35] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:35] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:35] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:35] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:35] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:35] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:35] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:35] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:35] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:35] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:35] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:35] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:35] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:35] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:35] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:35] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:35] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:35] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:35] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:35] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:35] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:35] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:35] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:35] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:36] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:36] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:36] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:36] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:36] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:36] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:36] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:36] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:36] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:36] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:36] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:36] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:36] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:36] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:36] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:36] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:36] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:36] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:36] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:36] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:36] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:36] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:36] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:36] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:36] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:36] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:36] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:36] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:36] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:36] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:36] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:36] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:36] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:36] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:36] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:36] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:36] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:36] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:36] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:36] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:36] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:36] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:36] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:36] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:36] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:36] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:36] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:36] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:36] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:36] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:37] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:37] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:37] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:37] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:37] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:37] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:37] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:37] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:37] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:37] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:37] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:37] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:37] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:37] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:37] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:37] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:37] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:37] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:37] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:37] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:37] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame <--- SIP read from UDP:192.168.17.141:5060 ---> REGISTER sip:kerrfamily.org SIP/2.0 Via: SIP/2.0/UDP 192.168.17.141:5060;branch=z9hG4bK40f55431c75a163b568cf2a0aa016e;rport From: "Cordless 1" ;tag=702542550 To: "Cordless 1" Call-ID: 2660979324@192_168_17_141 CSeq: 1743 REGISTER Contact: Max-Forwards: 70 User-Agent: C610A IP/42.072.00.000.000 Expires: 180 Allow: INVITE, ACK, CANCEL, BYE, OPTIONS, INFO, REFER, SUBSCRIBE, NOTIFY ontent-Length: 0 <-------------> [Sep 23 11:47:37] DEBUG[840]: chan_sip.c:8521 parse_request: Header 0 [ 35]: REGISTER sip:kerrfamily.org SIP/2.0 [Sep 23 11:47:37] DEBUG[840]: chan_sip.c:8521 parse_request: Header 1 [ 87]: Via: SIP/2.0/UDP 192.168.17.141:5060;branch=z9hG4bK40f55431c75a163b568cf2a0aa016e;rport [Sep 23 11:47:37] DEBUG[840]: chan_sip.c:8521 parse_request: Header 2 [ 57]: From: "Cordless 1" ;tag=702542550 [Sep 23 11:47:37] DEBUG[840]: chan_sip.c:8521 parse_request: Header 3 [ 41]: To: "Cordless 1" [Sep 23 11:47:37] DEBUG[840]: chan_sip.c:8521 parse_request: Header 4 [ 34]: Call-ID: 2660979324@192_168_17_141 [Sep 23 11:47:37] DEBUG[840]: chan_sip.c:8521 parse_request: Header 5 [ 19]: CSeq: 1743 REGISTER [Sep 23 11:47:37] DEBUG[840]: chan_sip.c:8521 parse_request: Header 6 [ 38]: Contact: [Sep 23 11:47:37] DEBUG[840]: chan_sip.c:8521 parse_request: Header 7 [ 16]: Max-Forwards: 70 [Sep 23 11:47:37] DEBUG[840]: chan_sip.c:8521 parse_request: Header 8 [ 38]: User-Agent: C610A IP/42.072.00.000.000 [Sep 23 11:47:37] DEBUG[840]: chan_sip.c:8521 parse_request: Header 9 [ 12]: Expires: 180 [Sep 23 11:47:37] DEBUG[840]: chan_sip.c:8521 parse_request: Header 10 [ 72]: Allow: INVITE, ACK, CANCEL, BYE, OPTIONS, INFO, REFER, SUBSCRIBE, NOTIFY [Sep 23 11:47:37] DEBUG[840]: chan_sip.c:8521 parse_request: Header 11 [ 17]: Content-Length: 0 --- (12 headers 0 lines) --- [Sep 23 11:47:37] DEBUG[840]: chan_sip.c:8115 find_call: = Looking for Call ID: 2660979324@192_168_17_141 (Checking From) --From tag 702542550 --To-tag [Sep 23 11:47:37] DEBUG[840]: acl.c:736 ast_ouraddrfor: For destination '192.168.17.141', our source address is '192.168.17.1'. [Sep 23 11:47:37] DEBUG[840]: chan_sip.c:3498 ast_sip_ouraddrfor: Setting SIP_TRANSPORT_UDP with address 192.168.17.1:5060 [Sep 23 11:47:37] DEBUG[840]: chan_sip.c:7795 sip_alloc: Allocating new SIP dialog for 2660979324@192_168_17_141 - REGISTER (No RTP) [Sep 23 11:47:37] DEBUG[840]: chan_sip.c:25254 handle_incoming: **** Received REGISTER (2) - Command in SIP REGISTER [Sep 23 11:47:37] DEBUG[840]: chan_sip.c:25083 handle_request_register: Initializing initreq for method REGISTER - callid 2660979324@192_168_17_141 [Sep 23 11:47:37] DEBUG[840]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting '192.168.17.141:5060' into... [Sep 23 11:47:37] DEBUG[840]: netsock2.c:192 ast_sockaddr_split_hostport: ...host '192.168.17.141' and port '5060'. Sending to 192.168.17.141:5060 (NAT) [Sep 23 11:47:37] DEBUG[840]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting 'kerrfamily.org' into... [Sep 23 11:47:37] DEBUG[840]: netsock2.c:192 ast_sockaddr_split_hostport: ...host 'kerrfamily.org' and port ''. <--- Transmitting (NAT) to 192.168.17.141:5060 ---> SIP/2.0 401 Unauthorized Via: SIP/2.0/UDP 192.168.17.141:5060;branch=z9hG4bK40f55431c75a163b568cf2a0aa016e;received=192.168.17.141;rport=5060 From: "Cordless 1" ;tag=702542550 To: "Cordless 1" ;tag=as0d87a3bf Call-ID: 2660979324@192_168_17_141 CSeq: 1743 REGISTER Server: Asterisk PBX 1.8.15.0 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH Supported: replaces, timer WWW-Authenticate: Digest algorithm=MD5, realm="asterisk", nonce="53c3909d" Content-Length: 0 <------------> [Sep 23 11:47:37] DEBUG[840]: chan_sip.c:3346 __sip_xmit: Trying to put 'SIP/2.0 401' onto UDP socket destined for 192.168.17.141:5060 Scheduling destruction of SIP dialog '2660979324@192_168_17_141' in 32000 ms (Method: REGISTER) [Sep 23 11:47:37] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame <--- SIP read from UDP:192.168.17.141:5060 ---> REGISTER sip:kerrfamily.org SIP/2.0 Via: SIP/2.0/UDP 192.168.17.141:5060;branch=z9hG4bK924cee5d33341ce22eaad3b18901b90;rport From: "Cordless 1" ;tag=702542550 To: "Cordless 1" Call-ID: 2660979324@192_168_17_141 CSeq: 1744 REGISTER Contact: Authorization: Digest username="111", realm="asterisk", algorithm=MD5, uri="sip:kerrfamily.org", nonce="53c3909d", response="5e2d500afbda4e6563f1da3ac0f8b787" Max-Forwards: 70 User-Agent: C610A IP/42.072.00.000.000 Expires: 180 Allow: INVITE, ACK, CANCEL, BYE, OPTIONS, INFO, REFER, SUBSCRIBE, NOTIFY Content-Length: 0 <-------------> [Sep 23 11:47:37] DEBUG[840]: chan_sip.c:8521 parse_request: Header 0 [ 35]: REGISTER sip:kerrfamily.org SIP/2.0 [Sep 23 11:47:37] DEBUG[840]: chan_sip.c:8521 parse_request: Header 1 [ 88]: Via: SIP/2.0/UDP 192.168.17.141:5060;branch=z9hG4bK924cee5d33341ce22eaad3b18901b90;rport [Sep 23 11:47:37] DEBUG[840]: chan_sip.c:8521 parse_request: Header 2 [ 57]: From: "Cordless 1" ;tag=702542550 [Sep 23 11:47:37] DEBUG[840]: chan_sip.c:8521 parse_request: Header 3 [ 41]: To: "Cordless 1" [Sep 23 11:47:37] DEBUG[840]: chan_sip.c:8521 parse_request: Header 4 [ 34]: Call-ID: 2660979324@192_168_17_141 [Sep 23 11:47:37] DEBUG[840]: chan_sip.c:8521 parse_request: Header 5 [ 19]: CSeq: 1744 REGISTER [Sep 23 11:47:37] DEBUG[840]: chan_sip.c:8521 parse_request: Header 6 [ 38]: Contact: [Sep 23 11:47:37] DEBUG[840]: chan_sip.c:8521 parse_request: Header 7 [158]: Authorization: Digest username="111", realm="asterisk", algorithm=MD5, uri="sip:kerrfamily.org", nonce="53c3909d", response="5e2d500afbda4e6563f1da3ac0f8b787" [Sep 23 11:47:37] DEBUG[840]: chan_sip.c:8521 parse_request: Header 8 [ 16]: Max-Forwards: 70 [Sep 23 11:47:37] DEBUG[840]: chan_sip.c:8521 parse_request: Header 9 [ 38]: User-Agent: C610A IP/42.072.00.000.000 [Sep 23 11:47:37] DEBUG[840]: chan_sip.c:8521 parse_request: Header 10 [ 12]: Expires: 180 [Sep 23 11:47:37] DEBUG[840]: chan_sip.c:8521 parse_request: Header 11 [ 72]: Allow: INVITE, ACK, CANCEL, BYE, OPTIONS, INFO, REFER, SUBSCRIBE, NOTIFY [Sep 23 11:47:37] DEBUG[840]: chan_sip.c:8521 parse_request: Header 12 [ 17]: Content-Length: 0 --- (13 headers 0 lines) --- [Sep 23 11:47:37] DEBUG[840]: chan_sip.c:8115 find_call: = Looking for Call ID: 2660979324@192_168_17_141 (Checking From) --From tag 702542550 --To-tag [Sep 23 11:47:37] DEBUG[840]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting 'kerrfamily.org' into... [Sep 23 11:47:37] DEBUG[840]: netsock2.c:192 ast_sockaddr_split_hostport: ...host 'kerrfamily.org' and port ''. [Sep 23 11:47:37] DEBUG[840]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting 'kerrfamily.org' into... [Sep 23 11:47:37] DEBUG[840]: netsock2.c:192 ast_sockaddr_split_hostport: ...host 'kerrfamily.org' and port ''. [Sep 23 11:47:37] DEBUG[840]: chan_sip.c:25254 handle_incoming: **** Received REGISTER (2) - Command in SIP REGISTER [Sep 23 11:47:37] DEBUG[840]: chan_sip.c:25083 handle_request_register: Initializing initreq for method REGISTER - callid 2660979324@192_168_17_141 [Sep 23 11:47:37] DEBUG[840]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting '192.168.17.141:5060' into... [Sep 23 11:47:37] DEBUG[840]: netsock2.c:192 ast_sockaddr_split_hostport: ...host '192.168.17.141' and port '5060'. Sending to 192.168.17.141:5060 (NAT) [Sep 23 11:47:37] DEBUG[840]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting 'kerrfamily.org' into... [Sep 23 11:47:37] DEBUG[840]: netsock2.c:192 ast_sockaddr_split_hostport: ...host 'kerrfamily.org' and port ''. [Sep 23 11:47:37] DEBUG[840]: chan_sip.c:14154 parse_register_contact: Store REGISTER's src-IP:port for call routing. [Sep 23 11:47:37] DEBUG[840]: chan_sip.c:7795 sip_alloc: Allocating new SIP dialog for 6543d052705f7d961ad829ef3dbfe47d@192.168.17.1:5060 - OPTIONS (No RTP) [Sep 23 11:47:37] DEBUG[840]: acl.c:736 ast_ouraddrfor: For destination '192.168.17.141', our source address is '192.168.17.1'. [Sep 23 11:47:37] DEBUG[840]: chan_sip.c:3498 ast_sip_ouraddrfor: Setting SIP_TRANSPORT_UDP with address 192.168.17.1:5060 [Sep 23 11:47:37] DEBUG[840]: chan_sip.c:3075 initialize_initreq: Initializing initreq for method OPTIONS - callid 047a83935e7cd2631e34f16b13d073c9@192.168.17.1:5060 [Sep 23 11:47:37] DEBUG[840]: chan_sip.c:8521 parse_request: Header 0 [ 43]: OPTIONS sip:111@192.168.17.141:5060 SIP/2.0 [Sep 23 11:47:37] DEBUG[840]: chan_sip.c:8521 parse_request: Header 1 [ 63]: Via: SIP/2.0/UDP 192.168.17.1:5060;branch=z9hG4bK49dc1854;rport [Sep 23 11:47:37] DEBUG[840]: chan_sip.c:8521 parse_request: Header 2 [ 16]: Max-Forwards: 70 [Sep 23 11:47:37] DEBUG[840]: chan_sip.c:8521 parse_request: Header 3 [ 59]: From: "asterisk" ;tag=as6ab20116 [Sep 23 11:47:37] DEBUG[840]: chan_sip.c:8521 parse_request: Header 4 [ 33]: To: [Sep 23 11:47:37] DEBUG[840]: chan_sip.c:8521 parse_request: Header 5 [ 41]: Contact: [Sep 23 11:47:37] DEBUG[840]: chan_sip.c:8521 parse_request: Header 6 [ 59]: Call-ID: 047a83935e7cd2631e34f16b13d073c9@192.168.17.1:5060 [Sep 23 11:47:37] DEBUG[840]: chan_sip.c:8521 parse_request: Header 7 [ 17]: CSeq: 102 OPTIONS [Sep 23 11:47:37] DEBUG[840]: chan_sip.c:8521 parse_request: Header 8 [ 33]: User-Agent: Asterisk PBX 1.8.15.0 [Sep 23 11:47:37] DEBUG[840]: chan_sip.c:8521 parse_request: Header 9 [ 35]: Date: Sun, 23 Sep 2012 15:47:37 GMT [Sep 23 11:47:37] DEBUG[840]: chan_sip.c:8521 parse_request: Header 10 [ 81]: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH [Sep 23 11:47:37] DEBUG[840]: chan_sip.c:8521 parse_request: Header 11 [ 26]: Supported: replaces, timer Reliably Transmitting (NAT) to 192.168.17.141:5060: OPTIONS sip:111@192.168.17.141:5060 SIP/2.0 Via: SIP/2.0/UDP 192.168.17.1:5060;branch=z9hG4bK49dc1854;rport Max-Forwards: 70 From: "asterisk" ;tag=as6ab20116 To: Contact: Call-ID: 047a83935e7cd2631e34f16b13d073c9@192.168.17.1:5060 CSeq: 102 OPTIONS User-Agent: Asterisk PBX 1.8.15.0 Date: Sun, 23 Sep 2012 15:47:37 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH Supported: replaces, timer Content-Length: 0 --- [Sep 23 11:47:37] DEBUG[840]: chan_sip.c:3801 __sip_reliable_xmit: *** SIP TIMER: Initializing retransmit timer on packet: Id #159 [Sep 23 11:47:37] DEBUG[840]: chan_sip.c:3346 __sip_xmit: Trying to put 'OPTIONS sip' onto UDP socket destined for 192.168.17.141:5060 <--- Transmitting (NAT) to 192.168.17.141:5060 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.17.141:5060;branch=z9hG4bK924cee5d33341ce22eaad3b18901b90;received=192.168.17.141;rport=5060 From: "Cordless 1" ;tag=702542550 To: "Cordless 1" ;tag=as0d87a3bf Call-ID: 2660979324@192_168_17_141 CSeq: 1744 REGISTER Server: Asterisk PBX 1.8.15.0 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH Supported: replaces, timer Expires: 180 Contact: ;expires=180 Date: Sun, 23 Sep 2012 15:47:37 GMT Content-Length: 0 <------------> [Sep 23 11:47:37] DEBUG[840]: chan_sip.c:3346 __sip_xmit: Trying to put 'SIP/2.0 200' onto UDP socket destined for 192.168.17.141:5060 [Sep 23 11:47:37] DEBUG[840]: chan_sip.c:7795 sip_alloc: Allocating new SIP dialog for 619091ed43f4a1ae5806a2053f70e6b6@192.168.17.1:5060 - NOTIFY (No RTP) [Sep 23 11:47:37] DEBUG[840]: chan_sip.c:3146 obproxy_get: OBPROXY: Not applying OBproxy to this call [Sep 23 11:47:37] DEBUG[840]: acl.c:736 ast_ouraddrfor: For destination '192.168.17.141', our source address is '192.168.17.1'. [Sep 23 11:47:37] DEBUG[840]: chan_sip.c:3498 ast_sip_ouraddrfor: Setting SIP_TRANSPORT_UDP with address 192.168.17.1:5060 Scheduling destruction of SIP dialog '1c73545e614f43624680a5c162b003dd@192.168.17.1:5060' in 6400 ms (Method: NOTIFY) [Sep 23 11:47:37] DEBUG[840]: chan_sip.c:3075 initialize_initreq: Initializing initreq for method NOTIFY - callid 1c73545e614f43624680a5c162b003dd@192.168.17.1:5060 [Sep 23 11:47:37] DEBUG[840]: chan_sip.c:8521 parse_request: Header 0 [ 42]: NOTIFY sip:111@192.168.17.141:5060 SIP/2.0 [Sep 23 11:47:37] DEBUG[840]: chan_sip.c:8521 parse_request: Header 1 [ 63]: Via: SIP/2.0/UDP 192.168.17.1:5060;branch=z9hG4bK4e48d0e3;rport [Sep 23 11:47:37] DEBUG[840]: chan_sip.c:8521 parse_request: Header 2 [ 16]: Max-Forwards: 70 [Sep 23 11:47:37] DEBUG[840]: chan_sip.c:8521 parse_request: Header 3 [ 59]: From: "asterisk" ;tag=as632d1a85 [Sep 23 11:47:37] DEBUG[840]: chan_sip.c:8521 parse_request: Header 4 [ 33]: To: [Sep 23 11:47:37] DEBUG[840]: chan_sip.c:8521 parse_request: Header 5 [ 41]: Contact: [Sep 23 11:47:37] DEBUG[840]: chan_sip.c:8521 parse_request: Header 6 [ 59]: Call-ID: 1c73545e614f43624680a5c162b003dd@192.168.17.1:5060 [Sep 23 11:47:37] DEBUG[840]: chan_sip.c:8521 parse_request: Header 7 [ 16]: CSeq: 102 NOTIFY [Sep 23 11:47:37] DEBUG[840]: chan_sip.c:8521 parse_request: Header 8 [ 33]: User-Agent: Asterisk PBX 1.8.15.0 [Sep 23 11:47:37] DEBUG[840]: chan_sip.c:8521 parse_request: Header 9 [ 22]: Event: message-summary [Sep 23 11:47:37] DEBUG[840]: chan_sip.c:8521 parse_request: Header 10 [ 48]: Content-Type: application/simple-message-summary Reliably Transmitting (NAT) to 192.168.17.141:5060: NOTIFY sip:111@192.168.17.141:5060 SIP/2.0 Via: SIP/2.0/UDP 192.168.17.1:5060;branch=z9hG4bK4e48d0e3;rport Max-Forwards: 70 From: "asterisk" ;tag=as632d1a85 To: Contact: Call-ID: 1c73545e614f43624680a5c162b003dd@192.168.17.1:5060 CSeq: 102 NOTIFY User-Agent: Asterisk PBX 1.8.15.0 Event: message-summary Content-Type: application/simple-message-summary Content-Length: 92 Messages-Waiting: no Message-Account: sip:asterisk@192.168.17.1 Voice-Message: 0/0 (0/0) --- [Sep 23 11:47:37] DEBUG[840]: chan_sip.c:3801 __sip_reliable_xmit: *** SIP TIMER: Initializing retransmit timer on packet: Id #162 [Sep 23 11:47:37] DEBUG[840]: chan_sip.c:3346 __sip_xmit: Trying to put 'NOTIFY sip:' onto UDP socket destined for 192.168.17.141:5060 Scheduling destruction of SIP dialog '2660979324@192_168_17_141' in 32000 ms (Method: REGISTER) [Sep 23 11:47:37] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:37] DEBUG[822]: devicestate.c:342 _ast_device_state: No provider found, checking channel drivers for SIP - 111 [Sep 23 11:47:37] DEBUG[822]: chan_sip.c:26559 sip_devicestate: Checking device state for peer 111 [Sep 23 11:47:37] DEBUG[822]: devicestate.c:460 do_state_change: Changing state for SIP/111 - state 2 (In use) [Sep 23 11:47:37] DEBUG[822]: devicestate.c:440 devstate_event: device 'SIP/111' state '2' [Sep 23 11:47:37] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:37] DEBUG[859]: app_queue.c:1490 handle_statechange: Device 'SIP/111' changed to state '2' (In use) but we don't care because they're not a member of any queue. <--- SIP read from UDP:192.168.17.141:5060 ---> SIP/2.0 486 Busy Here Via: SIP/2.0/UDP 192.168.17.1:5060;branch=z9hG4bK49dc1854;rport=5060 From: "asterisk" ;tag=as6ab20116 To: Call-ID: 047a83935e7cd2631e34f16b13d073c9@192.168.17.1:5060 CSeq: 102 OPTIONS User-Agent: C610A IP/42.072.00.000.000 Content-Length: 0 <-------------> [Sep 23 11:47:37] DEBUG[840]: chan_sip.c:8521 parse_request: Header 0 [ 21]: SIP/2.0 486 Busy Here [Sep 23 11:47:37] DEBUG[840]: chan_sip.c:8521 parse_request: Header 1 [ 68]: Via: SIP/2.0/UDP 192.168.17.1:5060;branch=z9hG4bK49dc1854;rport=5060 [Sep 23 11:47:37] DEBUG[840]: chan_sip.c:8521 parse_request: Header 2 [ 59]: From: "asterisk" ;tag=as6ab20116 [Sep 23 11:47:37] DEBUG[840]: chan_sip.c:8521 parse_request: Header 3 [ 33]: To: [Sep 23 11:47:37] DEBUG[840]: chan_sip.c:8521 parse_request: Header 4 [ 59]: Call-ID: 047a83935e7cd2631e34f16b13d073c9@192.168.17.1:5060 [Sep 23 11:47:37] DEBUG[840]: chan_sip.c:8521 parse_request: Header 5 [ 17]: CSeq: 102 OPTIONS [Sep 23 11:47:37] DEBUG[840]: chan_sip.c:8521 parse_request: Header 6 [ 38]: User-Agent: C610A IP/42.072.00.000.000 [Sep 23 11:47:37] DEBUG[840]: chan_sip.c:8521 parse_request: Header 7 [ 17]: Content-Length: 0 --- (8 headers 0 lines) --- [Sep 23 11:47:37] DEBUG[840]: chan_sip.c:8115 find_call: = Looking for Call ID: 047a83935e7cd2631e34f16b13d073c9@192.168.17.1:5060 (Checking To) --From tag as6ab20116 --To-tag [Sep 23 11:47:37] DEBUG[840]: chan_sip.c:3997 __sip_ack: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #159 [Sep 23 11:47:37] DEBUG[840]: chan_sip.c:4030 __sip_ack: Stopping retransmission on '047a83935e7cd2631e34f16b13d073c9@192.168.17.1:5060' of Request 102: Match Found [Sep 23 11:47:37] DEBUG[840]: chan_sip.c:6053 sip_destroy: Destroying SIP dialog 047a83935e7cd2631e34f16b13d073c9@192.168.17.1:5060 Really destroying SIP dialog '047a83935e7cd2631e34f16b13d073c9@192.168.17.1:5060' Method: OPTIONS [Sep 23 11:47:37] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame <--- SIP read from UDP:192.168.17.141:5060 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.17.1:5060;branch=z9hG4bK4e48d0e3;rport=5060 From: "asterisk" ;tag=as632d1a85 To: ;tag=ar723e0a94 Call-ID: 1c73545e614f43624680a5c162b003dd@192.168.17.1:5060 CSeq: 102 NOTIFY User-Agent: C610A IP/42.072.00.000.000 Content-Length: 0 <-------------> [Sep 23 11:47:37] DEBUG[840]: chan_sip.c:8521 parse_request: Header 0 [ 14]: SIP/2.0 200 OK [Sep 23 11:47:37] DEBUG[840]: chan_sip.c:8521 parse_request: Header 1 [ 68]: Via: SIP/2.0/UDP 192.168.17.1:5060;branch=z9hG4bK4e48d0e3;rport=5060 [Sep 23 11:47:37] DEBUG[840]: chan_sip.c:8521 parse_request: Header 2 [ 59]: From: "asterisk" ;tag=as632d1a85 [Sep 23 11:47:37] DEBUG[840]: chan_sip.c:8521 parse_request: Header 3 [ 48]: To: ;tag=ar723e0a94 [Sep 23 11:47:37] DEBUG[840]: chan_sip.c:8521 parse_request: Header 4 [ 59]: Call-ID: 1c73545e614f43624680a5c162b003dd@192.168.17.1:5060 [Sep 23 11:47:37] DEBUG[840]: chan_sip.c:8521 parse_request: Header 5 [ 16]: CSeq: 102 NOTIFY [Sep 23 11:47:37] DEBUG[840]: chan_sip.c:8521 parse_request: Header 6 [ 38]: User-Agent: C610A IP/42.072.00.000.000 [Sep 23 11:47:37] DEBUG[840]: chan_sip.c:8521 parse_request: Header 7 [ 17]: Content-Length: 0 --- (8 headers 0 lines) --- [Sep 23 11:47:37] DEBUG[840]: chan_sip.c:8115 find_call: = Looking for Call ID: 1c73545e614f43624680a5c162b003dd@192.168.17.1:5060 (Checking To) --From tag as632d1a85 --To-tag ar723e0a94 [Sep 23 11:47:37] DEBUG[840]: chan_sip.c:3997 __sip_ack: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #162 [Sep 23 11:47:37] DEBUG[840]: chan_sip.c:4030 __sip_ack: Stopping retransmission on '1c73545e614f43624680a5c162b003dd@192.168.17.1:5060' of Request 102: Match Found [Sep 23 11:47:37] DEBUG[840]: chan_sip.c:20560 handle_response_notify: Got 200 accepted on NOTIFY 1c73545e614f43624680a5c162b003dd@192.168.17.1:5060 [Sep 23 11:47:37] DEBUG[840]: chan_sip.c:6053 sip_destroy: Destroying SIP dialog 1c73545e614f43624680a5c162b003dd@192.168.17.1:5060 Really destroying SIP dialog '1c73545e614f43624680a5c162b003dd@192.168.17.1:5060' Method: NOTIFY [Sep 23 11:47:37] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:37] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:37] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:37] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:37] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:37] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:37] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:37] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:37] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:37] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:37] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:37] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:37] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:37] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:37] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:37] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:37] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:37] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:37] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:37] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:37] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:37] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:37] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:37] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:37] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:38] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:38] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:38] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:38] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:38] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:38] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:38] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:38] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:38] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:38] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:38] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:38] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:38] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:38] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:38] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:38] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:38] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:38] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:38] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:38] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:38] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:38] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:38] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:38] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:38] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:38] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:38] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:38] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:38] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:38] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:38] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:38] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:38] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:38] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:38] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:38] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:38] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:38] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:38] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:38] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:38] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:38] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:38] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:38] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:38] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:38] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:38] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:38] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:38] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:38] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:39] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:39] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:39] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:39] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:39] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:39] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:39] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:39] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:39] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:39] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:39] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:39] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:39] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:39] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:39] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:39] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:39] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:39] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:39] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:39] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:39] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:39] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:39] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:39] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:39] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:39] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:39] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:39] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:39] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:39] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:39] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:39] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:39] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:39] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:39] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:39] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:39] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:39] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:39] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:39] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:39] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:39] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:39] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:39] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:39] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:39] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:39] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:39] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:39] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:39] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:40] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:40] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:40] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:40] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:40] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:40] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:40] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:40] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:40] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:40] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:40] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:40] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:40] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:40] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:40] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:40] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:40] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:40] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:40] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:40] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:40] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:40] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:40] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:40] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:40] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:40] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:40] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:40] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:40] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:40] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:40] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:40] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:40] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:40] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:40] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:40] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:40] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:40] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:40] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:40] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:40] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:40] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:40] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:40] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:40] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:40] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:40] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:40] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:40] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:40] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:41] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:41] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:41] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:41] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:41] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:41] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:41] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:41] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:41] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:41] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:41] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:41] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:41] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:41] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:41] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:41] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:41] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:41] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:41] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:41] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:41] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:41] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:41] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:41] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:41] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:41] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:41] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:41] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:41] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:41] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:41] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:41] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:41] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:41] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:41] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:41] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:41] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:41] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:41] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:41] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:41] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:41] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:41] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:41] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:41] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:41] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:41] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:41] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:41] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:41] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:42] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:42] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:42] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:42] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:42] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:42] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:42] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:42] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:42] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:42] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:42] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:42] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:42] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:42] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:42] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:42] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:42] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:42] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:42] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:42] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:42] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:42] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:42] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:42] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:42] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:42] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:42] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:42] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:42] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:42] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:42] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:42] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:42] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:42] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:42] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:42] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:42] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:42] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:42] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:42] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:42] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:42] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:42] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:42] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:42] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:42] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:42] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:42] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:42] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:42] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:43] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:43] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:43] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:43] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:43] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:43] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:43] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:43] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:43] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:43] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:43] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:43] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:43] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:43] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:43] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:43] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:43] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:43] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:43] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:43] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:43] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:43] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:43] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:43] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:43] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:43] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:43] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:43] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:43] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:43] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:43] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:43] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:43] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:43] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:43] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:43] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:43] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:43] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:43] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:43] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:43] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:43] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:43] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:43] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:43] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:43] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:43] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:43] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:43] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:43] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:44] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:44] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:44] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:44] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:44] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:44] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:44] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:44] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:44] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:44] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:44] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:44] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:44] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:44] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:44] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:44] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:44] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:44] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:44] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:44] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:44] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:44] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame [Sep 23 11:47:44] DEBUG[1544]: res_rtp_asterisk.c:1324 ast_rtp_write: No remote address on RTP instance '0x88a5c30' so dropping frame <--- SIP read from UDP:192.168.17.4:5060 ---> SIP/2.0 200 OK To: ;tag=cf9d85834754482ci0 From: "Cordless 1" ;tag=as0da8de2e Call-ID: 0d642d503eb8e283036b65f8181d2d52@192.168.17.1:5060 CSeq: 102 INVITE Via: SIP/2.0/UDP 192.168.17.1:5060;branch=z9hG4bK7be19d57 Contact: Line 1 Server: Linksys/PAP2T-5.1.6(LS) Content-Length: 253 Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER Supported: x-sipura, replaces Content-Type: application/sdp v=0 o=- 529748 529748 IN IP4 192.168.17.4 s=- c=IN IP4 192.168.17.4 t=0 0 m=audio 16482 RTP/AVP 0 100 101 a=rtpmap:0 PCMU/8000 a=rtpmap:100 NSE/8000 a=fmtp:100 192-193 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=ptime:20 a=sendrecv <-------------> [Sep 23 11:47:44] DEBUG[840]: chan_sip.c:8521 parse_request: Header 0 [ 14]: SIP/2.0 200 OK [Sep 23 11:47:44] DEBUG[840]: chan_sip.c:8521 parse_request: Header 1 [ 54]: To: ;tag=cf9d85834754482ci0 [Sep 23 11:47:44] DEBUG[840]: chan_sip.c:8521 parse_request: Header 2 [ 56]: From: "Cordless 1" ;tag=as0da8de2e [Sep 23 11:47:44] DEBUG[840]: chan_sip.c:8521 parse_request: Header 3 [ 59]: Call-ID: 0d642d503eb8e283036b65f8181d2d52@192.168.17.1:5060 [Sep 23 11:47:44] DEBUG[840]: chan_sip.c:8521 parse_request: Header 4 [ 16]: CSeq: 102 INVITE [Sep 23 11:47:44] DEBUG[840]: chan_sip.c:8521 parse_request: Header 5 [ 57]: Via: SIP/2.0/UDP 192.168.17.1:5060;branch=z9hG4bK7be19d57 [Sep 23 11:47:44] DEBUG[840]: chan_sip.c:8521 parse_request: Header 6 [ 43]: Contact: Line 1 [Sep 23 11:47:44] DEBUG[840]: chan_sip.c:8521 parse_request: Header 7 [ 31]: Server: Linksys/PAP2T-5.1.6(LS) [Sep 23 11:47:44] DEBUG[840]: chan_sip.c:8521 parse_request: Header 8 [ 19]: Content-Length: 253 [Sep 23 11:47:44] DEBUG[840]: chan_sip.c:8521 parse_request: Header 9 [ 61]: Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER [Sep 23 11:47:44] DEBUG[840]: chan_sip.c:8521 parse_request: Header 10 [ 29]: Supported: x-sipura, replaces [Sep 23 11:47:44] DEBUG[840]: chan_sip.c:8521 parse_request: Header 11 [ 29]: Content-Type: application/sdp [Sep 23 11:47:44] DEBUG[840]: chan_sip.c:8521 parse_request: Header 12 [ 0]: [Sep 23 11:47:44] DEBUG[840]: chan_sip.c:8521 parse_request: Body 0 [ 3]: v=0 [Sep 23 11:47:44] DEBUG[840]: chan_sip.c:8521 parse_request: Body 1 [ 37]: o=- 529748 529748 IN IP4 192.168.17.4 [Sep 23 11:47:44] DEBUG[840]: chan_sip.c:8521 parse_request: Body 2 [ 3]: s=- [Sep 23 11:47:44] DEBUG[840]: chan_sip.c:8521 parse_request: Body 3 [ 21]: c=IN IP4 192.168.17.4 [Sep 23 11:47:44] DEBUG[840]: chan_sip.c:8521 parse_request: Body 4 [ 5]: t=0 0 [Sep 23 11:47:44] DEBUG[840]: chan_sip.c:8521 parse_request: Body 5 [ 31]: m=audio 16482 RTP/AVP 0 100 101 [Sep 23 11:47:44] DEBUG[840]: chan_sip.c:8521 parse_request: Body 6 [ 20]: a=rtpmap:0 PCMU/8000 [Sep 23 11:47:44] DEBUG[840]: chan_sip.c:8521 parse_request: Body 7 [ 21]: a=rtpmap:100 NSE/8000 [Sep 23 11:47:44] DEBUG[840]: chan_sip.c:8521 parse_request: Body 8 [ 18]: a=fmtp:100 192-193 [Sep 23 11:47:44] DEBUG[840]: chan_sip.c:8521 parse_request: Body 9 [ 33]: a=rtpmap:101 telephone-event/8000 [Sep 23 11:47:44] DEBUG[840]: chan_sip.c:8521 parse_request: Body 10 [ 15]: a=fmtp:101 0-15 [Sep 23 11:47:44] DEBUG[840]: chan_sip.c:8521 parse_request: Body 11 [ 10]: a=ptime:20 [Sep 23 11:47:44] DEBUG[840]: chan_sip.c:8558 parse_request: Body 12 [ 10]: a=sendrecv --- (12 headers 13 lines) --- [Sep 23 11:47:44] DEBUG[840]: chan_sip.c:8115 find_call: = Looking for Call ID: 0d642d503eb8e283036b65f8181d2d52@192.168.17.1:5060 (Checking To) --From tag as0da8de2e --To-tag cf9d85834754482ci0 [Sep 23 11:47:44] DEBUG[840]: chan_sip.c:3992 __sip_ack: Acked pending invite 102 [Sep 23 11:47:44] DEBUG[840]: chan_sip.c:4030 __sip_ack: Stopping retransmission on '0d642d503eb8e283036b65f8181d2d52@192.168.17.1:5060' of Request 102: Match Found [Sep 23 11:47:44] DEBUG[840]: chan_sip.c:20102 handle_response_invite: SIP response 200 to standard invite [Sep 23 11:47:44] DEBUG[840]: chan_sip.c:8908 process_sdp: Processing session-level SDP v=0... UNSUPPORTED OR FAILED. [Sep 23 11:47:44] DEBUG[840]: chan_sip.c:8908 process_sdp: Processing session-level SDP o=- 529748 529748 IN IP4 192.168.17.4... UNSUPPORTED OR FAILED. [Sep 23 11:47:44] DEBUG[840]: chan_sip.c:8908 process_sdp: Processing session-level SDP s=-... UNSUPPORTED OR FAILED. [Sep 23 11:47:44] DEBUG[840]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting '192.168.17.4' into... [Sep 23 11:47:44] DEBUG[840]: netsock2.c:192 ast_sockaddr_split_hostport: ...host '192.168.17.4' and port ''. [Sep 23 11:47:44] DEBUG[840]: chan_sip.c:8908 process_sdp: Processing session-level SDP c=IN IP4 192.168.17.4... OK. [Sep 23 11:47:44] DEBUG[840]: chan_sip.c:8908 process_sdp: Processing session-level SDP t=0 0... UNSUPPORTED OR FAILED. Found RTP audio format 0 [Sep 23 11:47:44] DEBUG[840]: rtp_engine.c:541 ast_rtp_codecs_payloads_set_m_type: Setting payload 0 based on m type on 0x41504490 Found RTP audio format 100 Found RTP audio format 101 [Sep 23 11:47:44] DEBUG[840]: rtp_engine.c:541 ast_rtp_codecs_payloads_set_m_type: Setting payload 101 based on m type on 0x41504490 Found audio description format PCMU for ID 0 [Sep 23 11:47:44] DEBUG[840]: chan_sip.c:9179 process_sdp: Processing media-level (audio) SDP a=rtpmap:0 PCMU/8000... OK. [Sep 23 11:47:44] DEBUG[840]: rtp_engine.c:608 ast_rtp_codecs_payloads_unset: Unsetting payload 100 on 0x41504490 Found unknown media description format NSE for ID 100 [Sep 23 11:47:44] DEBUG[840]: chan_sip.c:9179 process_sdp: Processing media-level (audio) SDP a=rtpmap:100 NSE/8000... UNSUPPORTED OR FAILED. [Sep 23 11:47:44] DEBUG[840]: chan_sip.c:9179 process_sdp: Processing media-level (audio) SDP a=fmtp:100 192-193... UNSUPPORTED OR FAILED. Found audio description format telephone-event for ID 101 [Sep 23 11:47:44] DEBUG[840]: chan_sip.c:9179 process_sdp: Processing media-level (audio) SDP a=rtpmap:101 telephone-event/8000... OK. [Sep 23 11:47:44] DEBUG[840]: chan_sip.c:9179 process_sdp: Processing media-level (audio) SDP a=fmtp:101 0-15... UNSUPPORTED OR FAILED. [Sep 23 11:47:44] DEBUG[840]: chan_sip.c:9179 process_sdp: Processing media-level (audio) SDP a=ptime:20... OK. [Sep 23 11:47:44] DEBUG[840]: chan_sip.c:9179 process_sdp: Processing media-level (audio) SDP a=sendrecv... OK. [Sep 23 11:47:44] DEBUG[840]: rtp_engine.c:644 ast_rtp_codecs_payload_formats: Incorporating payload 0 on 0x41504490 [Sep 23 11:47:44] DEBUG[840]: rtp_engine.c:644 ast_rtp_codecs_payload_formats: Incorporating payload 101 on 0x41504490 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|) [Sep 23 11:47:44] DEBUG[840]: res_rtp_asterisk.c:2563 ast_rtp_remote_address_set: Setting RTCP address on RTP instance '0x88a5c30' Peer audio RTP is at port 192.168.17.4:16482 [Sep 23 11:47:44] DEBUG[840]: rtp_engine.c:522 ast_rtp_codecs_payloads_copy: Copying payload 0 from 0x41504490 to 0x88a5ddc [Sep 23 11:47:44] DEBUG[840]: rtp_engine.c:522 ast_rtp_codecs_payloads_copy: Copying payload 101 from 0x41504490 to 0x88a5ddc [Sep 23 11:47:44] DEBUG[840]: res_rtp_asterisk.c:2489 ast_rtp_prop_set: Ignoring duplicate RTCP property on RTP instance '0x88a5c30' [Sep 23 11:47:44] DEBUG[840]: chan_sip.c:9424 process_sdp: We're settling with these formats: 0x4 (ulaw) [Sep 23 11:47:44] DEBUG[840]: chan_sip.c:9429 process_sdp: We have an owner, now see if we need to change this call [Sep 23 11:47:44] DEBUG[840]: chan_sip.c:5905 update_call_counter: Updating call counter for outgoing call [Sep 23 11:47:44] DEBUG[840]: chan_sip.c:14336 build_route: build_route: Contact hop: Line 1 list_route: hop: [Sep 23 11:47:44] DEBUG[840]: chan_sip.c:10397 reqprep: Strict routing enforced for session 0d642d503eb8e283036b65f8181d2d52@192.168.17.1:5060 set_destination: Parsing for address/port to send to [Sep 23 11:47:44] DEBUG[840]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting '192.168.17.4:5060' into... [Sep 23 11:47:44] DEBUG[840]: netsock2.c:192 ast_sockaddr_split_hostport: ...host '192.168.17.4' and port '5060'. set_destination: set destination to 192.168.17.4:5060 Transmitting (NAT) to 192.168.17.4:5060: ACK sip:101@192.168.17.4:5060 SIP/2.0 Via: SIP/2.0/UDP 192.168.17.1:5060;branch=z9hG4bK11297065;rport Max-Forwards: 70 From: "Cordless 1" ;tag=as0da8de2e To: ;tag=cf9d85834754482ci0 Contact: Call-ID: 0d642d503eb8e283036b65f8181d2d52@192.168.17.1:5060 CSeq: 102 ACK User-Agent: Asterisk PBX 1.8.15.0 Content-Length: 0 --- [Sep 23 11:47:44] DEBUG[840]: chan_sip.c:3346 __sip_xmit: Trying to put 'ACK sip:101' onto UDP socket destined for 192.168.17.4:5060 -- SIP/101-00000005 answered Local/s@OutboundSLA-b629;2 [Sep 23 11:47:44] DEBUG[1544]: features.c:4012 ast_bridge_call: bridge answer set, chan answer set [Sep 23 11:47:44] DEBUG[1544]: features.c:3854 clear_dialed_interfaces: Removing dialed interfaces datastore on SIP/101-00000005 since we're bridging [Sep 23 11:47:44] DEBUG[1544]: res_rtp_asterisk.c:828 ast_rtp_update_source: Setting the marker bit due to a source update [Sep 23 11:47:44] DEBUG[822]: devicestate.c:342 _ast_device_state: No provider found, checking channel drivers for SIP - 101 [Sep 23 11:47:44] DEBUG[822]: chan_sip.c:26559 sip_devicestate: Checking device state for peer 101 [Sep 23 11:47:44] DEBUG[822]: devicestate.c:460 do_state_change: Changing state for SIP/101 - state 2 (In use) [Sep 23 11:47:44] DEBUG[822]: devicestate.c:440 devstate_event: device 'SIP/101' state '2' [Sep 23 11:47:44] DEBUG[822]: devicestate.c:342 _ast_device_state: No provider found, checking channel drivers for SIP - 101 [Sep 23 11:47:44] DEBUG[822]: chan_sip.c:26559 sip_devicestate: Checking device state for peer 101 [Sep 23 11:47:44] DEBUG[822]: devicestate.c:460 do_state_change: Changing state for SIP/101 - state 2 (In use) [Sep 23 11:47:44] DEBUG[822]: devicestate.c:440 devstate_event: device 'SIP/101' state '2' [Sep 23 11:47:44] DEBUG[1543]: app_meetme.c:3595 conf_run: Got ignored control frame on channel Local/s@OutboundSLA-b629;1, f->frametype=4,f->subclass=22 [Sep 23 11:47:44] DEBUG[1543]: app_meetme.c:3595 conf_run: Got ignored control frame on channel Local/s@OutboundSLA-b629;1, f->frametype=4,f->subclass=20 [Sep 23 11:47:44] DEBUG[1544]: res_rtp_asterisk.c:1359 ast_rtp_write: Ooh, format changed from unknown to ulaw [Sep 23 11:47:44] DEBUG[1544]: res_rtp_asterisk.c:1390 ast_rtp_write: Created smoother: format: ulaw ms: 20 len: 160 [Sep 23 11:47:44] DEBUG[1544]: res_rtp_asterisk.c:1255 ast_rtp_raw_write: Starting RTCP transmission on RTP instance '0x88a5c30' [Sep 23 11:47:44] DEBUG[859]: app_queue.c:1490 handle_statechange: Device 'SIP/101' changed to state '2' (In use) but we don't care because they're not a member of any queue. [Sep 23 11:47:44] DEBUG[859]: app_queue.c:1490 handle_statechange: Device 'SIP/101' changed to state '2' (In use) but we don't care because they're not a member of any queue. [Sep 23 11:47:44] DEBUG[1544]: channel.c:5954 __ast_channel_masquerade: Planning to masquerade channel SIP/101-00000005 into the structure of Local/s@OutboundSLA-b629;1 [Sep 23 11:47:44] DEBUG[1544]: channel.c:5964 __ast_channel_masquerade: Done planning to masquerade channel SIP/101-00000005 into the structure of Local/s@OutboundSLA-b629;1 [Sep 23 11:47:44] DEBUG[1544]: chan_local.c:557 check_bridge: Masquerading Local/s@OutboundSLA-b629;1 <- SIP/101-00000005 [Sep 23 11:47:44] DEBUG[1544]: chan_local.c:760 local_indicate: Blocked indication -1 [Sep 23 11:47:44] DEBUG[1544]: channel.c:6539 ast_do_masquerade: Actually Masquerading SIP/101-00000005(6) into the structure of Local/s@OutboundSLA-b629;1(5) [Sep 23 11:47:44] DEBUG[1544]: channel.c:5175 set_format: Set channel SIP/101-00000005 to write format slin [Sep 23 11:47:44] DEBUG[1544]: channel.c:5175 set_format: Set channel SIP/101-00000005 to read format slin [Sep 23 11:47:44] DEBUG[1544]: channel.c:6743 ast_do_masquerade: Putting channel SIP/101-00000005 in slin/slin formats [Sep 23 11:47:44] DEBUG[1544]: chan_sip.c:6645 sip_fixup: SIP Fixup: New owner for dialogue 0d642d503eb8e283036b65f8181d2d52@192.168.17.1:5060: SIP/101-00000005 (Old parent: Local/s@OutboundSLA-b629;1) [Sep 23 11:47:44] DEBUG[1544]: channel.c:6819 ast_do_masquerade: Done Masquerading SIP/101-00000005 (6) [Sep 23 11:47:44] DEBUG[1544]: res_rtp_asterisk.c:847 ast_rtp_change_source: Changing ssrc from 257671102 to 371471377 due to a source change [Sep 23 11:47:44] DEBUG[1544]: chan_local.c:654 local_write: Not posting to 'Local/s@OutboundSLA-b629;2' queue since already masqueraded out [Sep 23 11:47:44] DEBUG[1544]: channel.c:7488 ast_channel_bridge: Bridge stops because we're zombie or need a soft hangup: c0=Local/s@OutboundSLA-b629;2, c1=Local/s@OutboundSLA-b629;1, flags: No,Yes,Yes,Yes [Sep 23 11:47:44] DEBUG[1544]: channel.c:7595 ast_channel_bridge: Bridge stops bridging channels Local/s@OutboundSLA-b629;2 and Local/s@OutboundSLA-b629;1 [Sep 23 11:47:44] DEBUG[1544]: channel.c:2695 ast_softhangup_nolock: Soft-Hanging up channel 'Local/s@OutboundSLA-b629;2' [Sep 23 11:47:44] DEBUG[1544]: pbx.c:4247 pbx_extension_helper: Launching 'Hangup' -- Executing [h@OutboundSLA:1] Hangup("Local/s@OutboundSLA-b629;2", "") in new stack [Sep 23 11:47:44] DEBUG[1544]: features.c:4393 ast_bridge_call: Spawn extension (OutboundSLA,h,1) exited non-zero on 'Local/s@OutboundSLA-b629;2' == Spawn extension (OutboundSLA, h, 1) exited non-zero on 'Local/s@OutboundSLA-b629;2' [Sep 23 11:47:44] DEBUG[1544]: channel.c:2850 ast_hangup: Hanging up zombie 'Local/s@OutboundSLA-b629;1' [Sep 23 11:47:44] DEBUG[1544]: app_dial.c:3027 dial_exec_full: Exiting with DIALSTATUS=ANSWER. [Sep 23 11:47:44] DEBUG[1544]: pbx.c:5064 __ast_pbx_run: Spawn extension (OutboundSLA,101,1) exited non-zero on 'Local/s@OutboundSLA-b629;2' == Spawn extension (OutboundSLA, 101, 1) exited non-zero on 'Local/s@OutboundSLA-b629;2' [Sep 23 11:47:44] DEBUG[1544]: channel.c:2695 ast_softhangup_nolock: Soft-Hanging up channel 'Local/s@OutboundSLA-b629;2' [Sep 23 11:47:44] DEBUG[1544]: channel.c:2844 ast_hangup: Hanging up channel 'Local/s@OutboundSLA-b629;2' [Sep 23 11:47:44] DEBUG[1543]: app_meetme.c:3127 conf_run: Ooh, something swapped out under us, starting over [Sep 23 11:47:44] DEBUG[1543]: app_meetme.c:2683 conf_run: Placed channel SIP/101-00000005 in DAHDI conf 1023 [Sep 23 11:47:44] DEBUG[822]: devicestate.c:342 _ast_device_state: No provider found, checking channel drivers for Local - s@OutboundSLA [Sep 23 11:47:44] DEBUG[822]: chan_local.c:300 local_devicestate: Checking if extension s@OutboundSLA exists (devicestate) [Sep 23 11:47:44] DEBUG[822]: devicestate.c:460 do_state_change: Changing state for Local/s@OutboundSLA - state 1 (Not in use) [Sep 23 11:47:44] DEBUG[822]: devicestate.c:440 devstate_event: device 'Local/s@OutboundSLA' state '1' [Sep 23 11:47:44] DEBUG[822]: devicestate.c:342 _ast_device_state: No provider found, checking channel drivers for Local - s@OutboundSLA [Sep 23 11:47:44] DEBUG[822]: chan_local.c:300 local_devicestate: Checking if extension s@OutboundSLA exists (devicestate) [Sep 23 11:47:44] DEBUG[822]: devicestate.c:460 do_state_change: Changing state for Local/s@OutboundSLA - state 1 (Not in use) [Sep 23 11:47:44] DEBUG[822]: devicestate.c:440 devstate_event: device 'Local/s@OutboundSLA' state '1' [Sep 23 11:47:44] DEBUG[1543]: dsp.c:475 ast_tone_detect_init: Setup tone 1100 Hz, 500 ms, block_size=160, hits_required=21 [Sep 23 11:47:44] DEBUG[1543]: dsp.c:475 ast_tone_detect_init: Setup tone 2100 Hz, 2600 ms, block_size=160, hits_required=116 [Sep 23 11:47:44] DEBUG[859]: app_queue.c:1490 handle_statechange: Device 'Local/s@OutboundSLA' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. [Sep 23 11:47:44] DEBUG[859]: app_queue.c:1490 handle_statechange: Device 'Local/s@OutboundSLA' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. [Sep 23 11:47:45] DEBUG[840]: chan_sip.c:3886 __sip_autodestruct: Auto destroying SIP dialog '2422681758@192_168_17_141' [Sep 23 11:47:45] DEBUG[840]: chan_sip.c:6053 sip_destroy: Destroying SIP dialog 2422681758@192_168_17_141 Really destroying SIP dialog '2422681758@192_168_17_141' Method: REGISTER <--- SIP read from UDP:192.168.17.141:5060 ---> BYE sip:*1@192.168.17.1:5060 SIP/2.0 Via: SIP/2.0/UDP 192.168.17.141:5060;branch=z9hG4bK4e55466abbb7bbfbb1fee2d628c9fbaf;rport From: "Cordless 1" ;tag=2709006301 To: ;tag=as5a0c6d3f Call-ID: 2496317345@192_168_17_141 CSeq: 3 BYE Contact: Max-Forwards: 70 User-Agent: C610A IP/42.072.00.000.000 Content-Length: 0 <-------------> [Sep 23 11:47:50] DEBUG[840]: chan_sip.c:8521 parse_request: Header 0 [ 36]: BYE sip:*1@192.168.17.1:5060 SIP/2.0 [Sep 23 11:47:50] DEBUG[840]: chan_sip.c:8521 parse_request: Header 1 [ 89]: Via: SIP/2.0/UDP 192.168.17.141:5060;branch=z9hG4bK4e55466abbb7bbfbb1fee2d628c9fbaf;rport [Sep 23 11:47:50] DEBUG[840]: chan_sip.c:8521 parse_request: Header 2 [ 58]: From: "Cordless 1" ;tag=2709006301 [Sep 23 11:47:50] DEBUG[840]: chan_sip.c:8521 parse_request: Header 3 [ 53]: To: ;tag=as5a0c6d3f [Sep 23 11:47:50] DEBUG[840]: chan_sip.c:8521 parse_request: Header 4 [ 34]: Call-ID: 2496317345@192_168_17_141 [Sep 23 11:47:50] DEBUG[840]: chan_sip.c:8521 parse_request: Header 5 [ 11]: CSeq: 3 BYE [Sep 23 11:47:50] DEBUG[840]: chan_sip.c:8521 parse_request: Header 6 [ 38]: Contact: [Sep 23 11:47:50] DEBUG[840]: chan_sip.c:8521 parse_request: Header 7 [ 16]: Max-Forwards: 70 [Sep 23 11:47:50] DEBUG[840]: chan_sip.c:8521 parse_request: Header 8 [ 38]: User-Agent: C610A IP/42.072.00.000.000 [Sep 23 11:47:50] DEBUG[840]: chan_sip.c:8521 parse_request: Header 9 [ 17]: Content-Length: 0 --- (10 headers 0 lines) --- [Sep 23 11:47:50] DEBUG[840]: chan_sip.c:8115 find_call: = Looking for Call ID: 2496317345@192_168_17_141 (Checking From) --From tag 2709006301 --To-tag as5a0c6d3f [Sep 23 11:47:50] DEBUG[840]: chan_sip.c:25254 handle_incoming: **** Received BYE (8) - Command in SIP BYE [Sep 23 11:47:50] DEBUG[840]: chan_sip.c:23954 handle_request_bye: Initializing initreq for method BYE - callid 2496317345@192_168_17_141 [Sep 23 11:47:50] DEBUG[840]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting '192.168.17.141:5060' into... [Sep 23 11:47:50] DEBUG[840]: netsock2.c:192 ast_sockaddr_split_hostport: ...host '192.168.17.141' and port '5060'. Sending to 192.168.17.141:5060 (NAT) [Sep 23 11:47:50] DEBUG[840]: chan_sip.c:3088 sip_alreadygone: Setting SIP_ALREADYGONE on dialog 2496317345@192_168_17_141 [Sep 23 11:47:50] DEBUG[840]: res_rtp_asterisk.c:2563 ast_rtp_remote_address_set: Setting RTCP address on RTP instance '0x8896740' Scheduling destruction of SIP dialog '2496317345@192_168_17_141' in 6400 ms (Method: BYE) [Sep 23 11:47:50] DEBUG[840]: chan_sip.c:24056 handle_request_bye: Received bye, issuing owner hangup <--- Transmitting (NAT) to 192.168.17.141:5060 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.17.141:5060;branch=z9hG4bK4e55466abbb7bbfbb1fee2d628c9fbaf;received=192.168.17.141;rport=5060 From: "Cordless 1" ;tag=2709006301 To: ;tag=as5a0c6d3f Call-ID: 2496317345@192_168_17_141 CSeq: 3 BYE Server: Asterisk PBX 1.8.15.0 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH Supported: replaces, timer Content-Length: 0 <------------> [Sep 23 11:47:50] DEBUG[840]: chan_sip.c:3346 __sip_xmit: Trying to put 'SIP/2.0 200' onto UDP socket destined for 192.168.17.141:5060 == Setting global variable 'MEETMEADMINSTATUS' to 'OK' [Sep 23 11:47:50] DEBUG[1542]: devicestate.c:440 devstate_event: device 'SLA:SLAphone111_SLAtrunk1' state '1' [Sep 23 11:47:50] DEBUG[1542]: devicestate.c:440 devstate_event: device 'SLA:SLAphone112_SLAtrunk1' state '1' [Sep 23 11:47:50] DEBUG[1542]: pbx.c:5039 __ast_pbx_run: Extension *1, priority 1 returned normally even though call was hung up [Sep 23 11:47:50] DEBUG[1542]: channel.c:2695 ast_softhangup_nolock: Soft-Hanging up channel 'SIP/111-00000004' [Sep 23 11:47:50] DEBUG[1542]: pbx.c:4247 pbx_extension_helper: Launching 'Hangup' -- Executing [h@DialPlanSLA:1] Hangup("SIP/111-00000004", "") in new stack [Sep 23 11:47:50] DEBUG[1542]: pbx.c:5243 __ast_pbx_run: Spawn extension (DialPlanSLA,h,1) exited non-zero on 'SIP/111-00000004' == Spawn extension (DialPlanSLA, h, 1) exited non-zero on 'SIP/111-00000004' [Sep 23 11:47:50] DEBUG[1542]: channel.c:2844 ast_hangup: Hanging up channel 'SIP/111-00000004' [Sep 23 11:47:50] DEBUG[1542]: chan_sip.c:6287 sip_hangup: Hangup call SIP/111-00000004, SIP callid 2496317345@192_168_17_141 [Sep 23 11:47:50] DEBUG[1542]: chan_sip.c:6292 sip_hangup: update_call_counter(111) - decrement call limit counter on hangup [Sep 23 11:47:50] DEBUG[1542]: chan_sip.c:5905 update_call_counter: Updating call counter for incoming call [Sep 23 11:47:50] DEBUG[1542]: chan_sip.c:5976 update_call_counter: Call from peer '111' removed from call limit 10 [Sep 23 11:47:50] DEBUG[1542]: res_rtp_asterisk.c:2563 ast_rtp_remote_address_set: Setting RTCP address on RTP instance '0x8896740' [Sep 23 11:47:50] DEBUG[822]: devicestate.c:342 _ast_device_state: No provider found, checking channel drivers for SIP - 111 [Sep 23 11:47:50] DEBUG[822]: chan_sip.c:26559 sip_devicestate: Checking device state for peer 111 [Sep 23 11:47:50] DEBUG[822]: devicestate.c:460 do_state_change: Changing state for SIP/111 - state 1 (Not in use) [Sep 23 11:47:50] DEBUG[822]: devicestate.c:440 devstate_event: device 'SIP/111' state '1' [Sep 23 11:47:50] DEBUG[822]: devicestate.c:342 _ast_device_state: No provider found, checking channel drivers for SIP - 111 [Sep 23 11:47:50] DEBUG[822]: chan_sip.c:26559 sip_devicestate: Checking device state for peer 111 [Sep 23 11:47:50] DEBUG[822]: devicestate.c:460 do_state_change: Changing state for SIP/111 - state 1 (Not in use) [Sep 23 11:47:50] DEBUG[822]: devicestate.c:440 devstate_event: device 'SIP/111' state '1' [Sep 23 11:47:50] DEBUG[823]: app_queue.c:1585 extension_state_cb: Extension 'SLAphone111_SLAtrunk1@DialPlanSLA' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. [Sep 23 11:47:50] DEBUG[823]: app_queue.c:1585 extension_state_cb: Extension 'SLAphone112_SLAtrunk1@DialPlanSLA' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. [Sep 23 11:47:50] DEBUG[859]: app_queue.c:1490 handle_statechange: Device 'SLA:SLAphone111_SLAtrunk1' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. [Sep 23 11:47:50] DEBUG[859]: app_queue.c:1490 handle_statechange: Device 'SLA:SLAphone112_SLAtrunk1' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. [Sep 23 11:47:50] DEBUG[859]: app_queue.c:1490 handle_statechange: Device 'SIP/111' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. [Sep 23 11:47:50] DEBUG[859]: app_queue.c:1490 handle_statechange: Device 'SIP/111' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. [Sep 23 11:47:50] DEBUG[1543]: devicestate.c:440 devstate_event: device 'meetme:SLA_SLAtrunk1' state '1' [Sep 23 11:47:50] DEBUG[1543]: channel.c:2844 ast_hangup: Hanging up channel 'DAHDI/pseudo-84343286' [Sep 23 11:47:50] DEBUG[1543]: chan_dahdi.c:6149 dahdi_hangup: dahdi_hangup(DAHDI/pseudo-84343286) [Sep 23 11:47:50] DEBUG[1543]: chan_dahdi.c:6316 dahdi_hangup: Hangup: channel: -2 index = 0, normal = 34, callwait = -1, thirdcall = -1 [Sep 23 11:47:50] DEBUG[1543]: chan_dahdi.c:6757 dahdi_setoption: Set option TDD MODE, value: OFF(0) on DAHDI/pseudo-84343286 [Sep 23 11:47:50] DEBUG[1543]: chan_dahdi.c:4874 update_conf: Updated conferencing on -2, with 0 conference users -- Hungup 'DAHDI/pseudo-84343286' [Sep 23 11:47:50] DEBUG[1543]: devicestate.c:440 devstate_event: device 'SLA:SLAphone111_SLAtrunk1' state '1' [Sep 23 11:47:50] DEBUG[1543]: devicestate.c:440 devstate_event: device 'SLA:SLAphone112_SLAtrunk1' state '1' [Sep 23 11:47:50] DEBUG[1543]: channel.c:2844 ast_hangup: Hanging up channel 'SIP/101-00000005' [Sep 23 11:47:50] DEBUG[1543]: chan_sip.c:6287 sip_hangup: Hangup call SIP/101-00000005, SIP callid 0d642d503eb8e283036b65f8181d2d52@192.168.17.1:5060 [Sep 23 11:47:50] DEBUG[1543]: chan_sip.c:6292 sip_hangup: update_call_counter(101) - decrement call limit counter on hangup [Sep 23 11:47:50] DEBUG[1543]: chan_sip.c:5905 update_call_counter: Updating call counter for outgoing call [Sep 23 11:47:50] DEBUG[1543]: chan_sip.c:5976 update_call_counter: Call to peer '101' removed from call limit 10 [Sep 23 11:47:50] DEBUG[1543]: res_rtp_asterisk.c:2563 ast_rtp_remote_address_set: Setting RTCP address on RTP instance '0x88a5c30' Scheduling destruction of SIP dialog '0d642d503eb8e283036b65f8181d2d52@192.168.17.1:5060' in 6400 ms (Method: INVITE) [Sep 23 11:47:50] DEBUG[1543]: chan_sip.c:10397 reqprep: Strict routing enforced for session 0d642d503eb8e283036b65f8181d2d52@192.168.17.1:5060 set_destination: Parsing for address/port to send to [Sep 23 11:47:50] DEBUG[1543]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting '192.168.17.4:5060' into... [Sep 23 11:47:50] DEBUG[1543]: netsock2.c:192 ast_sockaddr_split_hostport: ...host '192.168.17.4' and port '5060'. set_destination: set destination to 192.168.17.4:5060 Reliably Transmitting (NAT) to 192.168.17.4:5060: BYE sip:101@192.168.17.4:5060 SIP/2.0 Via: SIP/2.0/UDP 192.168.17.1:5060;branch=z9hG4bK324caf1e;rport Max-Forwards: 70 From: "Cordless 1" ;tag=as0da8de2e To: ;tag=cf9d85834754482ci0 Call-ID: 0d642d503eb8e283036b65f8181d2d52@192.168.17.1:5060 CSeq: 103 BYE User-Agent: Asterisk PBX 1.8.15.0 X-Asterisk-HangupCause: Unknown X-Asterisk-HangupCauseCode: 0 Content-Length: 0 --- [Sep 23 11:47:50] DEBUG[1543]: chan_sip.c:3801 __sip_reliable_xmit: *** SIP TIMER: Initializing retransmit timer on packet: Id #168 [Sep 23 11:47:50] DEBUG[1543]: chan_sip.c:3346 __sip_xmit: Trying to put 'BYE sip:101' onto UDP socket destined for 192.168.17.4:5060 [Sep 23 11:47:50] DEBUG[822]: devicestate.c:342 _ast_device_state: No provider found, checking channel drivers for DAHDI - pseudo [Sep 23 11:47:50] DEBUG[822]: devicestate.c:460 do_state_change: Changing state for DAHDI/pseudo - state 0 (Unknown) [Sep 23 11:47:50] DEBUG[822]: devicestate.c:440 devstate_event: device 'DAHDI/pseudo' state '0' [Sep 23 11:47:50] DEBUG[822]: devicestate.c:342 _ast_device_state: No provider found, checking channel drivers for SIP - 101 [Sep 23 11:47:50] DEBUG[822]: chan_sip.c:26559 sip_devicestate: Checking device state for peer 101 [Sep 23 11:47:50] DEBUG[822]: devicestate.c:460 do_state_change: Changing state for SIP/101 - state 1 (Not in use) [Sep 23 11:47:50] DEBUG[822]: devicestate.c:440 devstate_event: device 'SIP/101' state '1' [Sep 23 11:47:50] DEBUG[822]: devicestate.c:342 _ast_device_state: No provider found, checking channel drivers for SIP - 101 [Sep 23 11:47:50] DEBUG[822]: chan_sip.c:26559 sip_devicestate: Checking device state for peer 101 [Sep 23 11:47:50] DEBUG[822]: devicestate.c:460 do_state_change: Changing state for SIP/101 - state 1 (Not in use) [Sep 23 11:47:50] DEBUG[822]: devicestate.c:440 devstate_event: device 'SIP/101' state '1' [Sep 23 11:47:50] DEBUG[859]: app_queue.c:1490 handle_statechange: Device 'meetme:SLA_SLAtrunk1' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. [Sep 23 11:47:50] DEBUG[859]: app_queue.c:1490 handle_statechange: Device 'SLA:SLAphone111_SLAtrunk1' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. [Sep 23 11:47:50] DEBUG[859]: app_queue.c:1490 handle_statechange: Device 'SLA:SLAphone112_SLAtrunk1' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. [Sep 23 11:47:50] DEBUG[859]: app_queue.c:1490 handle_statechange: Device 'DAHDI/pseudo' changed to state '0' (Unknown) but we don't care because they're not a member of any queue. [Sep 23 11:47:50] DEBUG[859]: app_queue.c:1490 handle_statechange: Device 'SIP/101' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. [Sep 23 11:47:50] DEBUG[859]: app_queue.c:1490 handle_statechange: Device 'SIP/101' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. <--- SIP read from UDP:192.168.17.4:5060 ---> SIP/2.0 200 OK To: ;tag=cf9d85834754482ci0 From: "Cordless 1" ;tag=as0da8de2e Call-ID: 0d642d503eb8e283036b65f8181d2d52@192.168.17.1:5060 CSeq: 103 BYE Via: SIP/2.0/UDP 192.168.17.1:5060;branch=z9hG4bK324caf1e Server: Linksys/PAP2T-5.1.6(LS) Content-Length: 0 <-------------> [Sep 23 11:47:50] DEBUG[840]: chan_sip.c:8521 parse_request: Header 0 [ 14]: SIP/2.0 200 OK [Sep 23 11:47:50] DEBUG[840]: chan_sip.c:8521 parse_request: Header 1 [ 54]: To: ;tag=cf9d85834754482ci0 [Sep 23 11:47:50] DEBUG[840]: chan_sip.c:8521 parse_request: Header 2 [ 56]: From: "Cordless 1" ;tag=as0da8de2e [Sep 23 11:47:50] DEBUG[840]: chan_sip.c:8521 parse_request: Header 3 [ 59]: Call-ID: 0d642d503eb8e283036b65f8181d2d52@192.168.17.1:5060 [Sep 23 11:47:50] DEBUG[840]: chan_sip.c:8521 parse_request: Header 4 [ 13]: CSeq: 103 BYE [Sep 23 11:47:50] DEBUG[840]: chan_sip.c:8521 parse_request: Header 5 [ 57]: Via: SIP/2.0/UDP 192.168.17.1:5060;branch=z9hG4bK324caf1e [Sep 23 11:47:50] DEBUG[840]: chan_sip.c:8521 parse_request: Header 6 [ 31]: Server: Linksys/PAP2T-5.1.6(LS) [Sep 23 11:47:50] DEBUG[840]: chan_sip.c:8521 parse_request: Header 7 [ 17]: Content-Length: 0 --- (8 headers 0 lines) --- [Sep 23 11:47:50] DEBUG[840]: chan_sip.c:8115 find_call: = Looking for Call ID: 0d642d503eb8e283036b65f8181d2d52@192.168.17.1:5060 (Checking To) --From tag as0da8de2e --To-tag cf9d85834754482ci0 [Sep 23 11:47:50] DEBUG[840]: chan_sip.c:3997 __sip_ack: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #168 [Sep 23 11:47:50] DEBUG[840]: chan_sip.c:4030 __sip_ack: Stopping retransmission on '0d642d503eb8e283036b65f8181d2d52@192.168.17.1:5060' of Request 103: Match Found [Sep 23 11:47:50] DEBUG[840]: chan_sip.c:6053 sip_destroy: Destroying SIP dialog 0d642d503eb8e283036b65f8181d2d52@192.168.17.1:5060 Really destroying SIP dialog '0d642d503eb8e283036b65f8181d2d52@192.168.17.1:5060' Method: INVITE [Sep 23 11:47:50] DEBUG[840]: rtp_engine.c:298 instance_destructor: Destroyed RTP instance '0x88a5c30' pbx*CLI>