[Apr 18 19:03:28] Asterisk SVN-branch-11-r386006 built by root @ ubuntu on a x86_64 running Linux on 2013-04-18 21:43:29 UTC [Apr 18 19:03:28] DEBUG[17061] config.c: Parsing /etc/asterisk/logger.conf [Apr 18 19:03:28] VERBOSE[17061] config.c: == Parsing '/etc/asterisk/logger.conf': Found [Apr 18 19:03:28] VERBOSE[17061] logger.c: Asterisk Queue Logger restarted [Apr 18 19:03:34] DEBUG[16475] chan_sip.c: = Looking for Call ID: Y1FimtV-XdQeeFafFp204feiZuPHDhvL (Checking From) --From tag UShJfO0-O03KpBzTpqW-dJbG.O96lxcX --To-tag [Apr 18 19:03:34] DEBUG[16475] acl.c: For destination '10.24.18.138', our source address is '10.24.18.124'. [Apr 18 19:03:34] DEBUG[16475] chan_sip.c: Setting SIP_TRANSPORT_UDP with address 10.24.18.124:5060 [Apr 18 19:03:34] DEBUG[16475] netsock2.c: Splitting '10.24.18.138:5060' into... [Apr 18 19:03:34] DEBUG[16475] netsock2.c: ...host '10.24.18.138' and port '5060'. [Apr 18 19:03:34] DEBUG[16475] chan_sip.c: Allocating new SIP dialog for Y1FimtV-XdQeeFafFp204feiZuPHDhvL - INVITE (No RTP) [Apr 18 19:03:34] DEBUG[16475][C-00000009] chan_sip.c: **** Received INVITE (5) - Command in SIP INVITE [Apr 18 19:03:34] DEBUG[16475][C-00000009] sip/reqresp_parser.c: Begin: parsing SIP "Supported: replaces, 100rel, timer, norefersub" [Apr 18 19:03:34] DEBUG[16475][C-00000009] sip/reqresp_parser.c: Found SIP option: -replaces- [Apr 18 19:03:34] DEBUG[16475][C-00000009] sip/reqresp_parser.c: Matched SIP option: replaces [Apr 18 19:03:34] DEBUG[16475][C-00000009] sip/reqresp_parser.c: Found SIP option: -100rel- [Apr 18 19:03:34] DEBUG[16475][C-00000009] sip/reqresp_parser.c: Matched SIP option: 100rel [Apr 18 19:03:34] DEBUG[16475][C-00000009] sip/reqresp_parser.c: Found SIP option: -timer- [Apr 18 19:03:34] DEBUG[16475][C-00000009] sip/reqresp_parser.c: Matched SIP option: timer [Apr 18 19:03:34] DEBUG[16475][C-00000009] sip/reqresp_parser.c: Found SIP option: -norefersub- [Apr 18 19:03:34] DEBUG[16475][C-00000009] sip/reqresp_parser.c: Matched SIP option: norefersub [Apr 18 19:03:34] DEBUG[16475][C-00000009] netsock2.c: Splitting '10.24.18.138:5060' into... [Apr 18 19:03:34] DEBUG[16475][C-00000009] netsock2.c: ...host '10.24.18.138' and port '5060'. [Apr 18 19:03:34] DEBUG[16475][C-00000009] netsock2.c: Splitting '10.24.18.124' into... [Apr 18 19:03:34] DEBUG[16475][C-00000009] netsock2.c: ...host '10.24.18.124' and port ''. [Apr 18 19:03:34] DEBUG[16475][C-00000009] chan_sip.c: Trying to put 'SIP/2.0 401' onto UDP socket destined for 10.24.18.138:5060 [Apr 18 19:03:34] DEBUG[16475] chan_sip.c: = Looking for Call ID: Y1FimtV-XdQeeFafFp204feiZuPHDhvL (Checking From) --From tag UShJfO0-O03KpBzTpqW-dJbG.O96lxcX --To-tag as16a187d2 [Apr 18 19:03:34] DEBUG[16475][C-00000009] chan_sip.c: **** Received ACK (6) - Command in SIP ACK [Apr 18 19:03:34] DEBUG[16475][C-00000009] chan_sip.c: Stopping retransmission on 'Y1FimtV-XdQeeFafFp204feiZuPHDhvL' of Response 21033: Match Found [Apr 18 19:03:34] DEBUG[16475] chan_sip.c: = Looking for Call ID: Y1FimtV-XdQeeFafFp204feiZuPHDhvL (Checking From) --From tag UShJfO0-O03KpBzTpqW-dJbG.O96lxcX --To-tag [Apr 18 19:03:34] DEBUG[16475] netsock2.c: Splitting '10.24.18.124' into... [Apr 18 19:03:34] DEBUG[16475] netsock2.c: ...host '10.24.18.124' and port ''. [Apr 18 19:03:34] DEBUG[16475] netsock2.c: Splitting '10.24.18.124' into... [Apr 18 19:03:34] DEBUG[16475] netsock2.c: ...host '10.24.18.124' and port ''. [Apr 18 19:03:34] DEBUG[16475][C-00000009] chan_sip.c: **** Received INVITE (5) - Command in SIP INVITE [Apr 18 19:03:34] DEBUG[16475][C-00000009] netsock2.c: Splitting '10.24.18.138:5060' into... [Apr 18 19:03:34] DEBUG[16475][C-00000009] netsock2.c: ...host '10.24.18.138' and port '5060'. [Apr 18 19:03:34] DEBUG[16475][C-00000009] netsock2.c: Splitting '10.24.18.124' into... [Apr 18 19:03:34] DEBUG[16475][C-00000009] netsock2.c: ...host '10.24.18.124' and port ''. [Apr 18 19:03:34] DEBUG[16475][C-00000009] rtp_engine.c: Using engine 'asterisk' for RTP instance '0x7f0900043418' [Apr 18 19:03:34] DEBUG[16475][C-00000009] res_rtp_asterisk.c: Allocated port 14164 for RTP instance '0x7f0900043418' [Apr 18 19:03:34] DEBUG[16475][C-00000009] netsock2.c: Splitting '10.24.18.124' into... [Apr 18 19:03:34] DEBUG[16475][C-00000009] netsock2.c: ...host '10.24.18.124' and port ''. [Apr 18 19:03:34] DEBUG[16475][C-00000009] rtp_engine.c: RTP instance '0x7f0900043418' is setup and ready to go [Apr 18 19:03:34] DEBUG[16475][C-00000009] res_rtp_asterisk.c: Setup RTCP on RTP instance '0x7f0900043418' [Apr 18 19:03:34] VERBOSE[16475][C-00000009] netsock2.c: == Using SIP RTP CoS mark 5 [Apr 18 19:03:34] DEBUG[16475][C-00000009] chan_sip.c: Setting NAT on RTP to Off [Apr 18 19:03:34] DEBUG[16475][C-00000009] chan_sip.c: Processing session-level SDP v=0... UNSUPPORTED OR FAILED. [Apr 18 19:03:34] DEBUG[16475][C-00000009] chan_sip.c: Processing session-level SDP o=- 61701105 61701105 IN IP4 10.24.18.138... OK. [Apr 18 19:03:34] DEBUG[16475][C-00000009] chan_sip.c: Processing session-level SDP s=digphn... UNSUPPORTED OR FAILED. [Apr 18 19:03:34] DEBUG[16475][C-00000009] netsock2.c: Splitting '10.24.18.138' into... [Apr 18 19:03:34] DEBUG[16475][C-00000009] netsock2.c: ...host '10.24.18.138' and port ''. [Apr 18 19:03:34] DEBUG[16475][C-00000009] chan_sip.c: Processing session-level SDP c=IN IP4 10.24.18.138... OK. [Apr 18 19:03:34] DEBUG[16475][C-00000009] chan_sip.c: Processing session-level SDP t=0 0... UNSUPPORTED OR FAILED. [Apr 18 19:03:34] DEBUG[16475][C-00000009] chan_sip.c: Processing session-level SDP a=X-nat:0... UNSUPPORTED OR FAILED. [Apr 18 19:03:34] DEBUG[16475][C-00000009] rtp_engine.c: Setting payload 0 based on m type on 0x7f0915f675f0 [Apr 18 19:03:34] DEBUG[16475][C-00000009] rtp_engine.c: Setting payload 8 based on m type on 0x7f0915f675f0 [Apr 18 19:03:34] DEBUG[16475][C-00000009] rtp_engine.c: Setting payload 9 based on m type on 0x7f0915f675f0 [Apr 18 19:03:34] DEBUG[16475][C-00000009] rtp_engine.c: Setting payload 111 based on m type on 0x7f0915f675f0 [Apr 18 19:03:34] DEBUG[16475][C-00000009] rtp_engine.c: Setting payload 18 based on m type on 0x7f0915f675f0 [Apr 18 19:03:34] DEBUG[16475][C-00000009] rtp_engine.c: Setting payload 58 based on m type on 0x7f0915f675f0 [Apr 18 19:03:34] DEBUG[16475][C-00000009] rtp_engine.c: Setting payload 118 based on m type on 0x7f0915f675f0 [Apr 18 19:03:34] DEBUG[16475][C-00000009] rtp_engine.c: Setting payload 58 based on m type on 0x7f0915f675f0 [Apr 18 19:03:34] DEBUG[16475][C-00000009] rtp_engine.c: Setting payload 96 based on m type on 0x7f0915f675f0 [Apr 18 19:03:34] DEBUG[16475][C-00000009] chan_sip.c: Processing media-level (audio) SDP a=rtcp:4013 IN IP4 10.24.18.138... UNSUPPORTED OR FAILED. [Apr 18 19:03:34] DEBUG[16475][C-00000009] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:0 PCMU/8000... OK. [Apr 18 19:03:34] DEBUG[16475][C-00000009] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:8 PCMA/8000... OK. [Apr 18 19:03:34] DEBUG[16475][C-00000009] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:9 G722/8000... OK. [Apr 18 19:03:34] DEBUG[16475][C-00000009] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:111 G726-32/8000... OK. [Apr 18 19:03:34] DEBUG[16475][C-00000009] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:18 G729/8000... OK. [Apr 18 19:03:34] DEBUG[16475][C-00000009] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:58 L16/16000... OK. [Apr 18 19:03:34] DEBUG[16475][C-00000009] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:118 L16/8000... OK. [Apr 18 19:03:34] DEBUG[16475][C-00000009] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:58 L16-256/16000... OK. [Apr 18 19:03:34] DEBUG[16475][C-00000009] chan_sip.c: Processing media-level (audio) SDP a=sendrecv... OK. [Apr 18 19:03:34] DEBUG[16475][C-00000009] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:96 telephone-event/8000... OK. [Apr 18 19:03:34] DEBUG[16475][C-00000009] chan_sip.c: Processing media-level (audio) SDP a=fmtp:96 0-15... UNSUPPORTED OR FAILED. [Apr 18 19:03:34] DEBUG[16475][C-00000009] res_rtp_asterisk.c: Setting RTCP address on RTP instance '0x7f0900043418' [Apr 18 19:03:34] DEBUG[16475][C-00000009] rtp_engine.c: Copying payload 0 from 0x7f0915f675f0 to 0x7f09000435e0 [Apr 18 19:03:34] DEBUG[16475][C-00000009] rtp_engine.c: Copying payload 8 from 0x7f0915f675f0 to 0x7f09000435e0 [Apr 18 19:03:34] DEBUG[16475][C-00000009] rtp_engine.c: Copying payload 9 from 0x7f0915f675f0 to 0x7f09000435e0 [Apr 18 19:03:34] DEBUG[16475][C-00000009] rtp_engine.c: Copying payload 18 from 0x7f0915f675f0 to 0x7f09000435e0 [Apr 18 19:03:34] DEBUG[16475][C-00000009] rtp_engine.c: Copying payload 58 from 0x7f0915f675f0 to 0x7f09000435e0 [Apr 18 19:03:34] DEBUG[16475][C-00000009] rtp_engine.c: Copying payload 96 from 0x7f0915f675f0 to 0x7f09000435e0 [Apr 18 19:03:34] DEBUG[16475][C-00000009] rtp_engine.c: Copying payload 111 from 0x7f0915f675f0 to 0x7f09000435e0 [Apr 18 19:03:34] DEBUG[16475][C-00000009] rtp_engine.c: Copying payload 118 from 0x7f0915f675f0 to 0x7f09000435e0 [Apr 18 19:03:34] DEBUG[16475][C-00000009] res_rtp_asterisk.c: Ignoring duplicate RTCP property on RTP instance '0x7f0900043418' [Apr 18 19:03:34] DEBUG[16475][C-00000009] chan_sip.c: We're settling with these formats: (ulaw) [Apr 18 19:03:34] DEBUG[16475][C-00000009] chan_sip.c: Checking SIP call limits for device 6002 [Apr 18 19:03:34] DEBUG[16475][C-00000009] chan_sip.c: Updating call counter for incoming call [Apr 18 19:03:34] DEBUG[16475][C-00000009] netsock2.c: Splitting '10.24.18.124' into... [Apr 18 19:03:34] DEBUG[16475][C-00000009] netsock2.c: ...host '10.24.18.124' and port ''. [Apr 18 19:03:34] DEBUG[16475][C-00000009] netsock2.c: Splitting '10.24.18.124' into... [Apr 18 19:03:34] DEBUG[16475][C-00000009] netsock2.c: ...host '10.24.18.124' and port ''. [Apr 18 19:03:34] DEBUG[16475][C-00000009] chan_sip.c: *** Our native formats are (ulaw) [Apr 18 19:03:34] DEBUG[16475][C-00000009] chan_sip.c: *** Joint capabilities are (ulaw) [Apr 18 19:03:34] DEBUG[16475][C-00000009] chan_sip.c: *** Our capabilities are (ulaw) [Apr 18 19:03:34] DEBUG[16475][C-00000009] chan_sip.c: *** AST_CODEC_CHOOSE formats are ulaw [Apr 18 19:03:34] DEBUG[16475][C-00000009] chan_sip.c: This channel will not be able to handle video. [Apr 18 19:03:34] DEBUG[16475][C-00000009] chan_sip.c: build_route: Contact hop: "RustyTWO" [Apr 18 19:03:34] DEBUG[16475][C-00000009] chan_sip.c: Incoming INVITE with 'timer' option supported [Apr 18 19:03:34] DEBUG[16475][C-00000009] chan_sip.c: INVITE also has "Session-Expires" header. [Apr 18 19:03:34] DEBUG[16475][C-00000009] chan_sip.c: Session-Expires: 1800 [Apr 18 19:03:34] DEBUG[16475][C-00000009] chan_sip.c: INVITE also has "Min-SE" header. [Apr 18 19:03:34] DEBUG[16475][C-00000009] chan_sip.c: Received Min-SE: 90 [Apr 18 19:03:34] DEBUG[16475][C-00000009] chan_sip.c: Session timer started: 2142 - Y1FimtV-XdQeeFafFp204feiZuPHDhvL [Apr 18 19:03:34] DEBUG[16475][C-00000009] chan_sip.c: SIP/6002-00000006: New call is still down.... Trying... [Apr 18 19:03:34] DEBUG[16475][C-00000009] chan_sip.c: Trying to put 'SIP/2.0 100' onto UDP socket destined for 10.24.18.138:5060 [Apr 18 19:03:34] DEBUG[16464] devicestate.c: No provider found, checking channel drivers for SIP - 6002 [Apr 18 19:03:34] DEBUG[16464] chan_sip.c: Checking device state for peer 6002 [Apr 18 19:03:34] DEBUG[16464] devicestate.c: Changing state for SIP/6002 - state 1 (Not in use) [Apr 18 19:03:34] DEBUG[16464] devicestate.c: device 'SIP/6002' state '1' [Apr 18 19:03:34] DEBUG[17070][C-00000009] pbx.c: Launching 'Wait' [Apr 18 19:03:34] VERBOSE[17070][C-00000009] pbx.c: -- Executing [1111@internal:1] Wait("SIP/6002-00000006", "1") in new stack [Apr 18 19:03:34] DEBUG[16499] app_queue.c: Device 'SIP/6002' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. [Apr 18 19:03:35] DEBUG[17070][C-00000009] pbx.c: Launching 'Answer' [Apr 18 19:03:35] VERBOSE[17070][C-00000009] pbx.c: -- Executing [1111@internal:2] Answer("SIP/6002-00000006", "") in new stack [Apr 18 19:03:35] DEBUG[17070][C-00000009] chan_sip.c: SIP answering channel: SIP/6002-00000006 [Apr 18 19:03:35] DEBUG[17070][C-00000009] res_rtp_asterisk.c: Setting the marker bit due to a source update [Apr 18 19:03:35] DEBUG[16464] devicestate.c: No provider found, checking channel drivers for SIP - 6002 [Apr 18 19:03:35] DEBUG[16464] chan_sip.c: Checking device state for peer 6002 [Apr 18 19:03:35] DEBUG[16464] devicestate.c: Changing state for SIP/6002 - state 1 (Not in use) [Apr 18 19:03:35] DEBUG[16464] devicestate.c: device 'SIP/6002' state '1' [Apr 18 19:03:35] DEBUG[17070][C-00000009] chan_sip.c: Setting framing from config on incoming call [Apr 18 19:03:35] DEBUG[16499] app_queue.c: Device 'SIP/6002' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. [Apr 18 19:03:35] DEBUG[17070][C-00000009] chan_sip.c: ** Our capability: (ulaw) Video flag: True Text flag: True [Apr 18 19:03:35] DEBUG[17070][C-00000009] chan_sip.c: ** Our prefcodec: (nothing) [Apr 18 19:03:35] DEBUG[17070][C-00000009] chan_sip.c: -- Done with adding codecs to SDP [Apr 18 19:03:35] DEBUG[17070][C-00000009] chan_sip.c: Done building SDP. Settling with this capability: (ulaw) [Apr 18 19:03:35] DEBUG[17070][C-00000009] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 10.24.18.138:5060 [Apr 18 19:03:35] DEBUG[16475] chan_sip.c: ** SIP timers: Rescheduling retransmission 2 to 200 ms (t1 100 ms (Retrans id #2144)) [Apr 18 19:03:35] DEBUG[16475] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 10.24.18.138:5060 [Apr 18 19:03:35] DEBUG[17070][C-00000009] res_rtp_asterisk.c: 0x7f0900055010 -- Probation learning mode pass with source address 10.24.18.138:4012 [Apr 18 19:03:35] DEBUG[17070][C-00000009] pbx.c: Launching 'Dial' [Apr 18 19:03:35] VERBOSE[17070][C-00000009] pbx.c: -- Executing [1111@internal:3] Dial("SIP/6002-00000006", "MulticastRTP/basic/239.255.255.245:5555") in new stack [Apr 18 19:03:35] DEBUG[17070][C-00000009] netsock2.c: Splitting '239.255.255.245:5555' into... [Apr 18 19:03:35] DEBUG[17070][C-00000009] netsock2.c: ...host '239.255.255.245' and port '5555'. [Apr 18 19:03:35] DEBUG[17070][C-00000009] rtp_engine.c: Using engine 'multicast' for RTP instance '0x7f08f0027108' [Apr 18 19:03:35] DEBUG[17070][C-00000009] rtp_engine.c: RTP instance '0x7f08f0027108' is setup and ready to go [Apr 18 19:03:35] DEBUG[17070][C-00000009] rtp_engine.c: Can't find native functions for channel 'MulticastRTP/0x7f08f0027108' [Apr 18 19:03:35] DEBUG[17070][C-00000009] channel.c: Not copying variable DIALEDTIME. [Apr 18 19:03:35] DEBUG[17070][C-00000009] channel.c: Not copying variable ANSWEREDTIME. [Apr 18 19:03:35] DEBUG[17070][C-00000009] channel.c: Not copying variable DIALEDPEERNAME. [Apr 18 19:03:35] DEBUG[17070][C-00000009] channel.c: Not copying variable DIALEDPEERNUMBER. [Apr 18 19:03:35] DEBUG[17070][C-00000009] channel.c: Not copying variable DIALSTATUS. [Apr 18 19:03:35] DEBUG[17070][C-00000009] channel.c: Not copying variable SIPCALLID. [Apr 18 19:03:35] DEBUG[17070][C-00000009] channel.c: Not copying variable SIPDOMAIN. [Apr 18 19:03:35] DEBUG[17070][C-00000009] channel.c: Not copying variable SIPURI. [Apr 18 19:03:35] VERBOSE[17070][C-00000009] app_dial.c: -- Called MulticastRTP/basic/239.255.255.245:5555 [Apr 18 19:03:35] DEBUG[16464] devicestate.c: No provider found, checking channel drivers for MulticastRTP - 0x7f08f0027108 [Apr 18 19:03:35] DEBUG[16464] devicestate.c: Changing state for MulticastRTP/0x7f08f0027108 - state 0 (Unknown) [Apr 18 19:03:35] DEBUG[16464] devicestate.c: device 'MulticastRTP/0x7f08f0027108' state '0' [Apr 18 19:03:35] VERBOSE[17070][C-00000009] app_dial.c: -- MulticastRTP/0x7f08f0027108 answered SIP/6002-00000006 [Apr 18 19:03:35] DEBUG[17070][C-00000009] features.c: bridge answer set, chan answer set [Apr 18 19:03:35] DEBUG[17070][C-00000009] features.c: Removing dialed interfaces datastore on MulticastRTP/0x7f08f0027108 since we're bridging [Apr 18 19:03:35] DEBUG[17070][C-00000009] res_rtp_asterisk.c: Setting the marker bit due to a source update [Apr 18 19:03:35] DEBUG[16499] app_queue.c: Device 'MulticastRTP/0x7f08f0027108' changed to state '0' (Unknown) but we don't care because they're not a member of any queue. [Apr 18 19:03:35] DEBUG[16475] chan_sip.c: = Looking for Call ID: Y1FimtV-XdQeeFafFp204feiZuPHDhvL (Checking From) --From tag UShJfO0-O03KpBzTpqW-dJbG.O96lxcX --To-tag as08956ff9 [Apr 18 19:03:35] DEBUG[16475][C-00000009] chan_sip.c: **** Received ACK (6) - Command in SIP ACK [Apr 18 19:03:35] DEBUG[16475][C-00000009] chan_sip.c: Stopping retransmission on 'Y1FimtV-XdQeeFafFp204feiZuPHDhvL' of Response 21034: Match Found [Apr 18 19:03:35] DEBUG[16475] chan_sip.c: = Looking for Call ID: Y1FimtV-XdQeeFafFp204feiZuPHDhvL (Checking From) --From tag UShJfO0-O03KpBzTpqW-dJbG.O96lxcX --To-tag as08956ff9 [Apr 18 19:03:35] DEBUG[16475][C-00000009] chan_sip.c: **** Received ACK (6) - Command in SIP ACK [Apr 18 19:03:40] DEBUG[17070][C-00000009] res_rtp_asterisk.c: Got RTCP report of 40 bytes [Apr 18 19:03:40] DEBUG[17070][C-00000009] res_rtp_asterisk.c: Got RTCP report of 48 bytes [Apr 18 19:03:41] DEBUG[16475] chan_sip.c: = Looking for Call ID: Y1FimtV-XdQeeFafFp204feiZuPHDhvL (Checking From) --From tag UShJfO0-O03KpBzTpqW-dJbG.O96lxcX --To-tag as08956ff9 [Apr 18 19:03:41] DEBUG[16475][C-00000009] chan_sip.c: **** Received BYE (8) - Command in SIP BYE [Apr 18 19:03:41] DEBUG[16475][C-00000009] netsock2.c: Splitting '10.24.18.138:5060' into... [Apr 18 19:03:41] DEBUG[16475][C-00000009] netsock2.c: ...host '10.24.18.138' and port '5060'. [Apr 18 19:03:41] DEBUG[16475][C-00000009] chan_sip.c: Setting SIP_ALREADYGONE on dialog Y1FimtV-XdQeeFafFp204feiZuPHDhvL [Apr 18 19:03:41] DEBUG[16475][C-00000009] res_rtp_asterisk.c: Setting RTCP address on RTP instance '0x7f0900043418' [Apr 18 19:03:41] DEBUG[16475][C-00000009] chan_sip.c: Session timer stopped: 2142 - Y1FimtV-XdQeeFafFp204feiZuPHDhvL [Apr 18 19:03:41] DEBUG[16475][C-00000009] chan_sip.c: Received bye, issuing owner hangup [Apr 18 19:03:41] DEBUG[16475][C-00000009] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 10.24.18.138:5060 [Apr 18 19:03:41] DEBUG[17070][C-00000009] channel.c: Didn't get a frame from channel: SIP/6002-00000006 [Apr 18 19:03:41] DEBUG[17070][C-00000009] channel.c: Bridge stops bridging channels SIP/6002-00000006 and MulticastRTP/0x7f08f0027108 [Apr 18 19:03:41] DEBUG[17070][C-00000009] channel.c: Hanging up channel 'MulticastRTP/0x7f08f0027108' [Apr 18 19:03:41] DEBUG[17070][C-00000009] rtp_engine.c: Destroyed RTP instance '0x7f08f0027108' [Apr 18 19:03:41] DEBUG[16464] devicestate.c: No provider found, checking channel drivers for MulticastRTP - 0x7f08f0027108 [Apr 18 19:03:41] DEBUG[16464] devicestate.c: Changing state for MulticastRTP/0x7f08f0027108 - state 0 (Unknown) [Apr 18 19:03:41] DEBUG[16464] devicestate.c: device 'MulticastRTP/0x7f08f0027108' state '0' [Apr 18 19:03:41] DEBUG[16499] app_queue.c: Device 'MulticastRTP/0x7f08f0027108' changed to state '0' (Unknown) but we don't care because they're not a member of any queue. [Apr 18 19:03:41] DEBUG[17070][C-00000009] app_dial.c: Exiting with DIALSTATUS=ANSWER. [Apr 18 19:03:41] DEBUG[17070][C-00000009] pbx.c: Spawn extension (internal,1111,3) exited non-zero on 'SIP/6002-00000006' [Apr 18 19:03:41] VERBOSE[17070][C-00000009] pbx.c: == Spawn extension (internal, 1111, 3) exited non-zero on 'SIP/6002-00000006' [Apr 18 19:03:41] DEBUG[17070][C-00000009] channel.c: Soft-Hanging up channel 'SIP/6002-00000006' [Apr 18 19:03:41] DEBUG[17070][C-00000009] channel.c: Hanging up channel 'SIP/6002-00000006' [Apr 18 19:03:41] DEBUG[17070][C-00000009] chan_sip.c: Hangup call SIP/6002-00000006, SIP callid Y1FimtV-XdQeeFafFp204feiZuPHDhvL [Apr 18 19:03:41] DEBUG[17070][C-00000009] res_rtp_asterisk.c: Setting RTCP address on RTP instance '0x7f0900043418' [Apr 18 19:03:41] DEBUG[16464] devicestate.c: No provider found, checking channel drivers for SIP - 6002 [Apr 18 19:03:41] DEBUG[16464] chan_sip.c: Checking device state for peer 6002 [Apr 18 19:03:41] DEBUG[16464] devicestate.c: Changing state for SIP/6002 - state 1 (Not in use) [Apr 18 19:03:41] DEBUG[16464] devicestate.c: device 'SIP/6002' state '1' [Apr 18 19:03:41] DEBUG[16499] app_queue.c: Device 'SIP/6002' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. [Apr 18 19:03:47] DEBUG[16475] chan_sip.c: Auto destroying SIP dialog 'Y1FimtV-XdQeeFafFp204feiZuPHDhvL' [Apr 18 19:03:47] DEBUG[16475] chan_sip.c: Destroying SIP dialog Y1FimtV-XdQeeFafFp204feiZuPHDhvL [Apr 18 19:03:47] DEBUG[16475] rtp_engine.c: Destroyed RTP instance '0x7f0900043418'