<--- SIP read from UDP:192.168.17.141:5060 ---> INVITE sip:101@kerrfamily.org;user=phone SIP/2.0 Via: SIP/2.0/UDP 192.168.17.141:5060;branch=z9hG4bKfe446a9b4d1bdce63920d94b5d3f8614;rport From: "Cordless 1" ;tag=589782611 To: Call-ID: 4243978099@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 25 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 12:15:30] DEBUG[840]: chan_sip.c:8521 parse_request: Header 0 [ 48]: INVITE sip:101@kerrfamily.org;user=phone SIP/2.0 [Sep 23 12:15:30] DEBUG[840]: chan_sip.c:8521 parse_request: Header 1 [ 89]: Via: SIP/2.0/UDP 192.168.17.141:5060;branch=z9hG4bKfe446a9b4d1bdce63920d94b5d3f8614;rport [Sep 23 12:15:30] DEBUG[840]: chan_sip.c:8521 parse_request: Header 2 [ 57]: From: "Cordless 1" ;tag=589782611 [Sep 23 12:15:30] DEBUG[840]: chan_sip.c:8521 parse_request: Header 3 [ 39]: To: [Sep 23 12:15:30] DEBUG[840]: chan_sip.c:8521 parse_request: Header 4 [ 34]: Call-ID: 4243978099@192_168_17_141 [Sep 23 12:15:30] DEBUG[840]: chan_sip.c:8521 parse_request: Header 5 [ 14]: CSeq: 2 INVITE [Sep 23 12:15:30] DEBUG[840]: chan_sip.c:8521 parse_request: Header 6 [ 38]: Contact: [Sep 23 12:15:30] DEBUG[840]: chan_sip.c:8521 parse_request: Header 7 [ 16]: Max-Forwards: 70 [Sep 23 12:15:30] DEBUG[840]: chan_sip.c:8521 parse_request: Header 8 [ 38]: User-Agent: C610A IP/42.072.00.000.000 [Sep 23 12:15:30] DEBUG[840]: chan_sip.c:8521 parse_request: Header 9 [ 19]: Supported: replaces [Sep 23 12:15:30] DEBUG[840]: chan_sip.c:8521 parse_request: Header 10 [ 48]: Allow-Events: message-summary, refer, ua-profile [Sep 23 12:15:30] DEBUG[840]: chan_sip.c:8521 parse_request: Header 11 [ 72]: Allow: INVITE, ACK, CANCEL, BYE, OPTIONS, INFO, REFER, SUBSCRIBE, NOTIFY [Sep 23 12:15:30] DEBUG[840]: chan_sip.c:8521 parse_request: Header 12 [ 29]: Content-Type: application/sdp [Sep 23 12:15:30] DEBUG[840]: chan_sip.c:8521 parse_request: Header 13 [ 19]: Content-Length: 384 [Sep 23 12:15:30] DEBUG[840]: chan_sip.c:8521 parse_request: Header 14 [ 0]: [Sep 23 12:15:30] DEBUG[840]: chan_sip.c:8521 parse_request: Body 0 [ 3]: v=0 [Sep 23 12:15:30] DEBUG[840]: chan_sip.c:8521 parse_request: Body 1 [ 35]: o=111 5012 25 IN IP4 192.168.17.141 [Sep 23 12:15:30] DEBUG[840]: chan_sip.c:8521 parse_request: Body 2 [ 9]: s=Mapping [Sep 23 12:15:30] DEBUG[840]: chan_sip.c:8521 parse_request: Body 3 [ 23]: c=IN IP4 192.168.17.141 [Sep 23 12:15:30] DEBUG[840]: chan_sip.c:8521 parse_request: Body 4 [ 5]: t=0 0 [Sep 23 12:15:30] 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 12:15:30] DEBUG[840]: chan_sip.c:8521 parse_request: Body 6 [ 20]: a=rtpmap:9 G722/8000 [Sep 23 12:15:30] DEBUG[840]: chan_sip.c:8521 parse_request: Body 7 [ 20]: a=rtpmap:0 PCMU/8000 [Sep 23 12:15:30] DEBUG[840]: chan_sip.c:8521 parse_request: Body 8 [ 20]: a=rtpmap:8 PCMA/8000 [Sep 23 12:15:30] DEBUG[840]: chan_sip.c:8521 parse_request: Body 9 [ 24]: a=rtpmap:96 G726-32/8000 [Sep 23 12:15:30] DEBUG[840]: chan_sip.c:8521 parse_request: Body 10 [ 29]: a=rtpmap:97 AAL2-G726-32/8000 [Sep 23 12:15:30] DEBUG[840]: chan_sip.c:8521 parse_request: Body 11 [ 23]: a=rtpmap:2 G726-32/8000 [Sep 23 12:15:30] DEBUG[840]: chan_sip.c:8521 parse_request: Body 12 [ 21]: a=rtpmap:18 G729/8000 [Sep 23 12:15:30] DEBUG[840]: chan_sip.c:8521 parse_request: Body 13 [ 19]: a=fmtp:18 annexb=no [Sep 23 12:15:30] DEBUG[840]: chan_sip.c:8521 parse_request: Body 14 [ 33]: a=rtpmap:101 telephone-event/8000 [Sep 23 12:15:30] DEBUG[840]: chan_sip.c:8521 parse_request: Body 15 [ 15]: a=fmtp:101 0-16 [Sep 23 12:15:30] DEBUG[840]: chan_sip.c:8558 parse_request: Body 16 [ 10]: a=ptime:20 --- (14 headers 17 lines) --- [Sep 23 12:15:30] DEBUG[840]: chan_sip.c:8115 find_call: = Looking for Call ID: 4243978099@192_168_17_141 (Checking From) --From tag 589782611 --To-tag [Sep 23 12:15:30] DEBUG[840]: acl.c:736 ast_ouraddrfor: For destination '192.168.17.141', our source address is '192.168.17.1'. [Sep 23 12:15:30] DEBUG[840]: chan_sip.c:3498 ast_sip_ouraddrfor: Setting SIP_TRANSPORT_UDP with address 192.168.17.1:5060 [Sep 23 12:15:30] DEBUG[840]: chan_sip.c:7795 sip_alloc: Allocating new SIP dialog for 4243978099@192_168_17_141 - INVITE (No RTP) [Sep 23 12:15:30] DEBUG[840]: chan_sip.c:25254 handle_incoming: **** Received INVITE (5) - Command in SIP INVITE [Sep 23 12:15:30] DEBUG[840]: sip/reqresp_parser.c:1550 parse_sip_options: Begin: parsing SIP "Supported: replaces" [Sep 23 12:15:30] DEBUG[840]: sip/reqresp_parser.c:1566 parse_sip_options: Found SIP option: -replaces- [Sep 23 12:15:30] DEBUG[840]: sip/reqresp_parser.c:1574 parse_sip_options: Matched SIP option: replaces [Sep 23 12:15:30] DEBUG[840]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting '192.168.17.141:5060' into... [Sep 23 12:15:30] 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 12:15:30] DEBUG[840]: chan_sip.c:22643 handle_request_invite: Initializing initreq for method INVITE - callid 4243978099@192_168_17_141 Using INVITE request as basis request - 4243978099@192_168_17_141 [Sep 23 12:15:30] DEBUG[840]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting 'kerrfamily.org' into... [Sep 23 12:15:30] 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 12:15:30] DEBUG[840]: rtp_engine.c:350 ast_rtp_instance_new: Using engine 'asterisk' for RTP instance '0x8898e38' [Sep 23 12:15:30] DEBUG[840]: res_rtp_asterisk.c:556 ast_rtp_new: Allocated port 16598 for RTP instance '0x8898e38' [Sep 23 12:15:30] DEBUG[840]: rtp_engine.c:359 ast_rtp_instance_new: RTP instance '0x8898e38' is setup and ready to go [Sep 23 12:15:30] DEBUG[840]: res_rtp_asterisk.c:2523 ast_rtp_prop_set: Setup RTCP on RTP instance '0x8898e38' == Using SIP RTP CoS mark 5 [Sep 23 12:15:30] DEBUG[840]: chan_sip.c:5093 do_setnat: Setting NAT on RTP to On [Sep 23 12:15:30] DEBUG[840]: chan_sip.c:8908 process_sdp: Processing session-level SDP v=0... UNSUPPORTED OR FAILED. [Sep 23 12:15:30] DEBUG[840]: chan_sip.c:8908 process_sdp: Processing session-level SDP o=111 5012 25 IN IP4 192.168.17.141... UNSUPPORTED OR FAILED. [Sep 23 12:15:30] DEBUG[840]: chan_sip.c:8908 process_sdp: Processing session-level SDP s=Mapping... UNSUPPORTED OR FAILED. [Sep 23 12:15:30] DEBUG[840]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting '192.168.17.141' into... [Sep 23 12:15:30] DEBUG[840]: netsock2.c:192 ast_sockaddr_split_hostport: ...host '192.168.17.141' and port ''. [Sep 23 12:15:30] DEBUG[840]: chan_sip.c:8908 process_sdp: Processing session-level SDP c=IN IP4 192.168.17.141... OK. [Sep 23 12:15:30] 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 12:15:30] 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 12:15:30] 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 12:15:30] 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 12:15:30] 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 12:15:30] DEBUG[840]: rtp_engine.c:541 ast_rtp_codecs_payloads_set_m_type: Setting payload 18 based on m type on 0x41503e70 ound RTP audio format 101 [Sep 23 12:15:30] 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 12:15:30] 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 12:15:30] 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 12:15:30] 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 12:15:30] 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 12:15:30] 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 12:15:30] 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 12:15:30] DEBUG[840]: chan_sip.c:9179 process_sdp: Processing media-level (audio) SDP a=rtpmap:18 G729/8000... OK. [Sep 23 12:15:30] 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 12:15:30] DEBUG[840]: chan_sip.c:9179 process_sdp: Processing media-level (audio) SDP a=rtpmap:101 telephone-event/8000... OK. [Sep 23 12:15:30] DEBUG[840]: chan_sip.c:9179 process_sdp: Processing media-level (audio) SDP a=fmtp:101 0-16... UNSUPPORTED OR FAILED. [Sep 23 12:15:30] DEBUG[840]: chan_sip.c:9179 process_sdp: Processing media-level (audio) SDP a=ptime:20... OK. [Sep 23 12:15:30] DEBUG[840]: rtp_engine.c:644 ast_rtp_codecs_payload_formats: Incorporating payload 0 on 0x41503e70 [Sep 23 12:15:30] DEBUG[840]: rtp_engine.c:644 ast_rtp_codecs_payload_formats: Incorporating payload 2 on 0x41503e70 [Sep 23 12:15:30] DEBUG[840]: rtp_engine.c:644 ast_rtp_codecs_payload_formats: Incorporating payload 8 on 0x41503e70 [Sep 23 12:15:30] DEBUG[840]: rtp_engine.c:644 ast_rtp_codecs_payload_formats: Incorporating payload 9 on 0x41503e70 [Sep 23 12:15:30] DEBUG[840]: rtp_engine.c:644 ast_rtp_codecs_payload_formats: Incorporating payload 18 on 0x41503e70 [Sep 23 12:15:30] DEBUG[840]: rtp_engine.c:644 ast_rtp_codecs_payload_formats: Incorporating payload 96 on 0x41503e70 [Sep 23 12:15:30] DEBUG[840]: rtp_engine.c:644 ast_rtp_codecs_payload_formats: Incorporating payload 97 on 0x41503e70 [Sep 23 12:15:30] 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 12:15:30] DEBUG[840]: res_rtp_asterisk.c:2563 ast_rtp_remote_address_set: Setting RTCP address on RTP instance '0x8898e38' Peer audio RTP is at port 192.168.17.141:5012 [Sep 23 12:15:30] DEBUG[840]: rtp_engine.c:522 ast_rtp_codecs_payloads_copy: Copying payload 0 from 0x41503e70 to 0x8898fe4 [Sep 23 12:15:30] DEBUG[840]: rtp_engine.c:522 ast_rtp_codecs_payloads_copy: Copying payload 2 from 0x41503e70 to 0x8898fe4 [Sep 23 12:15:30] DEBUG[840]: rtp_engine.c:522 ast_rtp_codecs_payloads_copy: Copying payload 8 from 0x41503e70 to 0x8898fe4 [Sep 23 12:15:30] DEBUG[840]: rtp_engine.c:522 ast_rtp_codecs_payloads_copy: Copying payload 9 from 0x41503e70 to 0x8898fe4 [Sep 23 12:15:30] DEBUG[840]: rtp_engine.c:522 ast_rtp_codecs_payloads_copy: Copying payload 18 from 0x41503e70 to 0x8898fe4 [Sep 23 12:15:30] DEBUG[840]: rtp_engine.c:522 ast_rtp_codecs_payloads_copy: Copying payload 96 from 0x41503e70 to 0x8898fe4 [Sep 23 12:15:30] DEBUG[840]: rtp_engine.c:522 ast_rtp_codecs_payloads_copy: Copying payload 97 from 0x41503e70 to 0x8898fe4 [Sep 23 12:15:30] DEBUG[840]: rtp_engine.c:522 ast_rtp_codecs_payloads_copy: Copying payload 101 from 0x41503e70 to 0x8898fe4 [Sep 23 12:15:30] DEBUG[840]: res_rtp_asterisk.c:2489 ast_rtp_prop_set: Ignoring duplicate RTCP property on RTP instance '0x8898e38' [Sep 23 12:15:30] DEBUG[840]: chan_sip.c:9424 process_sdp: We're settling with these formats: 0x1104 (ulaw|g729|g722) [Sep 23 12:15:30] DEBUG[840]: chan_sip.c:22780 handle_request_invite: Checking SIP call limits for device 111 [Sep 23 12:15:30] DEBUG[840]: chan_sip.c:5905 update_call_counter: Updating call counter for incoming call [Sep 23 12:15:30] DEBUG[840]: chan_sip.c:6010 update_call_counter: Call from peer '111' is 1 out of 10 [Sep 23 12:15:30] DEBUG[840]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting 'kerrfamily.org' into... [Sep 23 12:15:30] DEBUG[840]: netsock2.c:192 ast_sockaddr_split_hostport: ...host 'kerrfamily.org' and port ''. [Sep 23 12:15:30] DEBUG[840]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting 'kerrfamily.org' into... [Sep 23 12:15:30] DEBUG[840]: netsock2.c:192 ast_sockaddr_split_hostport: ...host 'kerrfamily.org' and port ''. Looking for 101 in DialPlanSLA (domain kerrfamily.org) [Sep 23 12:15:30] DEBUG[840]: chan_sip.c:7091 sip_new: *** Our native formats are 0x1000 (g722) [Sep 23 12:15:30] DEBUG[840]: chan_sip.c:7092 sip_new: *** Joint capabilities are 0x1104 (ulaw|g729|g722) [Sep 23 12:15:30] DEBUG[840]: chan_sip.c:7093 sip_new: *** Our capabilities are 0x1104 (ulaw|g729|g722) [Sep 23 12:15:30] DEBUG[840]: chan_sip.c:7094 sip_new: *** AST_CODEC_CHOOSE formats are 0x1000 (g722) [Sep 23 12:15:30] DEBUG[840]: chan_sip.c:7124 sip_new: This channel will not be able to handle video. [Sep 23 12:15:30] DEBUG[840]: dsp.c:475 ast_tone_detect_init: Setup tone 1100 Hz, 500 ms, block_size=160, hits_required=21 [Sep 23 12:15:30] DEBUG[840]: dsp.c:475 ast_tone_detect_init: Setup tone 2100 Hz, 2600 ms, block_size=160, hits_required=116 [Sep 23 12:15:30] DEBUG[840]: chan_sip.c:14336 build_route: build_route: Contact hop: list_route: hop: [Sep 23 12:15:30] DEBUG[840]: chan_sip.c:23081 handle_request_invite: SIP/111-00000027: 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=z9hG4bKfe446a9b4d1bdce63920d94b5d3f8614;received=192.168.17.141;rport=5060 From: "Cordless 1" ;tag=589782611 To: Call-ID: 4243978099@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 12:15:30] 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 12:15:30] DEBUG[822]: devicestate.c:342 _ast_device_state: No provider found, checking channel drivers for SIP - 111 [Sep 23 12:15:30] DEBUG[822]: chan_sip.c:26559 sip_devicestate: Checking device state for peer 111 [Sep 23 12:15:30] DEBUG[822]: devicestate.c:460 do_state_change: Changing state for SIP/111 - state 2 (In use) [Sep 23 12:15:30] DEBUG[822]: devicestate.c:440 devstate_event: device 'SIP/111' state '2' [Sep 23 12:15:30] DEBUG[822]: devicestate.c:342 _ast_device_state: No provider found, checking channel drivers for SIP - 111 [Sep 23 12:15:30] DEBUG[822]: chan_sip.c:26559 sip_devicestate: Checking device state for peer 111 [Sep 23 12:15:30] DEBUG[822]: devicestate.c:460 do_state_change: Changing state for SIP/111 - state 2 (In use) [Sep 23 12:15:30] DEBUG[822]: devicestate.c:440 devstate_event: device 'SIP/111' state '2' [Sep 23 12:15:30] DEBUG[4137]: pbx.c:4247 pbx_extension_helper: Launching 'NoOp' -- Executing [101@DialPlanSLA:1] NoOp("SIP/111-00000027", "SLA Dialplan") in new stack [Sep 23 12:15:30] DEBUG[4137]: pbx.c:3256 ast_str_retrieve_variable: Result of 'EXTEN' is '101' [Sep 23 12:15:30] DEBUG[4137]: pbx.c:4247 pbx_extension_helper: Launching 'Set' -- Executing [101@DialPlanSLA:2] Set("SIP/111-00000027", "_SLA_EXTEN=101") in new stack [Sep 23 12:15:30] DEBUG[4137]: pbx.c:4075 pbx_substitute_variables_helper_full: Function result is '111' [Sep 23 12:15:30] DEBUG[4137]: pbx.c:4247 pbx_extension_helper: Launching 'SLAStation' -- Executing [101@DialPlanSLA:3] SLAStation("SIP/111-00000027", "SLAphone111") in new stack [Sep 23 12:15:30] DEBUG[4137]: devicestate.c:440 devstate_event: device 'SLA:SLAphone111_SLAtrunk1' state '2' [Sep 23 12:15:30] DEBUG[4137]: devicestate.c:440 devstate_event: device 'SLA:SLAphone112_SLAtrunk1' state '2' [Sep 23 12:15:30] DEBUG[4138]: channel.c:6141 ast_channel_inherit_variables: Copying soft-transferable variable SLA_EXTEN. [Sep 23 12:15:30] DEBUG[4138]: channel.c:6152 ast_channel_inherit_variables: Not copying variable SIPCALLID. [Sep 23 12:15:30] DEBUG[4138]: channel.c:6152 ast_channel_inherit_variables: Not copying variable SIPDOMAIN. [Sep 23 12:15:30] DEBUG[4138]: channel.c:6152 ast_channel_inherit_variables: Not copying variable SIPURI. -- Called s@OutboundSLA [Sep 23 12:15:30] 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 12:15:30] 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 12:15:30] 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 12:15:30] 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 12:15:30] 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 12:15:30] 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 12:15:30] DEBUG[4139]: pbx.c:4247 pbx_extension_helper: Launching 'NoOp' -- Executing [s@OutboundSLA:1] NoOp("Local/s@OutboundSLA-b2f8;2", "SLA Outbound context") in new stack [Sep 23 12:15:30] DEBUG[4139]: pbx.c:3256 ast_str_retrieve_variable: Result of 'SLA_EXTEN' is '101' [Sep 23 12:15:30] DEBUG[4139]: pbx.c:4143 pbx_substitute_variables_helper_full: Expression result is '1' [Sep 23 12:15:30] DEBUG[4139]: pbx.c:3256 ast_str_retrieve_variable: Result of 'SLA_EXTEN' is '101' [Sep 23 12:15:30] DEBUG[4139]: pbx.c:4247 pbx_extension_helper: Launching 'GotoIf' -- Executing [s@OutboundSLA:2] GotoIf("Local/s@OutboundSLA-b2f8;2", "1?101,1") in new stack -- Goto (OutboundSLA,101,1) [Sep 23 12:15:30] DEBUG[4139]: pbx.c:3256 ast_str_retrieve_variable: Result of 'EXTEN' is '101' [Sep 23 12:15:30] DEBUG[4139]: pbx.c:3256 ast_str_retrieve_variable: Result of 'RINGTIME' is '20' [Sep 23 12:15:30] DEBUG[4139]: pbx.c:4247 pbx_extension_helper: Launching 'Dial' -- Executing [101@OutboundSLA:1] Dial("Local/s@OutboundSLA-b2f8;2", "SIP/101,20,t") in new stack [Sep 23 12:15:30] DEBUG[4139]: chan_sip.c:26659 sip_request_call: Asked to create a SIP channel with formats: 0x1000 (g722) [Sep 23 12:15:30] DEBUG[4139]: chan_sip.c:7795 sip_alloc: Allocating new SIP dialog for 3301e30e37ddf7222c702c007df33717@192.168.17.1:5060 - INVITE (No RTP) [Sep 23 12:15:30] DEBUG[4139]: rtp_engine.c:350 ast_rtp_instance_new: Using engine 'asterisk' for RTP instance '0x88bc528' [Sep 23 12:15:30] DEBUG[4139]: res_rtp_asterisk.c:556 ast_rtp_new: Allocated port 16538 for RTP instance '0x88bc528' [Sep 23 12:15:30] DEBUG[4139]: rtp_engine.c:359 ast_rtp_instance_new: RTP instance '0x88bc528' is setup and ready to go [Sep 23 12:15:30] DEBUG[4139]: res_rtp_asterisk.c:2523 ast_rtp_prop_set: Setup RTCP on RTP instance '0x88bc528' == Using SIP RTP CoS mark 5 [Sep 23 12:15:30] DEBUG[4139]: chan_sip.c:5093 do_setnat: Setting NAT on RTP to On [Sep 23 12:15:30] DEBUG[4139]: chan_sip.c:3146 obproxy_get: OBPROXY: Not applying OBproxy to this call [Sep 23 12:15:30] DEBUG[4139]: acl.c:736 ast_ouraddrfor: For destination '192.168.17.4', our source address is '192.168.17.1'. [Sep 23 12:15:30] DEBUG[4139]: chan_sip.c:3498 ast_sip_ouraddrfor: Setting SIP_TRANSPORT_UDP with address 192.168.17.1:5060 [Sep 23 12:15:30] DEBUG[4139]: chan_sip.c:7091 sip_new: *** Our native formats are 0x4 (ulaw) [Sep 23 12:15:30] DEBUG[4139]: chan_sip.c:7092 sip_new: *** Joint capabilities are 0x0 (nothing) [Sep 23 12:15:30] DEBUG[4139]: chan_sip.c:7093 sip_new: *** Our capabilities are 0x4 (ulaw) [Sep 23 12:15:30] DEBUG[4139]: chan_sip.c:7094 sip_new: *** AST_CODEC_CHOOSE formats are 0x4 (ulaw) [Sep 23 12:15:30] DEBUG[4139]: chan_sip.c:7096 sip_new: *** Our preferred formats from the incoming channel are 0x1000 (g722) [Sep 23 12:15:30] DEBUG[4139]: chan_sip.c:7124 sip_new: This channel will not be able to handle video. [Sep 23 12:15:30] DEBUG[4139]: dsp.c:475 ast_tone_detect_init: Setup tone 1100 Hz, 500 ms, block_size=160, hits_required=21 [Sep 23 12:15:30] DEBUG[4139]: dsp.c:475 ast_tone_detect_init: Setup tone 2100 Hz, 2600 ms, block_size=160, hits_required=116 [Sep 23 12:15:30] DEBUG[4139]: rtp_engine.c:1412 ast_rtp_instance_early_bridge_make_compatible: Can't find native functions for channel 'Local/s@OutboundSLA-b2f8;2' [Sep 23 12:15:30] DEBUG[4139]: rtp_engine.c:1473 ast_rtp_instance_early_bridge_make_compatible: Seeded SDP of 'SIP/101-00000028' with that of 'Local/s@OutboundSLA-b2f8;2' [Sep 23 12:15:30] DEBUG[4139]: channel.c:6152 ast_channel_inherit_variables: Not copying variable DIALEDTIME. [Sep 23 12:15:30] DEBUG[4139]: channel.c:6152 ast_channel_inherit_variables: Not copying variable ANSWEREDTIME. [Sep 23 12:15:30] DEBUG[4139]: channel.c:6152 ast_channel_inherit_variables: Not copying variable DIALEDPEERNAME. [Sep 23 12:15:30] DEBUG[4139]: channel.c:6152 ast_channel_inherit_variables: Not copying variable DIALEDPEERNUMBER. [Sep 23 12:15:30] DEBUG[4139]: channel.c:6152 ast_channel_inherit_variables: Not copying variable DIALSTATUS. [Sep 23 12:15:30] DEBUG[4139]: channel.c:6152 ast_channel_inherit_variables: Not copying variable SLA_EXTEN. [Sep 23 12:15:30] DEBUG[4139]: chan_sip.c:5638 sip_call: Outgoing Call for 101 [Sep 23 12:15:30] DEBUG[4139]: chan_sip.c:5905 update_call_counter: Updating call counter for outgoing call [Sep 23 12:15:30] DEBUG[4139]: chan_sip.c:6010 update_call_counter: Call to peer '101' is 1 out of 10 [Sep 23 12:15:30] DEBUG[4139]: chan_sip.c:11413 add_sdp: ** Our capability: 0x4 (ulaw) Video flag: False Text flag: False [Sep 23 12:15:30] DEBUG[4139]: chan_sip.c:11414 add_sdp: ** Our prefcodec: 0x1000 (g722) Audio is at 16538 Adding codec 0x4 (ulaw) to SDP Adding non-codec 0x1 (telephone-event) to SDP [Sep 23 12:15:30] DEBUG[4139]: chan_sip.c:11523 add_sdp: -- Done with adding codecs to SDP [Sep 23 12:15:30] DEBUG[4139]: chan_sip.c:11709 add_sdp: Done building SDP. Settling with this capability: 0x4 (ulaw) [Sep 23 12:15:30] DEBUG[4139]: chan_sip.c:3075 initialize_initreq: Initializing initreq for method INVITE - callid 63b7ae5b176acb524d0538b7358a2eb3@192.168.17.1:5060 [Sep 23 12:15:30] DEBUG[4139]: chan_sip.c:8521 parse_request: Header 0 [ 40]: INVITE sip:101@192.168.17.4:5060 SIP/2.0 [Sep 23 12:15:30] DEBUG[4139]: chan_sip.c:8521 parse_request: Header 1 [ 63]: Via: SIP/2.0/UDP 192.168.17.1:5060;branch=z9hG4bK16778c30;rport [Sep 23 12:15:30] DEBUG[4139]: chan_sip.c:8521 parse_request: Header 2 [ 16]: Max-Forwards: 70 [Sep 23 12:15:30] DEBUG[4139]: chan_sip.c:8521 parse_request: Header 3 [ 56]: From: "Cordless 1" ;tag=as240543e8 [Sep 23 12:15:30] DEBUG[4139]: chan_sip.c:8521 parse_request: Header 4 [ 31]: To: [Sep 23 12:15:30] DEBUG[4139]: chan_sip.c:8521 parse_request: Header 5 [ 36]: Contact: [Sep 23 12:15:30] DEBUG[4139]: chan_sip.c:8521 parse_request: Header 6 [ 59]: Call-ID: 63b7ae5b176acb524d0538b7358a2eb3@192.168.17.1:5060 [Sep 23 12:15:30] DEBUG[4139]: chan_sip.c:8521 parse_request: Header 7 [ 16]: CSeq: 102 INVITE [Sep 23 12:15:30] DEBUG[4139]: chan_sip.c:8521 parse_request: Header 8 [ 33]: User-Agent: Asterisk PBX 1.8.15.0 [Sep 23 12:15:30] DEBUG[4139]: chan_sip.c:8521 parse_request: Header 9 [ 35]: Date: Sun, 23 Sep 2012 16:15:30 GMT [Sep 23 12:15:30] DEBUG[4139]: chan_sip.c:8521 parse_request: Header 10 [ 81]: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH [Sep 23 12:15:30] DEBUG[4139]: chan_sip.c:8521 parse_request: Header 11 [ 26]: Supported: replaces, timer [Sep 23 12:15:30] DEBUG[4139]: 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=z9hG4bK16778c30;rport Max-Forwards: 70 From: "Cordless 1" ;tag=as240543e8 To: Contact: Call-ID: 63b7ae5b176acb524d0538b7358a2eb3@192.168.17.1:5060 CSeq: 102 INVITE User-Agent: Asterisk PBX 1.8.15.0 Date: Sun, 23 Sep 2012 16:15:30 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH Supported: replaces, timer Content-Type: application/sdp Content-Length: 235 v=0 o=root 465494217 465494217 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 16538 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 12:15:30] DEBUG[4139]: chan_sip.c:3801 __sip_reliable_xmit: *** SIP TIMER: Initializing retransmit timer on packet: Id #868 [Sep 23 12:15:30] DEBUG[4139]: 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 12:15:30] DEBUG[4139]: channel.c:5175 set_format: Set channel SIP/101-00000028 to read format slin [Sep 23 12:15:30] DEBUG[4139]: channel.c:5175 set_format: Set channel Local/s@OutboundSLA-b2f8;2 to write format slin [Sep 23 12:15:30] DEBUG[4139]: channel.c:5175 set_format: Set channel Local/s@OutboundSLA-b2f8;2 to read format slin [Sep 23 12:15:30] DEBUG[4139]: channel.c:5175 set_format: Set channel SIP/101-00000028 to write format slin <--- SIP read from UDP:192.168.17.4:5060 ---> SIP/2.0 100 Trying To: From: "Cordless 1" ;tag=as240543e8 Call-ID: 63b7ae5b176acb524d0538b7358a2eb3@192.168.17.1:5060 CSeq: 102 INVITE Via: SIP/2.0/UDP 192.168.17.1:5060;branch=z9hG4bK16778c30 Server: Linksys/PAP2T-5.1.6(LS) Content-Length: 0 <-------------> [Sep 23 12:15:30] DEBUG[840]: chan_sip.c:8521 parse_request: Header 0 [ 18]: SIP/2.0 100 Trying [Sep 23 12:15:30] DEBUG[840]: chan_sip.c:8521 parse_request: Header 1 [ 31]: To: [Sep 23 12:15:30] DEBUG[840]: chan_sip.c:8521 parse_request: Header 2 [ 56]: From: "Cordless 1" ;tag=as240543e8 [Sep 23 12:15:30] DEBUG[840]: chan_sip.c:8521 parse_request: Header 3 [ 59]: Call-ID: 63b7ae5b176acb524d0538b7358a2eb3@192.168.17.1:5060 [Sep 23 12:15:30] DEBUG[840]: chan_sip.c:8521 parse_request: Header 4 [ 16]: CSeq: 102 INVITE [Sep 23 12:15:30] DEBUG[840]: chan_sip.c:8521 parse_request: Header 5 [ 57]: Via: SIP/2.0/UDP 192.168.17.1:5060;branch=z9hG4bK16778c30 [Sep 23 12:15:30] DEBUG[840]: chan_sip.c:8521 parse_request: Header 6 [ 31]: Server: Linksys/PAP2T-5.1.6(LS) [Sep 23 12:15:30] DEBUG[840]: chan_sip.c:8521 parse_request: Header 7 [ 17]: Content-Length: 0 --- (8 headers 0 lines) --- [Sep 23 12:15:30] DEBUG[840]: chan_sip.c:8115 find_call: = Looking for Call ID: 63b7ae5b176acb524d0538b7358a2eb3@192.168.17.1:5060 (Checking To) --From tag as240543e8 --To-tag [Sep 23 12:15:30] DEBUG[840]: chan_sip.c:4064 __sip_semi_ack: *** SIP TIMER: Cancelling retransmission #868 - INVITE (got response) [Sep 23 12:15:30] DEBUG[840]: chan_sip.c:4071 __sip_semi_ack: (Provisional) Stopping retransmission (but retaining packet) on '63b7ae5b176acb524d0538b7358a2eb3@192.168.17.1:5060' Request 102: Found [Sep 23 12:15:30] DEBUG[840]: chan_sip.c:20102 handle_response_invite: SIP response 100 to standard invite <--- SIP read from UDP:192.168.17.4:5060 ---> SIP/2.0 180 Ringing To: ;tag=7bc54c90c4307330i0 From: "Cordless 1" ;tag=as240543e8 Call-ID: 63b7ae5b176acb524d0538b7358a2eb3@192.168.17.1:5060 CSeq: 102 INVITE Via: SIP/2.0/UDP 192.168.17.1:5060;branch=z9hG4bK16778c30 Server: Linksys/PAP2T-5.1.6(LS) Content-Length: 0 <-------------> [Sep 23 12:15:30] DEBUG[840]: chan_sip.c:8521 parse_request: Header 0 [ 19]: SIP/2.0 180 Ringing [Sep 23 12:15:30] DEBUG[840]: chan_sip.c:8521 parse_request: Header 1 [ 54]: To: ;tag=7bc54c90c4307330i0 [Sep 23 12:15:30] DEBUG[840]: chan_sip.c:8521 parse_request: Header 2 [ 56]: From: "Cordless 1" ;tag=as240543e8 [Sep 23 12:15:30] DEBUG[840]: chan_sip.c:8521 parse_request: Header 3 [ 59]: Call-ID: 63b7ae5b176acb524d0538b7358a2eb3@192.168.17.1:5060 [Sep 23 12:15:30] DEBUG[840]: chan_sip.c:8521 parse_request: Header 4 [ 16]: CSeq: 102 INVITE [Sep 23 12:15:30] DEBUG[840]: chan_sip.c:8521 parse_request: Header 5 [ 57]: Via: SIP/2.0/UDP 192.168.17.1:5060;branch=z9hG4bK16778c30 [Sep 23 12:15:30] DEBUG[840]: chan_sip.c:8521 parse_request: Header 6 [ 31]: Server: Linksys/PAP2T-5.1.6(LS) [Sep 23 12:15:30] DEBUG[840]: chan_sip.c:8521 parse_request: Header 7 [ 17]: Content-Length: 0 --- (8 headers 0 lines) --- [Sep 23 12:15:30] DEBUG[840]: chan_sip.c:8115 find_call: = Looking for Call ID: 63b7ae5b176acb524d0538b7358a2eb3@192.168.17.1:5060 (Checking To) --From tag as240543e8 --To-tag 7bc54c90c4307330i0 [Sep 23 12:15:30] DEBUG[840]: chan_sip.c:4071 __sip_semi_ack: (Provisional) Stopping retransmission (but retaining packet) on '63b7ae5b176acb524d0538b7358a2eb3@192.168.17.1:5060' Request 102: Found [Sep 23 12:15:30] DEBUG[840]: chan_sip.c:20102 handle_response_invite: SIP response 180 to standard invite list_route: no route [Sep 23 12:15:30] DEBUG[822]: devicestate.c:342 _ast_device_state: No provider found, checking channel drivers for SIP - 101 [Sep 23 12:15:30] DEBUG[822]: chan_sip.c:26559 sip_devicestate: Checking device state for peer 101 [Sep 23 12:15:30] DEBUG[822]: devicestate.c:460 do_state_change: Changing state for SIP/101 - state 6 (Ringing) [Sep 23 12:15:30] DEBUG[822]: devicestate.c:440 devstate_event: device 'SIP/101' state '6' [Sep 23 12:15:30] DEBUG[822]: devicestate.c:342 _ast_device_state: No provider found, checking channel drivers for SIP - 101 [Sep 23 12:15:30] DEBUG[822]: chan_sip.c:26559 sip_devicestate: Checking device state for peer 101 [Sep 23 12:15:30] DEBUG[822]: devicestate.c:460 do_state_change: Changing state for SIP/101 - state 6 (Ringing) [Sep 23 12:15:30] DEBUG[822]: devicestate.c:440 devstate_event: device 'SIP/101' state '6' -- SIP/101-00000028 is ringing [Sep 23 12:15:31] DEBUG[822]: devicestate.c:342 _ast_device_state: No provider found, checking channel drivers for Local - s@OutboundSLA [Sep 23 12:15:31] DEBUG[822]: chan_local.c:300 local_devicestate: Checking if extension s@OutboundSLA exists (devicestate) [Sep 23 12:15:31] DEBUG[822]: devicestate.c:460 do_state_change: Changing state for Local/s@OutboundSLA - state 2 (In use) [Sep 23 12:15:31] DEBUG[822]: devicestate.c:440 devstate_event: device 'Local/s@OutboundSLA' state '2' -- Local/s@OutboundSLA-b2f8;1 is ringing [Sep 23 12:15:31] 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 12:15:31] 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 12:15:31] 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 12:15:38] DEBUG[840]: chan_sip.c:7795 sip_alloc: Allocating new SIP dialog for 0ca80c59266eff591e8998f2285f3c82@192.168.17.1:5060 - OPTIONS (No RTP) [Sep 23 12:15:38] DEBUG[840]: acl.c:736 ast_ouraddrfor: For destination '192.168.17.141', our source address is '192.168.17.1'. [Sep 23 12:15:38] DEBUG[840]: chan_sip.c:3498 ast_sip_ouraddrfor: Setting SIP_TRANSPORT_UDP with address 192.168.17.1:5060 [Sep 23 12:15:38] DEBUG[840]: chan_sip.c:3075 initialize_initreq: Initializing initreq for method OPTIONS - callid 05ec90cc5b91eeda7874e8b138f2e530@192.168.17.1:5060 [Sep 23 12:15:38] DEBUG[840]: chan_sip.c:8521 parse_request: Header 0 [ 43]: OPTIONS sip:111@192.168.17.141:5060 SIP/2.0 [Sep 23 12:15:38] DEBUG[840]: chan_sip.c:8521 parse_request: Header 1 [ 63]: Via: SIP/2.0/UDP 192.168.17.1:5060;branch=z9hG4bK3f6850d4;rport [Sep 23 12:15:38] DEBUG[840]: chan_sip.c:8521 parse_request: Header 2 [ 16]: Max-Forwards: 70 [Sep 23 12:15:38] DEBUG[840]: chan_sip.c:8521 parse_request: Header 3 [ 59]: From: "asterisk" ;tag=as626127dc [Sep 23 12:15:38] DEBUG[840]: chan_sip.c:8521 parse_request: Header 4 [ 33]: To: [Sep 23 12:15:38] DEBUG[840]: chan_sip.c:8521 parse_request: Header 5 [ 41]: Contact: [Sep 23 12:15:38] DEBUG[840]: chan_sip.c:8521 parse_request: Header 6 [ 59]: Call-ID: 05ec90cc5b91eeda7874e8b138f2e530@192.168.17.1:5060 [Sep 23 12:15:38] DEBUG[840]: chan_sip.c:8521 parse_request: Header 7 [ 17]: CSeq: 102 OPTIONS [Sep 23 12:15:38] DEBUG[840]: chan_sip.c:8521 parse_request: Header 8 [ 33]: User-Agent: Asterisk PBX 1.8.15.0 [Sep 23 12:15:38] DEBUG[840]: chan_sip.c:8521 parse_request: Header 9 [ 35]: Date: Sun, 23 Sep 2012 16:15:38 GMT [Sep 23 12:15:38] DEBUG[840]: chan_sip.c:8521 parse_request: Header 10 [ 81]: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH [Sep 23 12:15:38] 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=z9hG4bK3f6850d4;rport Max-Forwards: 70 From: "asterisk" ;tag=as626127dc To: Contact: Call-ID: 05ec90cc5b91eeda7874e8b138f2e530@192.168.17.1:5060 CSeq: 102 OPTIONS User-Agent: Asterisk PBX 1.8.15.0 Date: Sun, 23 Sep 2012 16:15:38 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH Supported: replaces, timer Content-Length: 0 --- [Sep 23 12:15:38] DEBUG[840]: chan_sip.c:3801 __sip_reliable_xmit: *** SIP TIMER: Initializing retransmit timer on packet: Id #870 [Sep 23 12:15:38] DEBUG[840]: chan_sip.c:3346 __sip_xmit: Trying to put 'OPTIONS sip' onto UDP socket destined for 192.168.17.141:5060 <--- 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=z9hG4bK3f6850d4;rport=5060 From: "asterisk" ;tag=as626127dc To: Call-ID: 05ec90cc5b91eeda7874e8b138f2e530@192.168.17.1:5060 CSeq: 102 OPTIONS User-Agent: C610A IP/42.072.00.000.000 Content-Length: 0 <-------------> [Sep 23 12:15:38] DEBUG[840]: chan_sip.c:8521 parse_request: Header 0 [ 21]: SIP/2.0 486 Busy Here [Sep 23 12:15:38] DEBUG[840]: chan_sip.c:8521 parse_request: Header 1 [ 68]: Via: SIP/2.0/UDP 192.168.17.1:5060;branch=z9hG4bK3f6850d4;rport=5060 [Sep 23 12:15:38] DEBUG[840]: chan_sip.c:8521 parse_request: Header 2 [ 59]: From: "asterisk" ;tag=as626127dc [Sep 23 12:15:38] DEBUG[840]: chan_sip.c:8521 parse_request: Header 3 [ 33]: To: [Sep 23 12:15:38] DEBUG[840]: chan_sip.c:8521 parse_request: Header 4 [ 59]: Call-ID: 05ec90cc5b91eeda7874e8b138f2e530@192.168.17.1:5060 [Sep 23 12:15:38] DEBUG[840]: chan_sip.c:8521 parse_request: Header 5 [ 17]: CSeq: 102 OPTIONS [Sep 23 12:15:38] DEBUG[840]: chan_sip.c:8521 parse_request: Header 6 [ 38]: User-Agent: C610A IP/42.072.00.000.000 [Sep 23 12:15:38] DEBUG[840]: chan_sip.c:8521 parse_request: Header 7 [ 17]: Content-Length: 0 --- (8 headers 0 lines) --- [Sep 23 12:15:38] DEBUG[840]: chan_sip.c:8115 find_call: = Looking for Call ID: 05ec90cc5b91eeda7874e8b138f2e530@192.168.17.1:5060 (Checking To) --From tag as626127dc --To-tag [Sep 23 12:15:38] DEBUG[840]: chan_sip.c:3997 __sip_ack: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #870 [Sep 23 12:15:38] DEBUG[840]: chan_sip.c:4030 __sip_ack: Stopping retransmission on '05ec90cc5b91eeda7874e8b138f2e530@192.168.17.1:5060' of Request 102: Match Found [Sep 23 12:15:38] DEBUG[840]: chan_sip.c:6053 sip_destroy: Destroying SIP dialog 05ec90cc5b91eeda7874e8b138f2e530@192.168.17.1:5060 Really destroying SIP dialog '05ec90cc5b91eeda7874e8b138f2e530@192.168.17.1:5060' Method: OPTIONS <--- SIP read from UDP:192.168.17.141:5060 ---> CANCEL sip:101@kerrfamily.org;user=phone SIP/2.0 Via: SIP/2.0/UDP 192.168.17.141:5060;branch=z9hG4bKfe446a9b4d1bdce63920d94b5d3f8614;rport From: "Cordless 1" ;tag=589782611 To: Call-ID: 4243978099@192_168_17_141 CSeq: 2 CANCEL Contact: Max-Forwards: 70 User-Agent: C610A IP/42.072.00.000.000 Content-Length: 0 <-------------> [Sep 23 12:15:39] DEBUG[840]: chan_sip.c:8521 parse_request: Header 0 [ 48]: CANCEL sip:101@kerrfamily.org;user=phone SIP/2.0 [Sep 23 12:15:39] DEBUG[840]: chan_sip.c:8521 parse_request: Header 1 [ 89]: Via: SIP/2.0/UDP 192.168.17.141:5060;branch=z9hG4bKfe446a9b4d1bdce63920d94b5d3f8614;rport [Sep 23 12:15:39] DEBUG[840]: chan_sip.c:8521 parse_request: Header 2 [ 57]: From: "Cordless 1" ;tag=589782611 [Sep 23 12:15:39] DEBUG[840]: chan_sip.c:8521 parse_request: Header 3 [ 39]: To: [Sep 23 12:15:39] DEBUG[840]: chan_sip.c:8521 parse_request: Header 4 [ 34]: Call-ID: 4243978099@192_168_17_141 [Sep 23 12:15:39] DEBUG[840]: chan_sip.c:8521 parse_request: Header 5 [ 14]: CSeq: 2 CANCEL [Sep 23 12:15:39] DEBUG[840]: chan_sip.c:8521 parse_request: Header 6 [ 38]: Contact: [Sep 23 12:15:39] DEBUG[840]: chan_sip.c:8521 parse_request: Header 7 [ 16]: Max-Forwards: 70 [Sep 23 12:15:39] DEBUG[840]: chan_sip.c:8521 parse_request: Header 8 [ 38]: User-Agent: C610A IP/42.072.00.000.000 [Sep 23 12:15:39] DEBUG[840]: chan_sip.c:8521 parse_request: Header 9 [ 17]: Content-Length: 0 --- (10 headers 0 lines) --- [Sep 23 12:15:39] DEBUG[840]: chan_sip.c:8115 find_call: = Looking for Call ID: 4243978099@192_168_17_141 (Checking From) --From tag 589782611 --To-tag [Sep 23 12:15:39] DEBUG[840]: chan_sip.c:25254 handle_incoming: **** Received CANCEL (14) - Command in SIP CANCEL [Sep 23 12:15:39] DEBUG[840]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting '192.168.17.141:5060' into... [Sep 23 12:15:39] 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 12:15:39] DEBUG[840]: chan_sip.c:3088 sip_alreadygone: Setting SIP_ALREADYGONE on dialog 4243978099@192_168_17_141 [Sep 23 12:15:39] DEBUG[840]: chan_sip.c:5905 update_call_counter: Updating call counter for incoming call [Sep 23 12:15:39] DEBUG[840]: chan_sip.c:5976 update_call_counter: Call from peer '111' removed from call limit 10 [Sep 23 12:15:39] DEBUG[840]: res_rtp_asterisk.c:2563 ast_rtp_remote_address_set: Setting RTCP address on RTP instance '0x8898e38' <--- Reliably Transmitting (NAT) to 192.168.17.141:5060 ---> SIP/2.0 487 Request Terminated Via: SIP/2.0/UDP 192.168.17.141:5060;branch=z9hG4bKfe446a9b4d1bdce63920d94b5d3f8614;received=192.168.17.141;rport=5060 From: "Cordless 1" ;tag=589782611 To: ;tag=as50edf124 Call-ID: 4243978099@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 Content-Length: 0 <------------> [Sep 23 12:15:39] DEBUG[840]: chan_sip.c:3801 __sip_reliable_xmit: *** SIP TIMER: Initializing retransmit timer on packet: Id #873 [Sep 23 12:15:39] DEBUG[840]: chan_sip.c:3346 __sip_xmit: Trying to put 'SIP/2.0 487' 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=z9hG4bKfe446a9b4d1bdce63920d94b5d3f8614;received=192.168.17.141;rport=5060 From: "Cordless 1" ;tag=589782611 To: ;tag=as50edf124 Call-ID: 4243978099@192_168_17_141 CSeq: 2 CANCEL 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 12:15:39] 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 12:15:39] DEBUG[822]: devicestate.c:342 _ast_device_state: No provider found, checking channel drivers for SIP - 111 [Sep 23 12:15:39] DEBUG[822]: chan_sip.c:26559 sip_devicestate: Checking device state for peer 111 [Sep 23 12:15:39] DEBUG[822]: devicestate.c:460 do_state_change: Changing state for SIP/111 - state 1 (Not in use) [Sep 23 12:15:39] DEBUG[822]: devicestate.c:440 devstate_event: device 'SIP/111' state '1' <--- SIP read from UDP:192.168.17.141:5060 ---> ACK sip:101@kerrfamily.org;user=phone SIP/2.0 Via: SIP/2.0/UDP 192.168.17.141:5060;branch=z9hG4bKfe446a9b4d1bdce63920d94b5d3f8614;rport From: "Cordless 1" ;tag=589782611 To: ;tag=as50edf124 Call-ID: 4243978099@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 12:15:39] DEBUG[840]: chan_sip.c:8521 parse_request: Header 0 [ 45]: ACK sip:101@kerrfamily.org;user=phone SIP/2.0 [Sep 23 12:15:39] DEBUG[840]: chan_sip.c:8521 parse_request: Header 1 [ 89]: Via: SIP/2.0/UDP 192.168.17.141:5060;branch=z9hG4bKfe446a9b4d1bdce63920d94b5d3f8614;rport [Sep 23 12:15:39] DEBUG[840]: chan_sip.c:8521 parse_request: Header 2 [ 57]: From: "Cordless 1" ;tag=589782611 [Sep 23 12:15:39] DEBUG[840]: chan_sip.c:8521 parse_request: Header 3 [ 54]: To: ;tag=as50edf124 [Sep 23 12:15:39] DEBUG[840]: chan_sip.c:8521 parse_request: Header 4 [ 34]: Call-ID: 4243978099@192_168_17_141 [Sep 23 12:15:39] DEBUG[840]: chan_sip.c:8521 parse_request: Header 5 [ 11]: CSeq: 2 ACK [Sep 23 12:15:39] DEBUG[840]: chan_sip.c:8521 parse_request: Header 6 [ 38]: Contact: [Sep 23 12:15:39] DEBUG[840]: chan_sip.c:8521 parse_request: Header 7 [ 16]: Max-Forwards: 70 [Sep 23 12:15:39] DEBUG[840]: chan_sip.c:8521 parse_request: Header 8 [ 38]: User-Agent: C610A IP/42.072.00.000.000 [Sep 23 12:15:39] DEBUG[840]: chan_sip.c:8521 parse_request: Header 9 [ 17]: Content-Length: 0 --- (10 headers 0 lines) --- [Sep 23 12:15:39] DEBUG[840]: chan_sip.c:8115 find_call: = Looking for Call ID: 4243978099@192_168_17_141 (Checking From) --From tag 589782611 --To-tag as50edf124 [Sep 23 12:15:39] DEBUG[840]: chan_sip.c:25254 handle_incoming: **** Received ACK (6) - Command in SIP ACK [Sep 23 12:15:39] DEBUG[840]: chan_sip.c:3997 __sip_ack: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #873 [Sep 23 12:15:39] DEBUG[840]: chan_sip.c:4030 __sip_ack: Stopping retransmission on '4243978099@192_168_17_141' of Response 2: Match Found [Sep 23 12:15:39] 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. <--- SIP read from UDP:192.168.17.4:5060 ---> SIP/2.0 200 OK To: ;tag=7bc54c90c4307330i0 From: "Cordless 1" ;tag=as240543e8 Call-ID: 63b7ae5b176acb524d0538b7358a2eb3@192.168.17.1:5060 CSeq: 102 INVITE Via: SIP/2.0/UDP 192.168.17.1:5060;branch=z9hG4bK16778c30 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=- 697345 697345 IN IP4 192.168.17.4 s=- c=IN IP4 192.168.17.4 t=0 0 m=audio 16390 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 12:15:46] DEBUG[840]: chan_sip.c:8521 parse_request: Header 0 [ 14]: SIP/2.0 200 OK [Sep 23 12:15:46] DEBUG[840]: chan_sip.c:8521 parse_request: Header 1 [ 54]: To: ;tag=7bc54c90c4307330i0 [Sep 23 12:15:46] DEBUG[840]: chan_sip.c:8521 parse_request: Header 2 [ 56]: From: "Cordless 1" ;tag=as240543e8 [Sep 23 12:15:46] DEBUG[840]: chan_sip.c:8521 parse_request: Header 3 [ 59]: Call-ID: 63b7ae5b176acb524d0538b7358a2eb3@192.168.17.1:5060 [Sep 23 12:15:46] DEBUG[840]: chan_sip.c:8521 parse_request: Header 4 [ 16]: CSeq: 102 INVITE [Sep 23 12:15:46] DEBUG[840]: chan_sip.c:8521 parse_request: Header 5 [ 57]: Via: SIP/2.0/UDP 192.168.17.1:5060;branch=z9hG4bK16778c30 [Sep 23 12:15:46] DEBUG[840]: chan_sip.c:8521 parse_request: Header 6 [ 43]: Contact: Line 1 [Sep 23 12:15:46] DEBUG[840]: chan_sip.c:8521 parse_request: Header 7 [ 31]: Server: Linksys/PAP2T-5.1.6(LS) [Sep 23 12:15:46] DEBUG[840]: chan_sip.c:8521 parse_request: Header 8 [ 19]: Content-Length: 253 [Sep 23 12:15:46] DEBUG[840]: chan_sip.c:8521 parse_request: Header 9 [ 61]: Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER [Sep 23 12:15:46] DEBUG[840]: chan_sip.c:8521 parse_request: Header 10 [ 29]: Supported: x-sipura, replaces [Sep 23 12:15:46] DEBUG[840]: chan_sip.c:8521 parse_request: Header 11 [ 29]: Content-Type: application/sdp [Sep 23 12:15:46] DEBUG[840]: chan_sip.c:8521 parse_request: Header 12 [ 0]: [Sep 23 12:15:46] DEBUG[840]: chan_sip.c:8521 parse_request: Body 0 [ 3]: v=0 [Sep 23 12:15:46] DEBUG[840]: chan_sip.c:8521 parse_request: Body 1 [ 37]: o=- 697345 697345 IN IP4 192.168.17.4 [Sep 23 12:15:46] DEBUG[840]: chan_sip.c:8521 parse_request: Body 2 [ 3]: s=- [Sep 23 12:15:46] DEBUG[840]: chan_sip.c:8521 parse_request: Body 3 [ 21]: c=IN IP4 192.168.17.4 [Sep 23 12:15:46] DEBUG[840]: chan_sip.c:8521 parse_request: Body 4 [ 5]: t=0 0 [Sep 23 12:15:46] DEBUG[840]: chan_sip.c:8521 parse_request: Body 5 [ 31]: m=audio 16390 RTP/AVP 0 100 101 [Sep 23 12:15:46] DEBUG[840]: chan_sip.c:8521 parse_request: Body 6 [ 20]: a=rtpmap:0 PCMU/8000 [Sep 23 12:15:46] DEBUG[840]: chan_sip.c:8521 parse_request: Body 7 [ 21]: a=rtpmap:100 NSE/8000 [Sep 23 12:15:46] DEBUG[840]: chan_sip.c:8521 parse_request: Body 8 [ 18]: a=fmtp:100 192-193 [Sep 23 12:15:46] DEBUG[840]: chan_sip.c:8521 parse_request: Body 9 [ 33]: a=rtpmap:101 telephone-event/8000 [Sep 23 12:15:46] DEBUG[840]: chan_sip.c:8521 parse_request: Body 10 [ 15]: a=fmtp:101 0-15 [Sep 23 12:15:46] DEBUG[840]: chan_sip.c:8521 parse_request: Body 11 [ 10]: a=ptime:20 [Sep 23 12:15:46] DEBUG[840]: chan_sip.c:8558 parse_request: Body 12 [ 10]: a=sendrecv --- (12 headers 13 lines) --- [Sep 23 12:15:46] DEBUG[840]: chan_sip.c:8115 find_call: = Looking for Call ID: 63b7ae5b176acb524d0538b7358a2eb3@192.168.17.1:5060 (Checking To) --From tag as240543e8 --To-tag 7bc54c90c4307330i0 [Sep 23 12:15:46] DEBUG[840]: chan_sip.c:3992 __sip_ack: Acked pending invite 102 [Sep 23 12:15:46] DEBUG[840]: chan_sip.c:4030 __sip_ack: Stopping retransmission on '63b7ae5b176acb524d0538b7358a2eb3@192.168.17.1:5060' of Request 102: Match Found [Sep 23 12:15:46] DEBUG[840]: chan_sip.c:20102 handle_response_invite: SIP response 200 to standard invite [Sep 23 12:15:46] DEBUG[840]: chan_sip.c:8908 process_sdp: Processing session-level SDP v=0... UNSUPPORTED OR FAILED. [Sep 23 12:15:46] DEBUG[840]: chan_sip.c:8908 process_sdp: Processing session-level SDP o=- 697345 697345 IN IP4 192.168.17.4... UNSUPPORTED OR FAILED. [Sep 23 12:15:46] DEBUG[840]: chan_sip.c:8908 process_sdp: Processing session-level SDP s=-... UNSUPPORTED OR FAILED. [Sep 23 12:15:46] DEBUG[840]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting '192.168.17.4' into... [Sep 23 12:15:46] DEBUG[840]: netsock2.c:192 ast_sockaddr_split_hostport: ...host '192.168.17.4' and port ''. [Sep 23 12:15:46] DEBUG[840]: chan_sip.c:8908 process_sdp: Processing session-level SDP c=IN IP4 192.168.17.4... OK. [Sep 23 12:15:46] 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 12:15:46] 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 12:15:46] 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 12:15:46] DEBUG[840]: chan_sip.c:9179 process_sdp: Processing media-level (audio) SDP a=rtpmap:0 PCMU/8000... OK. [Sep 23 12:15:46] 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 12:15:46] DEBUG[840]: chan_sip.c:9179 process_sdp: Processing media-level (audio) SDP a=rtpmap:100 NSE/8000... UNSUPPORTED OR FAILED. [Sep 23 12:15:46] 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 12:15:46] DEBUG[840]: chan_sip.c:9179 process_sdp: Processing media-level (audio) SDP a=rtpmap:101 telephone-event/8000... OK. [Sep 23 12:15:46] DEBUG[840]: chan_sip.c:9179 process_sdp: Processing media-level (audio) SDP a=fmtp:101 0-15... UNSUPPORTED OR FAILED. [Sep 23 12:15:46] DEBUG[840]: chan_sip.c:9179 process_sdp: Processing media-level (audio) SDP a=ptime:20... OK. [Sep 23 12:15:46] DEBUG[840]: chan_sip.c:9179 process_sdp: Processing media-level (audio) SDP a=sendrecv... OK. [Sep 23 12:15:46] DEBUG[840]: rtp_engine.c:644 ast_rtp_codecs_payload_formats: Incorporating payload 0 on 0x41504490 [Sep 23 12:15:46] 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 12:15:46] DEBUG[840]: res_rtp_asterisk.c:2563 ast_rtp_remote_address_set: Setting RTCP address on RTP instance '0x88bc528' Peer audio RTP is at port 192.168.17.4:16390 [Sep 23 12:15:46] DEBUG[840]: rtp_engine.c:522 ast_rtp_codecs_payloads_copy: Copying payload 0 from 0x41504490 to 0x88bc6d4 [Sep 23 12:15:46] DEBUG[840]: rtp_engine.c:522 ast_rtp_codecs_payloads_copy: Copying payload 101 from 0x41504490 to 0x88bc6d4 [Sep 23 12:15:46] DEBUG[840]: res_rtp_asterisk.c:2489 ast_rtp_prop_set: Ignoring duplicate RTCP property on RTP instance '0x88bc528' [Sep 23 12:15:46] DEBUG[840]: chan_sip.c:9424 process_sdp: We're settling with these formats: 0x4 (ulaw) [Sep 23 12:15:46] DEBUG[840]: chan_sip.c:9429 process_sdp: We have an owner, now see if we need to change this call [Sep 23 12:15:46] DEBUG[840]: chan_sip.c:5905 update_call_counter: Updating call counter for outgoing call [Sep 23 12:15:46] DEBUG[840]: chan_sip.c:14336 build_route: build_route: Contact hop: Line 1 list_route: hop: [Sep 23 12:15:46] DEBUG[840]: chan_sip.c:10397 reqprep: Strict routing enforced for session 63b7ae5b176acb524d0538b7358a2eb3@192.168.17.1:5060 set_destination: Parsing for address/port to send to [Sep 23 12:15:46] DEBUG[840]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting '192.168.17.4:5060' into... [Sep 23 12:15:46] 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=z9hG4bK011e8bf8;rport Max-Forwards: 70 From: "Cordless 1" ;tag=as240543e8 To: ;tag=7bc54c90c4307330i0 Contact: Call-ID: 63b7ae5b176acb524d0538b7358a2eb3@192.168.17.1:5060 CSeq: 102 ACK User-Agent: Asterisk PBX 1.8.15.0 Content-Length: 0 --- [Sep 23 12:15:46] 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-00000028 answered Local/s@OutboundSLA-b2f8;2 [Sep 23 12:15:46] DEBUG[4139]: chan_local.c:760 local_indicate: Blocked indication -1 [Sep 23 12:15:46] DEBUG[4139]: features.c:4012 ast_bridge_call: bridge answer set, chan answer set [Sep 23 12:15:46] DEBUG[4139]: features.c:3854 clear_dialed_interfaces: Removing dialed interfaces datastore on SIP/101-00000028 since we're bridging [Sep 23 12:15:46] DEBUG[4139]: res_rtp_asterisk.c:828 ast_rtp_update_source: Setting the marker bit due to a source update [Sep 23 12:15:46] DEBUG[822]: devicestate.c:342 _ast_device_state: No provider found, checking channel drivers for SIP - 101 [Sep 23 12:15:46] DEBUG[822]: chan_sip.c:26559 sip_devicestate: Checking device state for peer 101 [Sep 23 12:15:46] DEBUG[822]: devicestate.c:460 do_state_change: Changing state for SIP/101 - state 2 (In use) [Sep 23 12:15:46] DEBUG[822]: devicestate.c:440 devstate_event: device 'SIP/101' state '2' [Sep 23 12:15:46] DEBUG[822]: devicestate.c:342 _ast_device_state: No provider found, checking channel drivers for SIP - 101 [Sep 23 12:15:46] DEBUG[822]: chan_sip.c:26559 sip_devicestate: Checking device state for peer 101 [Sep 23 12:15:46] DEBUG[822]: devicestate.c:460 do_state_change: Changing state for SIP/101 - state 2 (In use) [Sep 23 12:15:46] DEBUG[822]: devicestate.c:440 devstate_event: device 'SIP/101' state '2' [Sep 23 12:15:46] DEBUG[822]: devicestate.c:342 _ast_device_state: No provider found, checking channel drivers for Local - s@OutboundSLA [Sep 23 12:15:46] DEBUG[822]: chan_local.c:300 local_devicestate: Checking if extension s@OutboundSLA exists (devicestate) [Sep 23 12:15:46] DEBUG[822]: devicestate.c:460 do_state_change: Changing state for Local/s@OutboundSLA - state 2 (In use) [Sep 23 12:15:46] DEBUG[822]: devicestate.c:440 devstate_event: device 'Local/s@OutboundSLA' state '2' -- Local/s@OutboundSLA-b2f8;1 answered [Sep 23 12:15:46] DEBUG[4138]: chan_dahdi.c:13646 dahdi_request: Using channel -2 [Sep 23 12:15:46] DEBUG[4138]: channel.c:5175 set_format: Set channel DAHDI/pseudo-1522217927 to read format slin [Sep 23 12:15:46] DEBUG[4138]: channel.c:5175 set_format: Set channel DAHDI/pseudo-1522217927 to write format slin -- Created MeetMe conference 1023 for conference 'SLA_SLAtrunk1' [Sep 23 12:15:46] DEBUG[4138]: devicestate.c:440 devstate_event: device 'meetme:SLA_SLAtrunk1' state '2' [Sep 23 12:15:46] DEBUG[4138]: chan_local.c:760 local_indicate: Blocked indication -1 [Sep 23 12:15:46] DEBUG[4138]: channel.c:5175 set_format: Set channel Local/s@OutboundSLA-b2f8;1 to write format slin [Sep 23 12:15:46] DEBUG[4138]: channel.c:5175 set_format: Set channel Local/s@OutboundSLA-b2f8;1 to read format slin [Sep 23 12:15:46] DEBUG[4138]: app_meetme.c:2683 conf_run: Placed channel Local/s@OutboundSLA-b2f8;1 in DAHDI conf 1023 [Sep 23 12:15:46] DEBUG[822]: devicestate.c:342 _ast_device_state: No provider found, checking channel drivers for Local - s@OutboundSLA [Sep 23 12:15:46] DEBUG[822]: chan_local.c:300 local_devicestate: Checking if extension s@OutboundSLA exists (devicestate) [Sep 23 12:15:46] DEBUG[822]: devicestate.c:460 do_state_change: Changing state for Local/s@OutboundSLA - state 2 (In use) [Sep 23 12:15:46] DEBUG[822]: devicestate.c:440 devstate_event: device 'Local/s@OutboundSLA' state '2' [Sep 23 12:15:46] DEBUG[822]: devicestate.c:342 _ast_device_state: No provider found, checking channel drivers for DAHDI - pseudo [Sep 23 12:15:46] DEBUG[822]: devicestate.c:460 do_state_change: Changing state for DAHDI/pseudo - state 2 (In use) [Sep 23 12:15:46] DEBUG[822]: devicestate.c:440 devstate_event: device 'DAHDI/pseudo' state '2' [Sep 23 12:15:46] 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 12:15:46] 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 12:15:46] 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 12:15:46] DEBUG[4139]: channel.c:5954 __ast_channel_masquerade: Planning to masquerade channel SIP/101-00000028 into the structure of Local/s@OutboundSLA-b2f8;1 [Sep 23 12:15:46] DEBUG[4139]: channel.c:5964 __ast_channel_masquerade: Done planning to masquerade channel SIP/101-00000028 into the structure of Local/s@OutboundSLA-b2f8;1 [Sep 23 12:15:46] DEBUG[4139]: chan_local.c:557 check_bridge: Masquerading Local/s@OutboundSLA-b2f8;1 <- SIP/101-00000028 [Sep 23 12:15:46] DEBUG[4139]: chan_local.c:760 local_indicate: Blocked indication -1 [Sep 23 12:15:46] DEBUG[4139]: channel.c:6539 ast_do_masquerade: Actually Masquerading SIP/101-00000028(6) into the structure of Local/s@OutboundSLA-b2f8;1(6) [Sep 23 12:15:46] DEBUG[4139]: channel.c:5175 set_format: Set channel SIP/101-00000028 to write format slin [Sep 23 12:15:46] DEBUG[4139]: channel.c:5175 set_format: Set channel SIP/101-00000028 to read format slin [Sep 23 12:15:46] DEBUG[4139]: channel.c:6743 ast_do_masquerade: Putting channel SIP/101-00000028 in slin/slin formats [Sep 23 12:15:46] DEBUG[4139]: chan_sip.c:6645 sip_fixup: SIP Fixup: New owner for dialogue 63b7ae5b176acb524d0538b7358a2eb3@192.168.17.1:5060: SIP/101-00000028 (Old parent: Local/s@OutboundSLA-b2f8;1) [Sep 23 12:15:46] DEBUG[4139]: channel.c:6819 ast_do_masquerade: Done Masquerading SIP/101-00000028 (6) [Sep 23 12:15:46] DEBUG[4139]: res_rtp_asterisk.c:840 ast_rtp_change_source: Not changing SSRC since we haven't sent any RTP yet [Sep 23 12:15:46] DEBUG[4139]: chan_local.c:654 local_write: Not posting to 'Local/s@OutboundSLA-b2f8;2' queue since already masqueraded out [Sep 23 12:15:46] DEBUG[4139]: channel.c:7488 ast_channel_bridge: Bridge stops because we're zombie or need a soft hangup: c0=Local/s@OutboundSLA-b2f8;2, c1=Local/s@OutboundSLA-b2f8;1, flags: No,Yes,Yes,Yes [Sep 23 12:15:46] DEBUG[4139]: channel.c:7595 ast_channel_bridge: Bridge stops bridging channels Local/s@OutboundSLA-b2f8;2 and Local/s@OutboundSLA-b2f8;1 [Sep 23 12:15:46] DEBUG[4139]: channel.c:2695 ast_softhangup_nolock: Soft-Hanging up channel 'Local/s@OutboundSLA-b2f8;2' [Sep 23 12:15:46] DEBUG[4139]: pbx.c:4247 pbx_extension_helper: Launching 'Hangup' -- Executing [h@OutboundSLA:1] Hangup("Local/s@OutboundSLA-b2f8;2", "") in new stack [Sep 23 12:15:46] DEBUG[4139]: features.c:4393 ast_bridge_call: Spawn extension (OutboundSLA,h,1) exited non-zero on 'Local/s@OutboundSLA-b2f8;2' == Spawn extension (OutboundSLA, h, 1) exited non-zero on 'Local/s@OutboundSLA-b2f8;2' [Sep 23 12:15:46] DEBUG[4139]: channel.c:2850 ast_hangup: Hanging up zombie 'Local/s@OutboundSLA-b2f8;1' [Sep 23 12:15:46] DEBUG[4139]: app_dial.c:3027 dial_exec_full: Exiting with DIALSTATUS=ANSWER. [Sep 23 12:15:46] DEBUG[4139]: pbx.c:5064 __ast_pbx_run: Spawn extension (OutboundSLA,101,1) exited non-zero on 'Local/s@OutboundSLA-b2f8;2' == Spawn extension (OutboundSLA, 101, 1) exited non-zero on 'Local/s@OutboundSLA-b2f8;2' [Sep 23 12:15:46] DEBUG[4139]: channel.c:2695 ast_softhangup_nolock: Soft-Hanging up channel 'Local/s@OutboundSLA-b2f8;2' [Sep 23 12:15:46] DEBUG[4139]: channel.c:2844 ast_hangup: Hanging up channel 'Local/s@OutboundSLA-b2f8;2' [Sep 23 12:15:46] DEBUG[4138]: dsp.c:475 ast_tone_detect_init: Setup tone 1100 Hz, 500 ms, block_size=160, hits_required=21 [Sep 23 12:15:46] DEBUG[4138]: dsp.c:475 ast_tone_detect_init: Setup tone 2100 Hz, 2600 ms, block_size=160, hits_required=116 [Sep 23 12:15:46] DEBUG[4138]: app_meetme.c:3127 conf_run: Ooh, something swapped out under us, starting over [Sep 23 12:15:46] DEBUG[4138]: app_meetme.c:2683 conf_run: Placed channel SIP/101-00000028 in DAHDI conf 1023 [Sep 23 12:15:46] 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 12:15:46] 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 12:15:46] 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 12:15:46] DEBUG[4137]: channel.c:5175 set_format: Set channel SIP/111-00000027 to write format slin [Sep 23 12:15:46] DEBUG[4137]: channel.c:5175 set_format: Set channel SIP/111-00000027 to read format slin [Sep 23 12:15:46] DEBUG[4137]: app_meetme.c:2683 conf_run: Placed channel SIP/111-00000027 in DAHDI conf 1023 [Sep 23 12:15:46] DEBUG[4137]: dsp.c:475 ast_tone_detect_init: Setup tone 1100 Hz, 500 ms, block_size=160, hits_required=21 [Sep 23 12:15:46] DEBUG[4137]: dsp.c:475 ast_tone_detect_init: Setup tone 2100 Hz, 2600 ms, block_size=160, hits_required=116 [Sep 23 12:15:46] DEBUG[822]: devicestate.c:342 _ast_device_state: No provider found, checking channel drivers for Local - s@OutboundSLA [Sep 23 12:15:46] DEBUG[822]: chan_local.c:300 local_devicestate: Checking if extension s@OutboundSLA exists (devicestate) [Sep 23 12:15:46] DEBUG[822]: devicestate.c:460 do_state_change: Changing state for Local/s@OutboundSLA - state 1 (Not in use) [Sep 23 12:15:46] DEBUG[822]: devicestate.c:440 devstate_event: device 'Local/s@OutboundSLA' state '1' [Sep 23 12:15:46] DEBUG[822]: devicestate.c:342 _ast_device_state: No provider found, checking channel drivers for Local - s@OutboundSLA [Sep 23 12:15:46] DEBUG[822]: chan_local.c:300 local_devicestate: Checking if extension s@OutboundSLA exists (devicestate) [Sep 23 12:15:46] DEBUG[822]: devicestate.c:460 do_state_change: Changing state for Local/s@OutboundSLA - state 1 (Not in use) [Sep 23 12:15:46] DEBUG[822]: devicestate.c:440 devstate_event: device 'Local/s@OutboundSLA' state '1' [Sep 23 12:15:46] DEBUG[4138]: dsp.c:475 ast_tone_detect_init: Setup tone 1100 Hz, 500 ms, block_size=160, hits_required=21 [Sep 23 12:15:46] DEBUG[4138]: dsp.c:475 ast_tone_detect_init: Setup tone 2100 Hz, 2600 ms, block_size=160, hits_required=116 [Sep 23 12:15:46] 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 12:15:46] 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 12:15:46] DEBUG[4138]: res_rtp_asterisk.c:1359 ast_rtp_write: Ooh, format changed from unknown to ulaw [Sep 23 12:15:46] DEBUG[4138]: res_rtp_asterisk.c:1390 ast_rtp_write: Created smoother: format: ulaw ms: 20 len: 160 [Sep 23 12:15:46] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:46] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:46] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:46] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:46] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:46] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:46] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:46] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:46] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:46] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:46] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:46] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:46] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:46] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:47] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:47] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:47] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:47] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:47] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:47] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:47] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:47] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:47] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:47] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:47] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:47] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:47] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:47] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:47] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:47] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:47] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:47] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:47] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:47] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:47] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:47] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:47] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:47] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:47] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:47] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:47] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:47] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:47] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:47] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:47] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:47] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:47] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:47] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:47] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:47] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:47] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:47] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:47] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:47] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:47] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:47] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:47] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:47] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:47] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:47] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:47] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:47] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:47] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:47] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:48] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:48] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:48] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:48] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:48] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:48] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:48] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:48] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:48] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:48] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:48] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:48] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:48] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:48] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:48] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:48] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:48] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:48] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:48] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:48] DEBUG[840]: chan_sip.c:7795 sip_alloc: Allocating new SIP dialog for 6c43b72c3b31b4bf3cba091e53bd5d4b@192.168.17.1:5060 - OPTIONS (No RTP) [Sep 23 12:15:48] DEBUG[840]: acl.c:736 ast_ouraddrfor: For destination '192.168.17.4', our source address is '192.168.17.1'. [Sep 23 12:15:48] DEBUG[840]: chan_sip.c:3498 ast_sip_ouraddrfor: Setting SIP_TRANSPORT_UDP with address 192.168.17.1:5060 [Sep 23 12:15:48] DEBUG[840]: chan_sip.c:3075 initialize_initreq: Initializing initreq for method OPTIONS - callid 4e02e89b5dbe77cb52c4df4c4ba14fff@192.168.17.1:5060 [Sep 23 12:15:48] DEBUG[840]: chan_sip.c:8521 parse_request: Header 0 [ 41]: OPTIONS sip:101@192.168.17.4:5060 SIP/2.0 [Sep 23 12:15:48] DEBUG[840]: chan_sip.c:8521 parse_request: Header 1 [ 63]: Via: SIP/2.0/UDP 192.168.17.1:5060;branch=z9hG4bK1c52071a;rport [Sep 23 12:15:48] DEBUG[840]: chan_sip.c:8521 parse_request: Header 2 [ 16]: Max-Forwards: 70 [Sep 23 12:15:48] DEBUG[840]: chan_sip.c:8521 parse_request: Header 3 [ 59]: From: "asterisk" ;tag=as4d401383 [Sep 23 12:15:48] DEBUG[840]: chan_sip.c:8521 parse_request: Header 4 [ 31]: To: [Sep 23 12:15:48] DEBUG[840]: chan_sip.c:8521 parse_request: Header 5 [ 41]: Contact: [Sep 23 12:15:48] DEBUG[840]: chan_sip.c:8521 parse_request: Header 6 [ 59]: Call-ID: 4e02e89b5dbe77cb52c4df4c4ba14fff@192.168.17.1:5060 [Sep 23 12:15:48] DEBUG[840]: chan_sip.c:8521 parse_request: Header 7 [ 17]: CSeq: 102 OPTIONS [Sep 23 12:15:48] DEBUG[840]: chan_sip.c:8521 parse_request: Header 8 [ 33]: User-Agent: Asterisk PBX 1.8.15.0 [Sep 23 12:15:48] DEBUG[840]: chan_sip.c:8521 parse_request: Header 9 [ 35]: Date: Sun, 23 Sep 2012 16:15:48 GMT [Sep 23 12:15:48] DEBUG[840]: chan_sip.c:8521 parse_request: Header 10 [ 81]: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH [Sep 23 12:15:48] DEBUG[840]: chan_sip.c:8521 parse_request: Header 11 [ 26]: Supported: replaces, timer Reliably Transmitting (NAT) to 192.168.17.4:5060: OPTIONS sip:101@192.168.17.4:5060 SIP/2.0 Via: SIP/2.0/UDP 192.168.17.1:5060;branch=z9hG4bK1c52071a;rport Max-Forwards: 70 From: "asterisk" ;tag=as4d401383 To: Contact: Call-ID: 4e02e89b5dbe77cb52c4df4c4ba14fff@192.168.17.1:5060 CSeq: 102 OPTIONS User-Agent: Asterisk PBX 1.8.15.0 Date: Sun, 23 Sep 2012 16:15:48 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH Supported: replaces, timer Content-Length: 0 --- [Sep 23 12:15:48] DEBUG[840]: chan_sip.c:3801 __sip_reliable_xmit: *** SIP TIMER: Initializing retransmit timer on packet: Id #875 [Sep 23 12:15:48] DEBUG[840]: chan_sip.c:3346 __sip_xmit: Trying to put 'OPTIONS sip' onto UDP socket destined for 192.168.17.4:5060 [Sep 23 12:15:48] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:48] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 <--- SIP read from UDP:192.168.17.4:5060 ---> SIP/2.0 486 Busy Here To: ;tag=3d870fd433ab4566i0 From: "asterisk" ;tag=as4d401383 Call-ID: 4e02e89b5dbe77cb52c4df4c4ba14fff@192.168.17.1:5060 CSeq: 102 OPTIONS Via: SIP/2.0/UDP 192.168.17.1:5060;branch=z9hG4bK1c52071a Server: Linksys/PAP2T-5.1.6(LS) Content-Length: 0 Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER Supported: x-sipura, replaces <-------------> [Sep 23 12:15:48] DEBUG[840]: chan_sip.c:8521 parse_request: Header 0 [ 21]: SIP/2.0 486 Busy Here [Sep 23 12:15:48] DEBUG[840]: chan_sip.c:8521 parse_request: Header 1 [ 54]: To: ;tag=3d870fd433ab4566i0 [Sep 23 12:15:48] DEBUG[840]: chan_sip.c:8521 parse_request: Header 2 [ 59]: From: "asterisk" ;tag=as4d401383 [Sep 23 12:15:48] DEBUG[840]: chan_sip.c:8521 parse_request: Header 3 [ 59]: Call-ID: 4e02e89b5dbe77cb52c4df4c4ba14fff@192.168.17.1:5060 [Sep 23 12:15:48] DEBUG[840]: chan_sip.c:8521 parse_request: Header 4 [ 17]: CSeq: 102 OPTIONS [Sep 23 12:15:48] DEBUG[840]: chan_sip.c:8521 parse_request: Header 5 [ 57]: Via: SIP/2.0/UDP 192.168.17.1:5060;branch=z9hG4bK1c52071a [Sep 23 12:15:48] DEBUG[840]: chan_sip.c:8521 parse_request: Header 6 [ 31]: Server: Linksys/PAP2T-5.1.6(LS) [Sep 23 12:15:48] DEBUG[840]: chan_sip.c:8521 parse_request: Header 7 [ 17]: Content-Length: 0 [Sep 23 12:15:48] DEBUG[840]: chan_sip.c:8521 parse_request: Header 8 [ 61]: Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER [Sep 23 12:15:48] DEBUG[840]: chan_sip.c:8521 parse_request: Header 9 [ 29]: Supported: x-sipura, replaces --- (10 headers 0 lines) --- [Sep 23 12:15:48] DEBUG[840]: chan_sip.c:8115 find_call: = Looking for Call ID: 4e02e89b5dbe77cb52c4df4c4ba14fff@192.168.17.1:5060 (Checking To) --From tag as4d401383 --To-tag 3d870fd433ab4566i0 [Sep 23 12:15:48] DEBUG[840]: chan_sip.c:3997 __sip_ack: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #875 [Sep 23 12:15:48] DEBUG[840]: chan_sip.c:4030 __sip_ack: Stopping retransmission on '4e02e89b5dbe77cb52c4df4c4ba14fff@192.168.17.1:5060' of Request 102: Match Found [Sep 23 12:15:48] DEBUG[840]: chan_sip.c:6053 sip_destroy: Destroying SIP dialog 4e02e89b5dbe77cb52c4df4c4ba14fff@192.168.17.1:5060 Really destroying SIP dialog '4e02e89b5dbe77cb52c4df4c4ba14fff@192.168.17.1:5060' Method: OPTIONS [Sep 23 12:15:48] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:48] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:48] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:48] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:48] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:48] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:48] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:48] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:48] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:48] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:48] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:48] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:48] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:48] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:48] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:48] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:48] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:48] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:48] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:48] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:48] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:48] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:48] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:48] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:48] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:48] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:48] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:48] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:48] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:49] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:49] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:49] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:49] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:49] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:49] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:49] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:49] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:49] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:49] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:49] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:49] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:49] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:49] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:49] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:49] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:49] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:49] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:49] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:49] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:49] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:49] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:49] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:49] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:49] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:49] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:49] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:49] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:49] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:49] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:49] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:49] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:49] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:49] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:49] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:49] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:49] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:49] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:49] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:49] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:49] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:49] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:49] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:49] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:49] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:49] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:49] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:49] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:49] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:49] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:50] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:50] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:50] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:50] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:50] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:50] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:50] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:50] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:50] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:50] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:50] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:50] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:50] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:50] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:50] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:50] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:50] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:50] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:50] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:50] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:50] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:50] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:50] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:50] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:50] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:50] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:50] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:50] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:50] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:50] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:50] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:50] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:50] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:50] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:50] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:50] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:50] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:50] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:50] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:50] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:50] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:50] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:50] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:50] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:50] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:50] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:50] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:50] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:50] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:50] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:51] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:51] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:51] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:51] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:51] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:51] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:51] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:51] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:51] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:51] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:51] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:51] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:51] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:51] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:51] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:51] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:51] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:51] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:51] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:51] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:51] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:51] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:51] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:51] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:51] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:51] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:51] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:51] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:51] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:51] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:51] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:51] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:51] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:51] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:51] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:51] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:51] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:51] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:51] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:51] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:51] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:51] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:51] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:51] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:51] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:51] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:51] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:51] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:51] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:51] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:52] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:52] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:52] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:52] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:52] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:52] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:52] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:52] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:52] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:52] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:52] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:52] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:52] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:52] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:52] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:52] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:52] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:52] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:52] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:52] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:52] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:52] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:52] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:52] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:52] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:52] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:52] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:52] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:52] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:52] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:52] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:52] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:52] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:52] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:52] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:52] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:52] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:52] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:52] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:52] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:52] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:52] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:52] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:52] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:52] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:52] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:52] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:52] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:52] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:52] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:53] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:53] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:53] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:53] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:53] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:53] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:53] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:53] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:53] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:53] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:53] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:53] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:53] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:53] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:53] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:53] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:53] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:53] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:53] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:53] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:53] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:53] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:53] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:53] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:53] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:53] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:53] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:53] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:53] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:53] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:53] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:53] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:53] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:53] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:53] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:53] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:53] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:53] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:53] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:53] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:53] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:53] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:53] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:53] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:53] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:53] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:53] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:53] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:53] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:53] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:54] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:54] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:54] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:54] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:54] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:54] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:54] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:54] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:54] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:54] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:54] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:54] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:54] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:54] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:54] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:54] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:54] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:54] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:54] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:54] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:54] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:54] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:54] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:54] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:54] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:54] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:54] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:54] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:54] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:54] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:54] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 <--- SIP read from UDP:192.168.17.4:5060 ---> BYE sip:111@192.168.17.1:5060 SIP/2.0 Via: SIP/2.0/UDP 192.168.17.4:5060;branch=z9hG4bK-be2bcb77 From: ;tag=7bc54c90c4307330i0 To: "Cordless 1" ;tag=as240543e8 Call-ID: 63b7ae5b176acb524d0538b7358a2eb3@192.168.17.1:5060 CSeq: 101 BYE Max-Forwards: 70 User-Agent: Linksys/PAP2T-5.1.6(LS) Content-Length: 0 <-------------> [Sep 23 12:15:54] DEBUG[840]: chan_sip.c:8521 parse_request: Header 0 [ 37]: BYE sip:111@192.168.17.1:5060 SIP/2.0 [Sep 23 12:15:54] DEBUG[840]: chan_sip.c:8521 parse_request: Header 1 [ 58]: Via: SIP/2.0/UDP 192.168.17.4:5060;branch=z9hG4bK-be2bcb77 [Sep 23 12:15:54] DEBUG[840]: chan_sip.c:8521 parse_request: Header 2 [ 56]: From: ;tag=7bc54c90c4307330i0 [Sep 23 12:15:54] DEBUG[840]: chan_sip.c:8521 parse_request: Header 3 [ 54]: To: "Cordless 1" ;tag=as240543e8 [Sep 23 12:15:54] DEBUG[840]: chan_sip.c:8521 parse_request: Header 4 [ 59]: Call-ID: 63b7ae5b176acb524d0538b7358a2eb3@192.168.17.1:5060 [Sep 23 12:15:54] DEBUG[840]: chan_sip.c:8521 parse_request: Header 5 [ 13]: CSeq: 101 BYE [Sep 23 12:15:54] DEBUG[840]: chan_sip.c:8521 parse_request: Header 6 [ 16]: Max-Forwards: 70 [Sep 23 12:15:54] DEBUG[840]: chan_sip.c:8521 parse_request: Header 7 [ 35]: User-Agent: Linksys/PAP2T-5.1.6(LS) [Sep 23 12:15:54] DEBUG[840]: chan_sip.c:8521 parse_request: Header 8 [ 17]: Content-Length: 0 --- (9 headers 0 lines) --- [Sep 23 12:15:54] DEBUG[840]: chan_sip.c:8115 find_call: = Looking for Call ID: 63b7ae5b176acb524d0538b7358a2eb3@192.168.17.1:5060 (Checking From) --From tag 7bc54c90c4307330i0 --To-tag as240543e8 [Sep 23 12:15:54] DEBUG[840]: chan_sip.c:25254 handle_incoming: **** Received BYE (8) - Command in SIP BYE [Sep 23 12:15:54] DEBUG[840]: chan_sip.c:23954 handle_request_bye: Initializing initreq for method BYE - callid 63b7ae5b176acb524d0538b7358a2eb3@192.168.17.1:5060 [Sep 23 12:15:54] DEBUG[840]: netsock2.c:138 ast_sockaddr_split_hostport: Splitting '192.168.17.4:5060' into... [Sep 23 12:15:54] DEBUG[840]: netsock2.c:192 ast_sockaddr_split_hostport: ...host '192.168.17.4' and port '5060'. Sending to 192.168.17.4:5060 (NAT) [Sep 23 12:15:54] DEBUG[840]: chan_sip.c:3088 sip_alreadygone: Setting SIP_ALREADYGONE on dialog 63b7ae5b176acb524d0538b7358a2eb3@192.168.17.1:5060 [Sep 23 12:15:54] DEBUG[840]: res_rtp_asterisk.c:2563 ast_rtp_remote_address_set: Setting RTCP address on RTP instance '0x88bc528' Scheduling destruction of SIP dialog '63b7ae5b176acb524d0538b7358a2eb3@192.168.17.1:5060' in 13440 ms (Method: BYE) [Sep 23 12:15:54] DEBUG[840]: chan_sip.c:24056 handle_request_bye: Received bye, issuing owner hangup <--- Transmitting (NAT) to 192.168.17.4:5060 ---> SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.17.4:5060;branch=z9hG4bK-be2bcb77;received=192.168.17.4;rport=5060 From: ;tag=7bc54c90c4307330i0 To: "Cordless 1" ;tag=as240543e8 Call-ID: 63b7ae5b176acb524d0538b7358a2eb3@192.168.17.1:5060 CSeq: 101 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 12:15:54] DEBUG[840]: chan_sip.c:3346 __sip_xmit: Trying to put 'SIP/2.0 200' onto UDP socket destined for 192.168.17.4:5060 [Sep 23 12:15:54] DEBUG[4138]: devicestate.c:440 devstate_event: device 'SLA:SLAphone111_SLAtrunk1' state '1' [Sep 23 12:15:54] DEBUG[4138]: devicestate.c:440 devstate_event: device 'SLA:SLAphone112_SLAtrunk1' state '1' [Sep 23 12:15:54] DEBUG[4138]: channel.c:2844 ast_hangup: Hanging up channel 'SIP/101-00000028' [Sep 23 12:15:54] DEBUG[4138]: chan_sip.c:6287 sip_hangup: Hangup call SIP/101-00000028, SIP callid 63b7ae5b176acb524d0538b7358a2eb3@192.168.17.1:5060 [Sep 23 12:15:54] DEBUG[4138]: chan_sip.c:6292 sip_hangup: update_call_counter(101) - decrement call limit counter on hangup [Sep 23 12:15:54] DEBUG[4138]: chan_sip.c:5905 update_call_counter: Updating call counter for outgoing call [Sep 23 12:15:54] DEBUG[4138]: chan_sip.c:5976 update_call_counter: Call to peer '101' removed from call limit 10 [Sep 23 12:15:54] DEBUG[4138]: res_rtp_asterisk.c:2563 ast_rtp_remote_address_set: Setting RTCP address on RTP instance '0x88bc528' [Sep 23 12:15:54] WARNING[4137]: app_meetme.c:3678 conf_run: Unable to write frame to channel SIP/111-00000027 [Sep 23 12:15:54] DEBUG[4137]: devicestate.c:440 devstate_event: device 'meetme:SLA_SLAtrunk1' state '1' [Sep 23 12:15:54] DEBUG[4137]: channel.c:2844 ast_hangup: Hanging up channel 'DAHDI/pseudo-1522217927' [Sep 23 12:15:54] DEBUG[4137]: chan_dahdi.c:6149 dahdi_hangup: dahdi_hangup(DAHDI/pseudo-1522217927) [Sep 23 12:15:54] DEBUG[4137]: chan_dahdi.c:6316 dahdi_hangup: Hangup: channel: -2 index = 0, normal = 39, callwait = -1, thirdcall = -1 [Sep 23 12:15:54] DEBUG[4137]: chan_dahdi.c:6757 dahdi_setoption: Set option TDD MODE, value: OFF(0) on DAHDI/pseudo-1522217927 [Sep 23 12:15:54] DEBUG[4137]: chan_dahdi.c:4874 update_conf: Updated conferencing on -2, with 0 conference users -- Hungup 'DAHDI/pseudo-1522217927' [Sep 23 12:15:54] WARNING[4137]: app_meetme.c:4558 admin_exec: Conference number 'SLA_SLAtrunk1' not found! == Setting global variable 'MEETMEADMINSTATUS' to 'NOTFOUND' [Sep 23 12:15:54] DEBUG[4137]: devicestate.c:440 devstate_event: device 'SLA:SLAphone111_SLAtrunk1' state '1' [Sep 23 12:15:54] DEBUG[4137]: devicestate.c:440 devstate_event: device 'SLA:SLAphone112_SLAtrunk1' state '1' [Sep 23 12:15:54] DEBUG[4137]: pbx.c:5039 __ast_pbx_run: Extension 101, priority 3 returned normally even though call was hung up [Sep 23 12:15:54] DEBUG[4137]: channel.c:2695 ast_softhangup_nolock: Soft-Hanging up channel 'SIP/111-00000027' [Sep 23 12:15:54] DEBUG[4137]: pbx.c:4247 pbx_extension_helper: Launching 'Hangup' -- Executing [h@DialPlanSLA:1] Hangup("SIP/111-00000027", "") in new stack [Sep 23 12:15:54] DEBUG[4137]: pbx.c:5243 __ast_pbx_run: Spawn extension (DialPlanSLA,h,1) exited non-zero on 'SIP/111-00000027' == Spawn extension (DialPlanSLA, h, 1) exited non-zero on 'SIP/111-00000027' [Sep 23 12:15:54] DEBUG[4137]: channel.c:2844 ast_hangup: Hanging up channel 'SIP/111-00000027' [Sep 23 12:15:54] DEBUG[4137]: chan_sip.c:6287 sip_hangup: Hangup call SIP/111-00000027, SIP callid 4243978099@192_168_17_141 [Sep 23 12:15:54] DEBUG[4137]: res_rtp_asterisk.c:2563 ast_rtp_remote_address_set: Setting RTCP address on RTP instance '0x8898e38' [Sep 23 12:15:54] DEBUG[822]: devicestate.c:342 _ast_device_state: No provider found, checking channel drivers for SIP - 101 [Sep 23 12:15:54] DEBUG[822]: chan_sip.c:26559 sip_devicestate: Checking device state for peer 101 [Sep 23 12:15:54] DEBUG[822]: devicestate.c:460 do_state_change: Changing state for SIP/101 - state 1 (Not in use) [Sep 23 12:15:54] DEBUG[822]: devicestate.c:440 devstate_event: device 'SIP/101' state '1' [Sep 23 12:15:54] DEBUG[822]: devicestate.c:342 _ast_device_state: No provider found, checking channel drivers for SIP - 101 [Sep 23 12:15:54] DEBUG[822]: chan_sip.c:26559 sip_devicestate: Checking device state for peer 101 [Sep 23 12:15:54] DEBUG[822]: devicestate.c:460 do_state_change: Changing state for SIP/101 - state 1 (Not in use) [Sep 23 12:15:54] DEBUG[822]: devicestate.c:440 devstate_event: device 'SIP/101' state '1' [Sep 23 12:15:54] DEBUG[822]: devicestate.c:342 _ast_device_state: No provider found, checking channel drivers for DAHDI - pseudo [Sep 23 12:15:54] DEBUG[822]: devicestate.c:460 do_state_change: Changing state for DAHDI/pseudo - state 0 (Unknown) [Sep 23 12:15:54] DEBUG[822]: devicestate.c:440 devstate_event: device 'DAHDI/pseudo' state '0' [Sep 23 12:15:54] DEBUG[822]: devicestate.c:342 _ast_device_state: No provider found, checking channel drivers for SIP - 111 [Sep 23 12:15:54] DEBUG[822]: chan_sip.c:26559 sip_devicestate: Checking device state for peer 111 [Sep 23 12:15:54] DEBUG[822]: devicestate.c:460 do_state_change: Changing state for SIP/111 - state 1 (Not in use) [Sep 23 12:15:54] DEBUG[822]: devicestate.c:440 devstate_event: device 'SIP/111' state '1' [Sep 23 12:15:54] 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 12:15:54] 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 12:15:54] 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 12:15:54] 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 12:15:54] 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 12:15:54] 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 12:15:54] 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 12:15:54] 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 12:15:54] 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 12:15:54] 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 12:15:54] 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 12:15:55] DEBUG[840]: chan_sip.c:6053 sip_destroy: Destroying SIP dialog 4243978099@192_168_17_141 Really destroying SIP dialog '4243978099@192_168_17_141' Method: ACK [Sep 23 12:15:55] DEBUG[840]: rtp_engine.c:298 instance_destructor: Destroyed RTP instance '0x8898e38' pbx*CLI>