*CLI> core set debug 1
Core debug was 0 and is now 1
*CLI> [Aug 14 08:45:00] DEBUG[22420]: chan_sip.c:4429 sip_alloc: Allocating new SIP dialog for mxabxvmcqykwipe@192.168.0.126 - REGISTER (No RTP)
[Aug 14 08:45:00] DEBUG[22420]: chan_sip.c:4429 sip_alloc: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP)

*CLI> [Aug 14 08:45:14] DEBUG[22420]: chan_sip.c:4429 sip_alloc: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP)
[Aug 14 08:45:18] DEBUG[22420]: chan_sip.c:2060 __sip_autodestruct: Auto destroying SIP dialog '2d8ff5881a8ca815230a30284def39b4@192.168.0.126'
[Aug 14 08:45:28] DEBUG[22420]: chan_sip.c:4429 sip_alloc: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP)
[Aug 14 08:45:42] DEBUG[22420]: chan_sip.c:4429 sip_alloc: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP)
[Aug 14 08:45:56] DEBUG[22420]: chan_sip.c:4429 sip_alloc: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP)
[Aug 14 08:46:10] DEBUG[22420]: chan_sip.c:4429 sip_alloc: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP)
[Aug 14 08:46:24] DEBUG[22420]: chan_sip.c:4429 sip_alloc: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP)
[Aug 14 08:46:38] DEBUG[22420]: chan_sip.c:4429 sip_alloc: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP)

*CLI> [Aug 14 08:46:52] DEBUG[22420]: chan_sip.c:4429 sip_alloc: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP)
[Aug 14 08:47:03] DEBUG[22420]: chan_sip.c:2676 do_setnat: Setting NAT on RTP to Off
[Aug 14 08:47:03] DEBUG[22420]: chan_sip.c:4429 sip_alloc: Allocating new SIP dialog for mgpgxyfprmhjipa@192.168.0.126 - INVITE (With RTP)
[Aug 14 08:47:03] DEBUG[22420]: chan_sip.c:2676 do_setnat: Setting NAT on RTP to Off
[Aug 14 08:47:06] DEBUG[22420]: chan_sip.c:4429 sip_alloc: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP)
[Aug 14 08:47:20] DEBUG[22420]: chan_sip.c:4429 sip_alloc: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP)
[Aug 14 08:47:34] DEBUG[22420]: chan_sip.c:4429 sip_alloc: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP)
[Aug 14 08:47:35] DEBUG[22420]: chan_sip.c:2060 __sip_autodestruct: Auto destroying SIP dialog 'mgpgxyfprmhjipa@192.168.0.126'
[Aug 14 08:47:38] DEBUG[22420]: chan_sip.c:4429 sip_alloc: Allocating new SIP dialog for wkotfutstrsimlm@192.168.0.126 - REGISTER (No RTP)
[Aug 14 08:47:44] DEBUG[22420]: chan_sip.c:15105 sipsock_read: Invalid SIP message - rejected , no callid, len 484
[Aug 14 08:47:48] DEBUG[22420]: chan_sip.c:4429 sip_alloc: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP)
[Aug 14 08:47:48] DEBUG[22420]: chan_sip.c:2139 __sip_ack: Stopping retransmission on '60e84fd25b3d004263ac243c4e54339d@192.168.0.126' of Request 102: Match Not Found
[Aug 14 08:47:48] NOTICE[22420]: chan_sip.c:12308 handle_response_peerpoke: Peer '666' is now Reachable. (2ms / 1000ms)
[Aug 14 08:47:53] DEBUG[22420]: chan_sip.c:4429 sip_alloc: Allocating new SIP dialog for (No Call-ID) - NOTIFY (No RTP)
[Aug 14 08:47:53] DEBUG[22420]: chan_sip.c:2139 __sip_ack: Stopping retransmission on '1526cec573666f6c299274ae04d6d669@192.168.0.126' of Request 102: Match Not Found
[Aug 14 08:47:56] DEBUG[22420]: chan_sip.c:2060 __sip_autodestruct: Auto destroying SIP dialog 'mxabxvmcqykwipe@192.168.0.126'
[Aug 14 08:48:00] DEBUG[22420]: chan_sip.c:2676 do_setnat: Setting NAT on RTP to Off
[Aug 14 08:48:00] DEBUG[22420]: chan_sip.c:4429 sip_alloc: Allocating new SIP dialog for wphgypdsmcajczp@192.168.0.126 - INVITE (With RTP)
[Aug 14 08:48:00] DEBUG[22420]: chan_sip.c:2676 do_setnat: Setting NAT on RTP to Off
[Aug 14 08:48:00] DEBUG[22420]: chan_sip.c:2139 __sip_ack: Stopping retransmission on 'wphgypdsmcajczp@192.168.0.126' of Response 697: Match Not Found
[Aug 14 08:48:00] DEBUG[22420]: chan_sip.c:2676 do_setnat: Setting NAT on RTP to Off
[Aug 14 08:48:00] DEBUG[22420]: chan_sip.c:13603 handle_request_invite: Checking SIP call limits for device 666
[Aug 14 08:48:00] DEBUG[22487]: pbx.c:1809 pbx_extension_helper: Launching 'MixMonitor'
    -- Executing [456@from-internal:1] MixMonitor("SIP/666-081f2238", "rec-1187074080.0.wav") in new stack
[Aug 14 08:48:00] DEBUG[22487]: channel.c:1420 ast_channel_spy_add: Spy MixMonitor added to channel SIP/666-081f2238
[Aug 14 08:48:00] DEBUG[22487]: pbx.c:1809 pbx_extension_helper: Launching 'Dial'
    -- Executing [456@from-internal:2] Dial("SIP/666-081f2238", "Mobile/nokia/800123456") in new stack
  == Begin MixMonitor Recording SIP/666-081f2238
[Aug 14 08:48:00] DEBUG[22487]: rtp.c:1574 ast_rtp_make_compatible: Channel 'Mobile/nokia-66c4' has no RTP, not doing anything
[Aug 14 08:48:00] DEBUG[22487]: channel.c:3491 ast_channel_inherit_variables: Not copying variable STACK-from-internal-456-2.
[Aug 14 08:48:00] DEBUG[22487]: channel.c:3491 ast_channel_inherit_variables: Not copying variable MIXMONITOR_FILENAME.
[Aug 14 08:48:00] DEBUG[22487]: channel.c:3491 ast_channel_inherit_variables: Not copying variable STACK-from-internal-456-1.
[Aug 14 08:48:00] DEBUG[22487]: channel.c:3491 ast_channel_inherit_variables: Not copying variable SIPCALLID.
[Aug 14 08:48:00] DEBUG[22487]: channel.c:3491 ast_channel_inherit_variables: Not copying variable SIPUSERAGENT.
[Aug 14 08:48:00] DEBUG[22487]: channel.c:3491 ast_channel_inherit_variables: Not copying variable SIPDOMAIN.
[Aug 14 08:48:00] DEBUG[22487]: channel.c:3491 ast_channel_inherit_variables: Not copying variable SIPURI.
[Aug 14 08:48:00] DEBUG[22487]: chan_mobile.c:522 mbl_call: Calling nokia/800123456 on Mobile/nokia-66c4
    -- Called nokia/800123456
[Aug 14 08:48:00] DEBUG[22487]: channel.c:3020 set_format: Set channel Mobile/nokia-66c4 to read format ulaw
[Aug 14 08:48:00] DEBUG[22487]: channel.c:3020 set_format: Set channel SIP/666-081f2238 to read format slin
[Aug 14 08:48:01] DEBUG[22423]: chan_mobile.c:877 rfcomm_write: rfcomm_write() (]okia) [ATD800123456;
[Aug 14 08:48:01] DEBUG[22423]: chan_mobile.c:1167 do_monitor_phone: rfcomm_read() (nokia) [OK]
[Aug 14 08:48:01] DEBUG[22396]: chan_mobile.c:774 mbl_devicestate: Checking device state for device nokia
[Aug 14 08:48:01] DEBUG[22423]: chan_mobile.c:1167 do_monitor_phone: rfcomm_read() (nokia) [+CIEV: 3,2]
[Aug 14 08:48:01] DEBUG[22423]: chan_mobile.c:1167 do_monitor_phone: rfcomm_read() (nokia) [+CIEV: 4,2]
[Aug 14 08:48:05] NOTICE[22424]: chan_mobile.c:1695 do_sco_listen: sco_socket returns 25...
[Aug 14 08:48:05] DEBUG[22424]: chan_mobile.c:1701 do_sco_listen: Incoming Audio Connection from device 00:1A:16:E3:C6:FB MTU is 64
[Aug 14 08:48:05] NOTICE[22424]: chan_mobile.c:1709 do_sco_listen: about to close the pvt-sco_socket and set it ns
[Aug 14 08:48:05] NOTICE[22424]: chan_mobile.c:1693 do_sco_listen: About to accept the sco_socket...
[Aug 14 08:48:06] DEBUG[22423]: chan_mobile.c:1167 do_monitor_phone: rfcomm_read() (nokia) [+CIEV: 3,3]
[Aug 14 08:48:06] DEBUG[22423]: chan_mobile.c:1167 do_monitor_phone: rfcomm_read() (nokia) [+CIEV: 4,3]
    -- Mobile/nokia-66c4 is ringing
[Aug 14 08:48:06] DEBUG[22487]: rtp.c:1499 ast_rtp_early_bridge: Channel 'Mobile/nokia-66c4' has no RTP, not doing anything
[Aug 14 08:48:06] DEBUG[22487]: channel.c:1601 queue_frame_to_spies: Building translator from ulaw to SLINEAR for spies on channel SIP/666-081f2238
[Aug 14 08:48:06] DEBUG[22487]: chan_sip.c:6553 transmit_response_with_sdp: Setting framing from config on incoming call
[Aug 14 08:48:06] DEBUG[22487]: rtp.c:2727 ast_rtp_write: Ooh, format changed from unknown to ulaw
[Aug 14 08:48:06] DEBUG[22487]: rtp.c:2744 ast_rtp_write: Created smoother: format: 4 ms: 20 len: 160
[Aug 14 08:48:06] DEBUG[22423]: chan_mobile.c:1167 do_monitor_phone: rfcomm_read() (nokia) [+CIEV: 1,1]
    -- Mobile/nokia-66c4 answered SIP/666-081f2238
[Aug 14 08:48:06] DEBUG[22487]: rtp.c:1499 ast_rtp_early_bridge: Channel 'Mobile/nokia-66c4' has no RTP, not doing anything
[Aug 14 08:48:06] DEBUG[22487]: chan_sip.c:3579 sip_answer: SIP answering channel: SIP/666-081f2238
[Aug 14 08:48:06] DEBUG[22487]: chan_sip.c:6553 transmit_response_with_sdp: Setting framing from config on incoming call
[Aug 14 08:48:06] DEBUG[22423]: chan_mobile.c:1167 do_monitor_phone: rfcomm_read() (nokia) [+CIEV: 3,0]
[Aug 14 08:48:06] DEBUG[22423]: chan_mobile.c:1167 do_monitor_phone: rfcomm_read() (nokia) [+CIEV: 4,0]
[Aug 14 08:48:06] DEBUG[22396]: chan_mobile.c:774 mbl_devicestate: Checking device state for device nokia
[Aug 14 08:48:06] DEBUG[22487]: rtp.c:2744 ast_rtp_write: Created smoother: format: 4 ms: 20 len: 160
[Aug 14 08:48:06] DEBUG[22420]: chan_sip.c:2139 __sip_ack: Stopping retransmission on 'wphgypdsmcajczp@192.168.0.126' of Response 698: Match Not Found
[Aug 14 08:48:06] DEBUG[22487]: rtp.c:875 ast_rtcp_read: Got RTCP report of 36 bytes
[Aug 14 08:48:06] DEBUG[22487]: chan_sip.c:4211 sip_rtp_read: Oooh, format changed to 2
[Aug 14 08:48:06] DEBUG[22487]: channel.c:3020 set_format: Set channel SIP/666-081f2238 to read format slin
[Aug 14 08:48:06] DEBUG[22487]: channel.c:3020 set_format: Set channel SIP/666-081f2238 to write format ulaw
[Aug 14 08:48:06] DEBUG[22487]: channel.c:1601 queue_frame_to_spies: Building translator from gsm to SLINEAR for spies on channel SIP/666-081f2238
[Aug 14 08:48:06] DEBUG[22487]: channel.c:1601 queue_frame_to_spies: Building translator from gsm to SLINEAR for spies on channel SIP/666-081f2238
[Aug 14 08:48:06] DEBUG[22487]: rtp.c:2727 ast_rtp_write: Ooh, format changed from ulaw to gsm
[Aug 14 08:48:06] DEBUG[22487]: rtp.c:2744 ast_rtp_write: Created smoother: format: 2 ms: 20 len: 33
[Aug 14 08:48:11] DEBUG[22487]: channel.c:3991 ast_generic_bridge: Didn't get a frame from channel: SIP/666-081f2238
[Aug 14 08:48:11] DEBUG[22487]: channel.c:4315 ast_channel_bridge: Bridge stops bridging channels SIP/666-081f2238 and Mobile/nokia-66c4
[Aug 14 08:48:11] DEBUG[22487]: channel.c:1764 ast_hangup: Hanging up channel 'Mobile/nokia-66c4'
[Aug 14 08:48:11] DEBUG[22487]: chan_mobile.c:549 mbl_hangup: Hanging up device nokia.
[Aug 14 08:48:11] DEBUG[22487]: chan_mobile.c:877 rfcomm_write: rfcomm_write() (]okia) [AT+CHUP
[Aug 14 08:48:11] DEBUG[22487]: rtp.c:1499 ast_rtp_early_bridge: Channel '<unspecified>' has no RTP, not doing anything
[Aug 14 08:48:11] DEBUG[22487]: app_dial.c:1686 dial_exec_full: Exiting with DIALSTATUS=ANSWER.
[Aug 14 08:48:11] DEBUG[22487]: pbx.c:2407 __ast_pbx_run: Spawn extension (from-internal,456,2) exited non-zero on 'SIP/666-081f2238'
  == Spawn extension (from-internal, 456, 2) exited non-zero on 'SIP/666-081f2238'
[Aug 14 08:48:11] DEBUG[22487]: channel.c:1547 ast_softhangup_nolock: Soft-Hanging up channel 'SIP/666-081f2238'
[Aug 14 08:48:11] DEBUG[22487]: channel.c:1457 spy_detach: Spy MixMonitor removed from channel SIP/666-081f2238
[Aug 14 08:48:11] DEBUG[22487]: channel.c:1764 ast_hangup: Hanging up channel 'SIP/666-081f2238'
[Aug 14 08:48:11] DEBUG[22487]: chan_sip.c:3422 sip_hangup: Hangup call SIP/666-081f2238, SIP callid wphgypdsmcajczp@192.168.0.126)
[Aug 14 08:48:11] DEBUG[22396]: chan_mobile.c:774 mbl_devicestate: Checking device state for device nokia
[Aug 14 08:48:11] DEBUG[22396]: chan_mobile.c:774 mbl_devicestate: Checking device state for device nokia
  == End MixMonitor Recording SIP/666-081f2238
[Aug 14 08:48:11] DEBUG[22423]: chan_mobile.c:1167 do_monitor_phone: rfcomm_read() (nokia) [OK]
[Aug 14 08:48:11] DEBUG[22423]: chan_mobile.c:1167 do_monitor_phone: rfcomm_read() (nokia) [+CIEV: 1,0]
[Aug 14 08:48:11] DEBUG[22423]: chan_mobile.c:1260 do_monitor_phone: Device nokia [+CIEV: 1,0]
[Aug 14 08:48:15] DEBUG[22420]: chan_sip.c:2676 do_setnat: Setting NAT on RTP to Off
[Aug 14 08:48:15] DEBUG[22420]: chan_sip.c:4429 sip_alloc: Allocating new SIP dialog for wvktclthdrxzcor@192.168.0.126 - INVITE (With RTP)
[Aug 14 08:48:15] DEBUG[22420]: chan_sip.c:2676 do_setnat: Setting NAT on RTP to Off
[Aug 14 08:48:15] DEBUG[22420]: chan_sip.c:2139 __sip_ack: Stopping retransmission on 'wvktclthdrxzcor@192.168.0.126' of Response 519: Match Not Found
[Aug 14 08:48:15] DEBUG[22420]: chan_sip.c:2676 do_setnat: Setting NAT on RTP to Off
[Aug 14 08:48:15] DEBUG[22420]: chan_sip.c:13603 handle_request_invite: Checking SIP call limits for device 666
[Aug 14 08:48:15] DEBUG[22500]: pbx.c:1809 pbx_extension_helper: Launching 'MixMonitor'
    -- Executing [457@from-internal:1] MixMonitor("SIP/666-081f2238", "rec-1187074095.2.wav") in new stack
[Aug 14 08:48:15] DEBUG[22500]: channel.c:1420 ast_channel_spy_add: Spy MixMonitor added to channel SIP/666-081f2238
[Aug 14 08:48:15] DEBUG[22500]: pbx.c:1809 pbx_extension_helper: Launching 'Dial'
    -- Executing [457@from-internal:2] Dial("SIP/666-081f2238", "Mobile/nokia/*77") in new stack
[Aug 14 08:48:15] DEBUG[22500]: rtp.c:1574 ast_rtp_make_compatible: Channel 'Mobile/nokia-0d66' has no RTP, not doing anything
[Aug 14 08:48:15] DEBUG[22500]: channel.c:3491 ast_channel_inherit_variables: Not copying variable STACK-from-internal-457-2.
[Aug 14 08:48:15] DEBUG[22500]: channel.c:3491 ast_channel_inherit_variables: Not copying variable MIXMONITOR_FILENAME.
[Aug 14 08:48:15] DEBUG[22500]: channel.c:3491 ast_channel_inherit_variables: Not copying variable STACK-from-internal-457-1.
[Aug 14 08:48:15] DEBUG[22500]: channel.c:3491 ast_channel_inherit_variables: Not copying variable SIPCALLID.
[Aug 14 08:48:15] DEBUG[22500]: channel.c:3491 ast_channel_inherit_variables: Not copying variable SIPUSERAGENT.
[Aug 14 08:48:15] DEBUG[22500]: channel.c:3491 ast_channel_inherit_variables: Not copying variable SIPDOMAIN.
[Aug 14 08:48:15] DEBUG[22500]: channel.c:3491 ast_channel_inherit_variables: Not copying variable SIPURI.
[Aug 14 08:48:15] DEBUG[22500]: chan_mobile.c:522 mbl_call: Calling nokia/*77 on Mobile/nokia-0d66
    -- Called nokia/*77
[Aug 14 08:48:15] DEBUG[22500]: channel.c:3020 set_format: Set channel Mobile/nokia-0d66 to read format ulaw
[Aug 14 08:48:15] DEBUG[22500]: channel.c:3020 set_format: Set channel SIP/666-081f2238 to read format slin
  == Begin MixMonitor Recording SIP/666-081f2238
[Aug 14 08:48:15] DEBUG[22423]: chan_mobile.c:877 rfcomm_write: rfcomm_write() (]okia) [ATD*77;
[Aug 14 08:48:15] DEBUG[22423]: chan_mobile.c:1167 do_monitor_phone: rfcomm_read() (nokia) [OK]
[Aug 14 08:48:15] DEBUG[22396]: chan_mobile.c:774 mbl_devicestate: Checking device state for device nokia
[Aug 14 08:48:15] DEBUG[22423]: chan_mobile.c:1167 do_monitor_phone: rfcomm_read() (nokia) [+CIEV: 3,2]
[Aug 14 08:48:15] DEBUG[22423]: chan_mobile.c:1167 do_monitor_phone: rfcomm_read() (nokia) [+CIEV: 4,2]
[Aug 14 08:48:16] DEBUG[22420]: chan_sip.c:2060 __sip_autodestruct: Auto destroying SIP dialog 'wkotfutstrsimlm@192.168.0.126'
[Aug 14 08:48:18] DEBUG[22423]: chan_mobile.c:1167 do_monitor_phone: rfcomm_read() (nokia) [+CIEV: 3,3]
[Aug 14 08:48:18] DEBUG[22423]: chan_mobile.c:1167 do_monitor_phone: rfcomm_read() (nokia) [+CIEV: 4,3]
    -- Mobile/nokia-0d66 is ringing
[Aug 14 08:48:18] DEBUG[22500]: rtp.c:1499 ast_rtp_early_bridge: Channel 'Mobile/nokia-0d66' has no RTP, not doing anything
[Aug 14 08:48:19] NOTICE[22424]: chan_mobile.c:1695 do_sco_listen: sco_socket returns 24...
[Aug 14 08:48:19] DEBUG[22424]: chan_mobile.c:1701 do_sco_listen: Incoming Audio Connection from device 00:1A:16:E3:C6:FB MTU is 64
[Aug 14 08:48:19] NOTICE[22424]: chan_mobile.c:1709 do_sco_listen: about to close the pvt-sco_socket and set it ns
[Aug 14 08:48:19] NOTICE[22424]: chan_mobile.c:1693 do_sco_listen: About to accept the sco_socket...
[Aug 14 08:48:20] DEBUG[22423]: chan_mobile.c:1167 do_monitor_phone: rfcomm_read() (nokia) [+CIEV: 1,1]
[Aug 14 08:48:20] DEBUG[22396]: chan_mobile.c:774 mbl_devicestate: Checking device state for device nokia
    -- Mobile/nokia-0d66 answered SIP/666-081f2238
[Aug 14 08:48:20] DEBUG[22500]: rtp.c:1499 ast_rtp_early_bridge: Channel 'Mobile/nokia-0d66' has no RTP, not doing anything
[Aug 14 08:48:20] DEBUG[22423]: chan_mobile.c:1167 do_monitor_phone: rfcomm_read() (nokia) [+CIEV: 3,0]
[Aug 14 08:48:20] DEBUG[22423]: chan_mobile.c:1167 do_monitor_phone: rfcomm_read() (nokia) [+CIEV: 4,0]
[Aug 14 08:48:20] DEBUG[22500]: chan_sip.c:3579 sip_answer: SIP answering channel: SIP/666-081f2238
[Aug 14 08:48:20] DEBUG[22500]: chan_sip.c:6553 transmit_response_with_sdp: Setting framing from config on incoming call
[Aug 14 08:48:20] DEBUG[22500]: channel.c:1601 queue_frame_to_spies: Building translator from ulaw to SLINEAR for spies on channel SIP/666-081f2238
[Aug 14 08:48:20] DEBUG[22500]: rtp.c:2727 ast_rtp_write: Ooh, format changed from unknown to ulaw
[Aug 14 08:48:20] DEBUG[22500]: rtp.c:2744 ast_rtp_write: Created smoother: format: 4 ms: 20 len: 160
[Aug 14 08:48:20] DEBUG[22420]: chan_sip.c:2139 __sip_ack: Stopping retransmission on 'wvktclthdrxzcor@192.168.0.126' of Response 520: Match Not Found
[Aug 14 08:48:20] DEBUG[22500]: rtp.c:875 ast_rtcp_read: Got RTCP report of 36 bytes
[Aug 14 08:48:20] DEBUG[22500]: chan_sip.c:4211 sip_rtp_read: Oooh, format changed to 2
[Aug 14 08:48:20] DEBUG[22500]: channel.c:3020 set_format: Set channel SIP/666-081f2238 to read format slin
[Aug 14 08:48:20] DEBUG[22500]: channel.c:3020 set_format: Set channel SIP/666-081f2238 to write format ulaw
[Aug 14 08:48:20] DEBUG[22500]: channel.c:1601 queue_frame_to_spies: Building translator from gsm to SLINEAR for spies on channel SIP/666-081f2238
[Aug 14 08:48:20] DEBUG[22500]: channel.c:1601 queue_frame_to_spies: Building translator from gsm to SLINEAR for spies on channel SIP/666-081f2238
[Aug 14 08:48:20] DEBUG[22500]: rtp.c:2727 ast_rtp_write: Ooh, format changed from ulaw to gsm
[Aug 14 08:48:20] DEBUG[22500]: rtp.c:2744 ast_rtp_write: Created smoother: format: 2 ms: 20 len: 33
[Aug 14 08:48:22] DEBUG[22500]: channel.c:3991 ast_generic_bridge: Didn't get a frame from channel: SIP/666-081f2238
[Aug 14 08:48:22] DEBUG[22500]: channel.c:4315 ast_channel_bridge: Bridge stops bridging channels SIP/666-081f2238 and Mobile/nokia-0d66
[Aug 14 08:48:22] DEBUG[22500]: channel.c:1764 ast_hangup: Hanging up channel 'Mobile/nokia-0d66'
[Aug 14 08:48:22] DEBUG[22500]: chan_mobile.c:549 mbl_hangup: Hanging up device nokia.
[Aug 14 08:48:22] DEBUG[22500]: chan_mobile.c:877 rfcomm_write: rfcomm_write() (]okia) [AT+CHUP
[Aug 14 08:48:22] DEBUG[22500]: rtp.c:1499 ast_rtp_early_bridge: Channel '<unspecified>' has no RTP, not doing anything
[Aug 14 08:48:22] DEBUG[22500]: app_dial.c:1686 dial_exec_full: Exiting with DIALSTATUS=ANSWER.
[Aug 14 08:48:22] DEBUG[22500]: pbx.c:2407 __ast_pbx_run: Spawn extension (from-internal,457,2) exited non-zero on 'SIP/666-081f2238'
  == Spawn extension (from-internal, 457, 2) exited non-zero on 'SIP/666-081f2238'
[Aug 14 08:48:22] DEBUG[22500]: channel.c:1547 ast_softhangup_nolock: Soft-Hanging up channel 'SIP/666-081f2238'
[Aug 14 08:48:22] DEBUG[22500]: channel.c:1457 spy_detach: Spy MixMonitor removed from channel SIP/666-081f2238
[Aug 14 08:48:22] DEBUG[22500]: channel.c:1764 ast_hangup: Hanging up channel 'SIP/666-081f2238'
[Aug 14 08:48:22] DEBUG[22500]: chan_sip.c:3422 sip_hangup: Hangup call SIP/666-081f2238, SIP callid wvktclthdrxzcor@192.168.0.126)
[Aug 14 08:48:22] DEBUG[22396]: chan_mobile.c:774 mbl_devicestate: Checking device state for device nokia
[Aug 14 08:48:22] DEBUG[22396]: chan_mobile.c:774 mbl_devicestate: Checking device state for device nokia
  == End MixMonitor Recording SIP/666-081f2238
[Aug 14 08:48:22] DEBUG[22423]: chan_mobile.c:1167 do_monitor_phone: rfcomm_read() (nokia) [OK]
[Aug 14 08:48:22] DEBUG[22423]: chan_mobile.c:1167 do_monitor_phone: rfcomm_read() (nokia) [+CIEV: 1,0]
[Aug 14 08:48:22] DEBUG[22423]: chan_mobile.c:1260 do_monitor_phone: Device nokia [+CIEV: 1,0]
[Aug 14 08:48:31] DEBUG[22420]: chan_sip.c:2676 do_setnat: Setting NAT on RTP to Off
[Aug 14 08:48:31] DEBUG[22420]: chan_sip.c:4429 sip_alloc: Allocating new SIP dialog for axqmzjbguhhjlpb@192.168.0.126 - INVITE (With RTP)
[Aug 14 08:48:31] DEBUG[22420]: chan_sip.c:2676 do_setnat: Setting NAT on RTP to Off
[Aug 14 08:48:31] DEBUG[22420]: chan_sip.c:2139 __sip_ack: Stopping retransmission on 'axqmzjbguhhjlpb@192.168.0.126' of Response 219: Match Not Found
[Aug 14 08:48:31] DEBUG[22420]: chan_sip.c:2676 do_setnat: Setting NAT on RTP to Off
[Aug 14 08:48:31] DEBUG[22420]: chan_sip.c:13603 handle_request_invite: Checking SIP call limits for device 666
[Aug 14 08:48:31] DEBUG[22513]: pbx.c:1809 pbx_extension_helper: Launching 'MixMonitor'
    -- Executing [456@from-internal:1] MixMonitor("SIP/666-081f2238", "rec-1187074111.4.wav") in new stack
[Aug 14 08:48:31] DEBUG[22513]: channel.c:1420 ast_channel_spy_add: Spy MixMonitor added to channel SIP/666-081f2238
[Aug 14 08:48:31] DEBUG[22513]: pbx.c:1809 pbx_extension_helper: Launching 'Dial'
    -- Executing [456@from-internal:2] Dial("SIP/666-081f2238", "Mobile/nokia/800123456") in new stack
[Aug 14 08:48:31] DEBUG[22513]: rtp.c:1574 ast_rtp_make_compatible: Channel 'Mobile/nokia-1b8d' has no RTP, not doing anything
[Aug 14 08:48:31] DEBUG[22513]: channel.c:3491 ast_channel_inherit_variables: Not copying variable STACK-from-internal-456-2.
[Aug 14 08:48:31] DEBUG[22513]: channel.c:3491 ast_channel_inherit_variables: Not copying variable MIXMONITOR_FILENAME.
[Aug 14 08:48:31] DEBUG[22513]: channel.c:3491 ast_channel_inherit_variables: Not copying variable STACK-from-internal-456-1.
[Aug 14 08:48:31] DEBUG[22513]: channel.c:3491 ast_channel_inherit_variables: Not copying variable SIPCALLID.
[Aug 14 08:48:31] DEBUG[22513]: channel.c:3491 ast_channel_inherit_variables: Not copying variable SIPUSERAGENT.
[Aug 14 08:48:31] DEBUG[22513]: channel.c:3491 ast_channel_inherit_variables: Not copying variable SIPDOMAIN.
[Aug 14 08:48:31] DEBUG[22513]: channel.c:3491 ast_channel_inherit_variables: Not copying variable SIPURI.
[Aug 14 08:48:31] DEBUG[22513]: chan_mobile.c:522 mbl_call: Calling nokia/800123456 on Mobile/nokia-1b8d
    -- Called nokia/800123456
[Aug 14 08:48:31] DEBUG[22513]: channel.c:3020 set_format: Set channel Mobile/nokia-1b8d to read format ulaw
[Aug 14 08:48:31] DEBUG[22513]: channel.c:3020 set_format: Set channel SIP/666-081f2238 to read format slin
  == Begin MixMonitor Recording SIP/666-081f2238
[Aug 14 08:48:31] DEBUG[22423]: chan_mobile.c:877 rfcomm_write: rfcomm_write() (]okia) [ATD800123456;
[Aug 14 08:48:32] DEBUG[22423]: chan_mobile.c:1167 do_monitor_phone: rfcomm_read() (nokia) [OK]
[Aug 14 08:48:32] DEBUG[22396]: chan_mobile.c:774 mbl_devicestate: Checking device state for device nokia
[Aug 14 08:48:32] DEBUG[22423]: chan_mobile.c:1167 do_monitor_phone: rfcomm_read() (nokia) [+CIEV: 3,2]
[Aug 14 08:48:32] DEBUG[22423]: chan_mobile.c:1167 do_monitor_phone: rfcomm_read() (nokia) [+CIEV: 4,2]
[Aug 14 08:48:35] NOTICE[22424]: chan_mobile.c:1695 do_sco_listen: sco_socket returns 25...
[Aug 14 08:48:35] DEBUG[22424]: chan_mobile.c:1701 do_sco_listen: Incoming Audio Connection from device 00:1A:16:E3:C6:FB MTU is 64
[Aug 14 08:48:35] NOTICE[22424]: chan_mobile.c:1709 do_sco_listen: about to close the pvt-sco_socket and set it ns
[Aug 14 08:48:35] NOTICE[22424]: chan_mobile.c:1693 do_sco_listen: About to accept the sco_socket...
[Aug 14 08:48:36] DEBUG[22423]: chan_mobile.c:1167 do_monitor_phone: rfcomm_read() (nokia) [+CIEV: 3,3]
[Aug 14 08:48:36] DEBUG[22423]: chan_mobile.c:1167 do_monitor_phone: rfcomm_read() (nokia) [+CIEV: 4,3]
    -- Mobile/nokia-1b8d is ringing
[Aug 14 08:48:36] DEBUG[22513]: rtp.c:1499 ast_rtp_early_bridge: Channel 'Mobile/nokia-1b8d' has no RTP, not doing anything
[Aug 14 08:48:36] DEBUG[22513]: channel.c:1601 queue_frame_to_spies: Building translator from ulaw to SLINEAR for spies on channel SIP/666-081f2238
[Aug 14 08:48:36] DEBUG[22513]: chan_sip.c:6553 transmit_response_with_sdp: Setting framing from config on incoming call
[Aug 14 08:48:36] DEBUG[22513]: rtp.c:2727 ast_rtp_write: Ooh, format changed from unknown to ulaw
[Aug 14 08:48:36] DEBUG[22513]: rtp.c:2744 ast_rtp_write: Created smoother: format: 4 ms: 20 len: 160
[Aug 14 08:48:37] DEBUG[22423]: chan_mobile.c:1167 do_monitor_phone: rfcomm_read() (nokia) [+CIEV: 1,1]
    -- Mobile/nokia-1b8d answered SIP/666-081f2238
[Aug 14 08:48:37] DEBUG[22513]: rtp.c:1499 ast_rtp_early_bridge: Channel 'Mobile/nokia-1b8d' has no RTP, not doing anything
[Aug 14 08:48:37] DEBUG[22513]: chan_sip.c:3579 sip_answer: SIP answering channel: SIP/666-081f2238
[Aug 14 08:48:37] DEBUG[22513]: chan_sip.c:6553 transmit_response_with_sdp: Setting framing from config on incoming call
[Aug 14 08:48:37] DEBUG[22396]: chan_mobile.c:774 mbl_devicestate: Checking device state for device nokia
[Aug 14 08:48:37] DEBUG[22513]: rtp.c:2744 ast_rtp_write: Created smoother: format: 4 ms: 20 len: 160
[Aug 14 08:48:37] DEBUG[22423]: chan_mobile.c:1167 do_monitor_phone: rfcomm_read() (nokia) [+CIEV: 3,0]
[Aug 14 08:48:37] DEBUG[22423]: chan_mobile.c:1167 do_monitor_phone: rfcomm_read() (nokia) [+CIEV: 4,0]
[Aug 14 08:48:37] DEBUG[22420]: chan_sip.c:2139 __sip_ack: Stopping retransmission on 'axqmzjbguhhjlpb@192.168.0.126' of Response 220: Match Not Found
[Aug 14 08:48:37] DEBUG[22513]: rtp.c:875 ast_rtcp_read: Got RTCP report of 36 bytes
[Aug 14 08:48:37] DEBUG[22513]: chan_sip.c:4211 sip_rtp_read: Oooh, format changed to 2
[Aug 14 08:48:37] DEBUG[22513]: channel.c:3020 set_format: Set channel SIP/666-081f2238 to read format slin
[Aug 14 08:48:37] DEBUG[22513]: channel.c:3020 set_format: Set channel SIP/666-081f2238 to write format ulaw
[Aug 14 08:48:37] DEBUG[22513]: channel.c:1601 queue_frame_to_spies: Building translator from gsm to SLINEAR for spies on channel SIP/666-081f2238
[Aug 14 08:48:37] DEBUG[22513]: channel.c:1601 queue_frame_to_spies: Building translator from gsm to SLINEAR for spies on channel SIP/666-081f2238
[Aug 14 08:48:37] DEBUG[22513]: rtp.c:2727 ast_rtp_write: Ooh, format changed from ulaw to gsm
[Aug 14 08:48:37] DEBUG[22513]: rtp.c:2744 ast_rtp_write: Created smoother: format: 2 ms: 20 len: 33
[Aug 14 08:48:38] DEBUG[22513]: channel.c:3991 ast_generic_bridge: Didn't get a frame from channel: SIP/666-081f2238
[Aug 14 08:48:38] DEBUG[22513]: channel.c:4315 ast_channel_bridge: Bridge stops bridging channels SIP/666-081f2238 and Mobile/nokia-1b8d
[Aug 14 08:48:38] DEBUG[22513]: channel.c:1764 ast_hangup: Hanging up channel 'Mobile/nokia-1b8d'
[Aug 14 08:48:38] DEBUG[22513]: chan_mobile.c:549 mbl_hangup: Hanging up device nokia.
[Aug 14 08:48:38] DEBUG[22513]: chan_mobile.c:877 rfcomm_write: rfcomm_write() (]okia) [AT+CHUP
[Aug 14 08:48:38] DEBUG[22513]: rtp.c:1499 ast_rtp_early_bridge: Channel '<unspecified>' has no RTP, not doing anything
[Aug 14 08:48:38] DEBUG[22513]: app_dial.c:1686 dial_exec_full: Exiting with DIALSTATUS=ANSWER.
[Aug 14 08:48:38] DEBUG[22513]: pbx.c:2407 __ast_pbx_run: Spawn extension (from-internal,456,2) exited non-zero on 'SIP/666-081f2238'
  == Spawn extension (from-internal, 456, 2) exited non-zero on 'SIP/666-081f2238'
[Aug 14 08:48:38] DEBUG[22513]: channel.c:1547 ast_softhangup_nolock: Soft-Hanging up channel 'SIP/666-081f2238'
[Aug 14 08:48:38] DEBUG[22513]: channel.c:1457 spy_detach: Spy MixMonitor removed from channel SIP/666-081f2238
[Aug 14 08:48:38] DEBUG[22513]: channel.c:1764 ast_hangup: Hanging up channel 'SIP/666-081f2238'
[Aug 14 08:48:38] DEBUG[22513]: chan_sip.c:3422 sip_hangup: Hangup call SIP/666-081f2238, SIP callid axqmzjbguhhjlpb@192.168.0.126)
[Aug 14 08:48:38] DEBUG[22396]: chan_mobile.c:774 mbl_devicestate: Checking device state for device nokia
  == End MixMonitor Recording SIP/666-081f2238
[Aug 14 08:48:38] DEBUG[22396]: chan_mobile.c:774 mbl_devicestate: Checking device state for device nokia
[Aug 14 08:48:38] DEBUG[22423]: chan_mobile.c:1167 do_monitor_phone: rfcomm_read() (nokia) [OK]
[Aug 14 08:48:38] DEBUG[22423]: chan_mobile.c:1167 do_monitor_phone: rfcomm_read() (nokia) [+CIEV: 1,0]
[Aug 14 08:48:38] DEBUG[22423]: chan_mobile.c:1260 do_monitor_phone: Device nokia [+CIEV: 1,0]
[Aug 14 08:48:42] DEBUG[22420]: chan_sip.c:2676 do_setnat: Setting NAT on RTP to Off
[Aug 14 08:48:42] DEBUG[22420]: chan_sip.c:4429 sip_alloc: Allocating new SIP dialog for rcqywaobykqjlgv@192.168.0.126 - INVITE (With RTP)
[Aug 14 08:48:42] DEBUG[22420]: chan_sip.c:2676 do_setnat: Setting NAT on RTP to Off
[Aug 14 08:48:42] DEBUG[22420]: chan_sip.c:2139 __sip_ack: Stopping retransmission on 'rcqywaobykqjlgv@192.168.0.126' of Response 226: Match Not Found
[Aug 14 08:48:42] DEBUG[22420]: chan_sip.c:2676 do_setnat: Setting NAT on RTP to Off
[Aug 14 08:48:42] DEBUG[22420]: chan_sip.c:13603 handle_request_invite: Checking SIP call limits for device 666
[Aug 14 08:48:42] DEBUG[22526]: pbx.c:1809 pbx_extension_helper: Launching 'MixMonitor'
    -- Executing [457@from-internal:1] MixMonitor("SIP/666-081f0a30", "rec-1187074122.6.wav") in new stack
[Aug 14 08:48:42] DEBUG[22526]: channel.c:1420 ast_channel_spy_add: Spy MixMonitor added to channel SIP/666-081f0a30
[Aug 14 08:48:42] DEBUG[22526]: pbx.c:1809 pbx_extension_helper: Launching 'Dial'
    -- Executing [457@from-internal:2] Dial("SIP/666-081f0a30", "Mobile/nokia/*77") in new stack
[Aug 14 08:48:42] DEBUG[22526]: rtp.c:1574 ast_rtp_make_compatible: Channel 'Mobile/nokia-6796' has no RTP, not doing anything
[Aug 14 08:48:42] DEBUG[22526]: channel.c:3491 ast_channel_inherit_variables: Not copying variable STACK-from-internal-457-2.
[Aug 14 08:48:42] DEBUG[22526]: channel.c:3491 ast_channel_inherit_variables: Not copying variable MIXMONITOR_FILENAME.
[Aug 14 08:48:42] DEBUG[22526]: channel.c:3491 ast_channel_inherit_variables: Not copying variable STACK-from-internal-457-1.
[Aug 14 08:48:42] DEBUG[22526]: channel.c:3491 ast_channel_inherit_variables: Not copying variable SIPCALLID.
[Aug 14 08:48:42] DEBUG[22526]: channel.c:3491 ast_channel_inherit_variables: Not copying variable SIPUSERAGENT.
[Aug 14 08:48:42] DEBUG[22526]: channel.c:3491 ast_channel_inherit_variables: Not copying variable SIPDOMAIN.
[Aug 14 08:48:42] DEBUG[22526]: channel.c:3491 ast_channel_inherit_variables: Not copying variable SIPURI.
[Aug 14 08:48:42] DEBUG[22526]: chan_mobile.c:522 mbl_call: Calling nokia/*77 on Mobile/nokia-6796
    -- Called nokia/*77
[Aug 14 08:48:42] DEBUG[22526]: channel.c:3020 set_format: Set channel Mobile/nokia-6796 to read format ulaw
[Aug 14 08:48:42] DEBUG[22526]: channel.c:3020 set_format: Set channel SIP/666-081f0a30 to read format slin
  == Begin MixMonitor Recording SIP/666-081f0a30
[Aug 14 08:48:42] DEBUG[22423]: chan_mobile.c:877 rfcomm_write: rfcomm_write() (]okia) [ATD*77;
[Aug 14 08:48:42] DEBUG[22423]: chan_mobile.c:1167 do_monitor_phone: rfcomm_read() (nokia) [OK]
[Aug 14 08:48:43] DEBUG[22396]: chan_mobile.c:774 mbl_devicestate: Checking device state for device nokia
[Aug 14 08:48:43] DEBUG[22423]: chan_mobile.c:1167 do_monitor_phone: rfcomm_read() (nokia) [+CIEV: 3,2]
[Aug 14 08:48:43] DEBUG[22423]: chan_mobile.c:1167 do_monitor_phone: rfcomm_read() (nokia) [+CIEV: 4,2]
[Aug 14 08:48:46] DEBUG[22423]: chan_mobile.c:1167 do_monitor_phone: rfcomm_read() (nokia) [+CIEV: 3,3]
[Aug 14 08:48:46] DEBUG[22423]: chan_mobile.c:1167 do_monitor_phone: rfcomm_read() (nokia) [+CIEV: 4,3]
    -- Mobile/nokia-6796 is ringing
[Aug 14 08:48:46] DEBUG[22526]: rtp.c:1499 ast_rtp_early_bridge: Channel 'Mobile/nokia-6796' has no RTP, not doing anything
[Aug 14 08:48:46] NOTICE[22424]: chan_mobile.c:1695 do_sco_listen: sco_socket returns 24...
[Aug 14 08:48:46] DEBUG[22424]: chan_mobile.c:1701 do_sco_listen: Incoming Audio Connection from device 00:1A:16:E3:C6:FB MTU is 64
[Aug 14 08:48:46] NOTICE[22424]: chan_mobile.c:1709 do_sco_listen: about to close the pvt-sco_socket and set it ns
[Aug 14 08:48:46] NOTICE[22424]: chan_mobile.c:1693 do_sco_listen: About to accept the sco_socket...
[Aug 14 08:48:48] DEBUG[22423]: chan_mobile.c:1167 do_monitor_phone: rfcomm_read() (nokia) [+CIEV: 1,1]
[Aug 14 08:48:48] DEBUG[22396]: chan_mobile.c:774 mbl_devicestate: Checking device state for device nokia
    -- Mobile/nokia-6796 answered SIP/666-081f0a30
[Aug 14 08:48:48] DEBUG[22526]: rtp.c:1499 ast_rtp_early_bridge: Channel 'Mobile/nokia-6796' has no RTP, not doing anything
[Aug 14 08:48:48] DEBUG[22526]: chan_sip.c:3579 sip_answer: SIP answering channel: SIP/666-081f0a30
[Aug 14 08:48:48] DEBUG[22526]: chan_sip.c:6553 transmit_response_with_sdp: Setting framing from config on incoming call
[Aug 14 08:48:48] DEBUG[22423]: chan_mobile.c:1167 do_monitor_phone: rfcomm_read() (nokia) [+CIEV: 3,0]
[Aug 14 08:48:48] DEBUG[22423]: chan_mobile.c:1167 do_monitor_phone: rfcomm_read() (nokia) [+CIEV: 4,0]
[Aug 14 08:48:48] DEBUG[22526]: channel.c:1601 queue_frame_to_spies: Building translator from ulaw to SLINEAR for spies on channel SIP/666-081f0a30
[Aug 14 08:48:48] DEBUG[22526]: rtp.c:2727 ast_rtp_write: Ooh, format changed from unknown to ulaw
[Aug 14 08:48:48] DEBUG[22526]: rtp.c:2744 ast_rtp_write: Created smoother: format: 4 ms: 20 len: 160
[Aug 14 08:48:48] DEBUG[22420]: chan_sip.c:2139 __sip_ack: Stopping retransmission on 'rcqywaobykqjlgv@192.168.0.126' of Response 227: Match Not Found
[Aug 14 08:48:48] DEBUG[22526]: rtp.c:875 ast_rtcp_read: Got RTCP report of 36 bytes
[Aug 14 08:48:48] DEBUG[22526]: chan_sip.c:4211 sip_rtp_read: Oooh, format changed to 2
[Aug 14 08:48:48] DEBUG[22526]: channel.c:3020 set_format: Set channel SIP/666-081f0a30 to read format slin
[Aug 14 08:48:48] DEBUG[22526]: channel.c:3020 set_format: Set channel SIP/666-081f0a30 to write format ulaw
[Aug 14 08:48:48] DEBUG[22526]: channel.c:1601 queue_frame_to_spies: Building translator from gsm to SLINEAR for spies on channel SIP/666-081f0a30
[Aug 14 08:48:48] DEBUG[22526]: channel.c:1601 queue_frame_to_spies: Building translator from gsm to SLINEAR for spies on channel SIP/666-081f0a30
[Aug 14 08:48:48] DEBUG[22526]: rtp.c:2727 ast_rtp_write: Ooh, format changed from ulaw to gsm
[Aug 14 08:48:48] DEBUG[22526]: rtp.c:2744 ast_rtp_write: Created smoother: format: 2 ms: 20 len: 33
[Aug 14 08:48:48] DEBUG[22420]: chan_sip.c:4429 sip_alloc: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP)
[Aug 14 08:48:48] DEBUG[22420]: chan_sip.c:2139 __sip_ack: Stopping retransmission on '0a55f25068a526c547d7d16a386dad32@192.168.0.126' of Request 102: Match Not Found
[Aug 14 08:48:50] DEBUG[22526]: channel.c:3991 ast_generic_bridge: Didn't get a frame from channel: SIP/666-081f0a30
[Aug 14 08:48:50] DEBUG[22526]: channel.c:4315 ast_channel_bridge: Bridge stops bridging channels SIP/666-081f0a30 and Mobile/nokia-6796
[Aug 14 08:48:50] DEBUG[22526]: channel.c:1764 ast_hangup: Hanging up channel 'Mobile/nokia-6796'
[Aug 14 08:48:50] DEBUG[22526]: chan_mobile.c:549 mbl_hangup: Hanging up device nokia.
[Aug 14 08:48:50] DEBUG[22526]: chan_mobile.c:877 rfcomm_write: rfcomm_write() (]okia) [AT+CHUP
[Aug 14 08:48:50] DEBUG[22526]: rtp.c:1499 ast_rtp_early_bridge: Channel '<unspecified>' has no RTP, not doing anything
[Aug 14 08:48:50] DEBUG[22526]: app_dial.c:1686 dial_exec_full: Exiting with DIALSTATUS=ANSWER.
[Aug 14 08:48:50] DEBUG[22526]: pbx.c:2407 __ast_pbx_run: Spawn extension (from-internal,457,2) exited non-zero on 'SIP/666-081f0a30'
  == Spawn extension (from-internal, 457, 2) exited non-zero on 'SIP/666-081f0a30'
[Aug 14 08:48:50] DEBUG[22526]: channel.c:1547 ast_softhangup_nolock: Soft-Hanging up channel 'SIP/666-081f0a30'
[Aug 14 08:48:50] DEBUG[22526]: channel.c:1457 spy_detach: Spy MixMonitor removed from channel SIP/666-081f0a30
[Aug 14 08:48:50] DEBUG[22526]: channel.c:1764 ast_hangup: Hanging up channel 'SIP/666-081f0a30'
[Aug 14 08:48:50] DEBUG[22526]: chan_sip.c:3422 sip_hangup: Hangup call SIP/666-081f0a30, SIP callid rcqywaobykqjlgv@192.168.0.126)
[Aug 14 08:48:50] DEBUG[22396]: chan_mobile.c:774 mbl_devicestate: Checking device state for device nokia
[Aug 14 08:48:50] DEBUG[22396]: chan_mobile.c:774 mbl_devicestate: Checking device state for device nokia
  == End MixMonitor Recording SIP/666-081f0a30
[Aug 14 08:48:50] DEBUG[22423]: chan_mobile.c:1167 do_monitor_phone: rfcomm_read() (nokia) [OK]
[Aug 14 08:48:50] DEBUG[22423]: chan_mobile.c:1167 do_monitor_phone: rfcomm_read() (nokia) [+CIEV: 1,0]
[Aug 14 08:48:50] DEBUG[22423]: chan_mobile.c:1260 do_monitor_phone: Device nokia [+CIEV: 1,0]
[Aug 14 08:49:48] DEBUG[22420]: chan_sip.c:4429 sip_alloc: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP)
[Aug 14 08:49:48] DEBUG[22420]: chan_sip.c:2139 __sip_ack: Stopping retransmission on '02d186b8524750760bf9f25523f0a780@192.168.0.126' of Request 102: Match Not Found