[Sep 14 14:00:01] DEBUG[7444]: pbx.c:1753 pbx_extension_helper: Launching 'Dial'
    -- Executing [hidden@default:1] Dial("IAX2/markoz-2", "Mobile/LG310/hidden") in new stack
[Sep 14 14:00:01] DEBUG[7444]: rtp.c:1883 ast_rtp_make_compatible: Channel 'Mobile/LG310-6f69' has no RTP, not doing anything
[Sep 14 14:00:01] DEBUG[7444]: channel.c:3406 ast_channel_inherit_variables: Not copying variable STACK-default-hidden-1.
[Sep 14 14:00:01] DEBUG[7444]: chan_mobile.c:536 mbl_call: Calling LG310/hidden on Mobile/LG310-6f69
    -- Called LG310/hidden
]Sep 14 14:00:02] DEBUG[7444]: chan_mobile.c:870 rfcomm_write: rfcomm_write() (LG310) [ATDhidden;
[Sep 14 14:00:04] DEBUG[7444]: chan_mobile.c:1245 do_monitor_phone: rfcomm_read() (LG310) [OK]
[Sep 14 14:00:04] DEBUG[7444]: chan_mobile.c:1245 do_monitor_phone: rfcomm_read() (LG310) [+CIEV: 3,2]
[Sep 14 14:00:04] DEBUG[7444]: chan_mobile.c:768 mbl_devicestate: Checking device state for device LG310
[Sep 14 14:00:10] DEBUG[7444]: chan_mobile.c:1245 do_monitor_phone: rfcomm_read() (LG310) [+CIEV: 3,3]
    -- Mobile/LG310-6f69 is ringing
[Sep 14 14:00:10] NOTICE[7444]: chan_mobile.c:1773 do_sco_listen: sco_socket returns 39...
[Sep 14 14:00:10] DEBUG[7444]: chan_mobile.c:1062 sco_set_options: error setting SCO_TXBUFS 92
[Sep 14 14:00:10] DEBUG[7444]: chan_mobile.c:1065 sco_set_options: error setting SCO_RXBUFS 92
[Sep 14 14:00:10] DEBUG[7444]: chan_mobile.c:1781 do_sco_listen: Incoming Audio Connection from device 00:19:A1:63:84:B1 MTU is 64
[Sep 14 14:00:10] NOTICE[7444]: chan_mobile.c:1789 do_sco_listen: about to close the pvt-sco_socket and set it ns
[Sep 14 14:00:10] NOTICE[7444]: chan_mobile.c:1771 do_sco_listen: About to accept the sco_socket...
[Sep 14 14:00:12] DEBUG[7444]: chan_mobile.c:1245 do_monitor_phone: rfcomm_read() (LG310) [+CIEV: 2,1]
[Sep 14 14:00:12] DEBUG[7444]: chan_mobile.c:1245 do_monitor_phone: rfcomm_read() (LG310) [+CIEV: 3,0]
[Sep 14 14:00:12] DEBUG[7444]: chan_mobile.c:768 mbl_devicestate: Checking device state for device LG310
    -- Mobile/LG310-6f69 answered IAX2/markoz-2
[Sep 14 14:00:12] DEBUG[7444]: chan_iax2.c:3629 iax2_answer: Answering IAX2 call
[Sep 14 14:00:12] DEBUG[7444]: channel.c:967 channel_find_locked: Avoiding initial deadlock for channel '0x818c098'
[Sep 14 14:00:12] DEBUG[7444]: chan_iax2.c:8599 socket_process: Received mini frame before first full voice frame
[Sep 14 14:00:12] DEBUG[7444]: chan_iax2.c:8599 socket_process: Received mini frame before first full voice frame
[Sep 14 14:00:12] DEBUG[7444]: chan_iax2.c:8599 socket_process: Received mini frame before first full voice frame
[Sep 14 14:00:12] DEBUG[7444]: chan_iax2.c:8599 socket_process: Received mini frame before first full voice frame
[Sep 14 14:00:12] DEBUG[7444]: chan_iax2.c:7538 socket_process: Ooh, voice format changed to 64
[Sep 14 14:00:12] DEBUG[7444]: channel.c:2921 set_format: Set channel IAX2/markoz-2 to read format slin
[Sep 14 14:00:12] DEBUG[7444]: chan_iax2.c:7365 socket_process: Packet arrived out of order (expecting 5, got 4) (frametype = 2, subclass = 64)
[Sep 14 14:00:12] DEBUG[7444]: chan_iax2.c:7372 socket_process: Acking anyway
[Sep 14 14:00:12] DEBUG[7444]: chan_iax2.c:7365 socket_process: Packet arrived out of order (expecting 5, got 4) (frametype = 2, subclass = 64)
[Sep 14 14:00:12] DEBUG[7444]: chan_iax2.c:7372 socket_process: Acking anyway
[Sep 14 14:00:12] DEBUG[7444]: chan_iax2.c:7365 socket_process: Packet arrived out of order (expecting 5, got 4) (frametype = 2, subclass = 64)
[Sep 14 14:00:12] DEBUG[7444]: chan_iax2.c:7372 socket_process: Acking anyway
[Sep 14 14:00:12] DEBUG[7444]: chan_iax2.c:7365 socket_process: Packet arrived out of order (expecting 5, got 4) (frametype = 2, subclass = 64)
[Sep 14 14:00:12] DEBUG[7444]: chan_iax2.c:7372 socket_process: Acking anyway
[Sep 14 14:00:13] DEBUG[7444]: chan_iax2.c:8358 socket_process: Received VNAK: resending outstanding frames
[Sep 14 14:00:13] DEBUG[7444]: chan_iax2.c:8358 socket_process: Received VNAK: resending outstanding frames
[Sep 14 14:00:13] DEBUG[7444]: chan_iax2.c:8358 socket_process: Received VNAK: resending outstanding frames
[Sep 14 14:00:13] DEBUG[7444]: chan_iax2.c:8358 socket_process: Received VNAK: resending outstanding frames
[Sep 14 14:00:13] DEBUG[7444]: chan_iax2.c:8358 socket_process: Received VNAK: resending outstanding frames
[Sep 14 14:00:13] DEBUG[7444]: chan_iax2.c:8358 socket_process: Received VNAK: resending outstanding frames
[Sep 14 14:00:13] DEBUG[7444]: chan_iax2.c:8358 socket_process: Received VNAK: resending outstanding frames
[Sep 14 14:00:13] DEBUG[7444]: chan_iax2.c:8358 socket_process: Received VNAK: resending outstanding frames
[Sep 14 14:00:13] DEBUG[7444]: chan_iax2.c:8358 socket_process: Received VNAK: resending outstanding frames
[Sep 14 14:00:13] DEBUG[7444]: chan_iax2.c:8358 socket_process: Received VNAK: resending outstanding frames
[Sep 14 14:00:13] DEBUG[7444]: chan_iax2.c:8358 socket_process: Received VNAK: resending outstanding frames
[Sep 14 14:00:13] DEBUG[7444]: chan_iax2.c:8358 socket_process: Received VNAK: resending outstanding frames
[Sep 14 14:00:13] DEBUG[7444]: chan_iax2.c:8358 socket_process: Received VNAK: resending outstanding frames
[Sep 14 14:00:13] DEBUG[7444]: chan_iax2.c:8358 socket_process: Received VNAK: resending outstanding frames
[Sep 14 14:00:13] DEBUG[7444]: chan_iax2.c:8358 socket_process: Received VNAK: resending outstanding frames
[Sep 14 14:00:13] DEBUG[7444]: chan_iax2.c:8358 socket_process: Received VNAK: resending outstanding frames
[Sep 14 14:00:13] DEBUG[7444]: chan_iax2.c:8358 socket_process: Received VNAK: resending outstanding frames
[Sep 14 14:00:13] DEBUG[7444]: chan_iax2.c:8358 socket_process: Received VNAK: resending outstanding frames
[Sep 14 14:00:13] DEBUG[7444]: chan_iax2.c:8358 socket_process: Received VNAK: resending outstanding frames
[Sep 14 14:00:13] DEBUG[7444]: chan_iax2.c:8358 socket_process: Received VNAK: resending outstanding frames
[Sep 14 14:00:13] DEBUG[7444]: chan_iax2.c:8358 socket_process: Received VNAK: resending outstanding frames
[Sep 14 14:00:13] DEBUG[7444]: chan_iax2.c:8358 socket_process: Received VNAK: resending outstanding frames
[Sep 14 14:00:13] DEBUG[7444]: chan_iax2.c:8358 socket_process: Received VNAK: resending outstanding frames
[Sep 14 14:00:13] DEBUG[7444]: chan_iax2.c:8358 socket_process: Received VNAK: resending outstanding frames
[Sep 14 14:00:13] DEBUG[7444]: chan_iax2.c:8358 socket_process: Received VNAK: resending outstanding frames
[Sep 14 14:00:13] DEBUG[7444]: chan_iax2.c:8358 socket_process: Received VNAK: resending outstanding frames
[Sep 14 14:00:13] DEBUG[7444]: chan_iax2.c:8358 socket_process: Received VNAK: resending outstanding frames
[Sep 14 14:00:13] DEBUG[7444]: chan_iax2.c:8358 socket_process: Received VNAK: resending outstanding frames
[Sep 14 14:00:13] DEBUG[7444]: chan_iax2.c:8358 socket_process: Received VNAK: resending outstanding frames
[Sep 14 14:00:13] DEBUG[7444]: chan_iax2.c:8358 socket_process: Received VNAK: resending outstanding frames
[Sep 14 14:00:13] DEBUG[7444]: chan_iax2.c:8358 socket_process: Received VNAK: resending outstanding frames
[Sep 14 14:00:13] DEBUG[7444]: chan_iax2.c:8358 socket_process: Received VNAK: resending outstanding frames
[Sep 14 14:00:13] DEBUG[7444]: chan_iax2.c:8358 socket_process: Received VNAK: resending outstanding frames
[Sep 14 14:00:13] DEBUG[7444]: chan_iax2.c:8358 socket_process: Received VNAK: resending outstanding frames
[Sep 14 14:00:13] DEBUG[7444]: chan_iax2.c:8358 socket_process: Received VNAK: resending outstanding frames
[Sep 14 14:00:13] DEBUG[7444]: chan_iax2.c:8358 socket_process: Received VNAK: resending outstanding frames
[Sep 14 14:00:13] DEBUG[7444]: chan_iax2.c:8358 socket_process: Received VNAK: resending outstanding frames
[Sep 14 14:00:13] DEBUG[7444]: chan_iax2.c:8358 socket_process: Received VNAK: resending outstanding frames
[Sep 14 14:00:13] DEBUG[7444]: chan_iax2.c:8358 socket_process: Received VNAK: resending outstanding frames
[Sep 14 14:00:13] DEBUG[7444]: chan_iax2.c:8358 socket_process: Received VNAK: resending outstanding frames
[Sep 14 14:00:13] DEBUG[7444]: chan_iax2.c:8358 socket_process: Received VNAK: resending outstanding frames
[Sep 14 14:00:13] DEBUG[7444]: chan_iax2.c:8358 socket_process: Received VNAK: resending outstanding frames
[Sep 14 14:00:13] DEBUG[7444]: chan_iax2.c:8358 socket_process: Received VNAK: resending outstanding frames
[Sep 14 14:00:13] DEBUG[7444]: chan_iax2.c:8358 socket_process: Received VNAK: resending outstanding frames
[Sep 14 14:00:13] DEBUG[7444]: chan_iax2.c:8358 socket_process: Received VNAK: resending outstanding frames
[Sep 14 14:00:13] DEBUG[7444]: chan_iax2.c:8358 socket_process: Received VNAK: resending outstanding frames
[Sep 14 14:00:13] DEBUG[7444]: chan_iax2.c:8358 socket_process: Received VNAK: resending outstanding frames
[Sep 14 14:00:13] DEBUG[7444]: chan_iax2.c:8358 socket_process: Received VNAK: resending outstanding frames
[Sep 14 14:00:13] DEBUG[7444]: chan_iax2.c:8358 socket_process: Received VNAK: resending outstanding frames
[Sep 14 14:00:13] DEBUG[7444]: chan_iax2.c:8358 socket_process: Received VNAK: resending outstanding frames
[Sep 14 14:00:13] DEBUG[7444]: chan_iax2.c:8358 socket_process: Received VNAK: resending outstanding frames
[Sep 14 14:00:13] DEBUG[7444]: chan_iax2.c:8358 socket_process: Received VNAK: resending outstanding frames
[Sep 14 14:00:13] DEBUG[7444]: chan_iax2.c:8358 socket_process: Received VNAK: resending outstanding frames
[Sep 14 14:00:13] DEBUG[7444]: chan_iax2.c:8358 socket_process: Received VNAK: resending outstanding frames
[Sep 14 14:00:13] DEBUG[7444]: chan_iax2.c:8358 socket_process: Received VNAK: resending outstanding frames
[Sep 14 14:00:13] DEBUG[7444]: chan_iax2.c:8358 socket_process: Received VNAK: resending outstanding frames
[Sep 14 14:00:13] DEBUG[7444]: chan_iax2.c:8358 socket_process: Received VNAK: resending outstanding frames
[Sep 14 14:00:13] DEBUG[7444]: chan_iax2.c:8358 socket_process: Received VNAK: resending outstanding frames
[Sep 14 14:00:13] DEBUG[7444]: chan_iax2.c:8358 socket_process: Received VNAK: resending outstanding frames
[Sep 14 14:00:13] DEBUG[7444]: chan_iax2.c:8358 socket_process: Received VNAK: resending outstanding frames
[Sep 14 14:00:13] DEBUG[7444]: chan_iax2.c:8358 socket_process: Received VNAK: resending outstanding frames
[Sep 14 14:00:13] DEBUG[7444]: chan_iax2.c:8358 socket_process: Received VNAK: resending outstanding frames
[Sep 14 14:00:13] DEBUG[7444]: chan_iax2.c:8358 socket_process: Received VNAK: resending outstanding frames
[Sep 14 14:00:13] DEBUG[7444]: chan_iax2.c:8358 socket_process: Received VNAK: resending outstanding frames
[Sep 14 14:00:13] DEBUG[7444]: chan_iax2.c:8358 socket_process: Received VNAK: resending outstanding frames
[Sep 14 14:00:13] DEBUG[7444]: chan_iax2.c:8358 socket_process: Received VNAK: resending outstanding frames
[Sep 14 14:00:13] DEBUG[7444]: chan_iax2.c:8358 socket_process: Received VNAK: resending outstanding frames
[Sep 14 14:00:13] DEBUG[7444]: chan_iax2.c:8358 socket_process: Received VNAK: resending outstanding frames
[Sep 14 14:00:13] DEBUG[7444]: chan_iax2.c:8358 socket_process: Received VNAK: resending outstanding frames
[Sep 14 14:00:13] DEBUG[7444]: chan_iax2.c:8358 socket_process: Received VNAK: resending outstanding frames
[Sep 14 14:00:13] DEBUG[7444]: chan_iax2.c:8358 socket_process: Received VNAK: resending outstanding frames
[Sep 14 14:00:13] DEBUG[7444]: chan_iax2.c:8358 socket_process: Received VNAK: resending outstanding frames
[Sep 14 14:00:13] DEBUG[7444]: chan_iax2.c:8358 socket_process: Received VNAK: resending outstanding frames
[Sep 14 14:00:13] DEBUG[7444]: chan_iax2.c:8358 socket_process: Received VNAK: resending outstanding frames
[Sep 14 14:00:13] DEBUG[7444]: chan_iax2.c:8358 socket_process: Received VNAK: resending outstanding frames
[Sep 14 14:00:13] DEBUG[7444]: chan_iax2.c:8358 socket_process: Received VNAK: resending outstanding frames
[Sep 14 14:00:13] DEBUG[7444]: chan_iax2.c:8358 socket_process: Received VNAK: resending outstanding frames
[Sep 14 14:00:13] DEBUG[7444]: chan_iax2.c:8358 socket_process: Received VNAK: resending outstanding frames
[Sep 14 14:00:13] DEBUG[7444]: chan_iax2.c:8358 socket_process: Received VNAK: resending outstanding frames
[Sep 14 14:00:13] DEBUG[7444]: chan_iax2.c:8358 socket_process: Received VNAK: resending outstanding frames
[Sep 14 14:00:13] DEBUG[7444]: chan_iax2.c:8358 socket_process: Received VNAK: resending outstanding frames
[Sep 14 14:00:13] DEBUG[7444]: chan_iax2.c:8358 socket_process: Received VNAK: resending outstanding frames
[Sep 14 14:00:13] DEBUG[7444]: chan_iax2.c:8358 socket_process: Received VNAK: resending outstanding frames
[Sep 14 14:00:13] DEBUG[7444]: chan_iax2.c:8358 socket_process: Received VNAK: resending outstanding frames
[Sep 14 14:00:13] DEBUG[7444]: chan_iax2.c:8358 socket_process: Received VNAK: resending outstanding frames
[Sep 14 14:00:13] DEBUG[7444]: chan_iax2.c:8358 socket_process: Received VNAK: resending outstanding frames
[Sep 14 14:00:13] DEBUG[7444]: chan_iax2.c:8358 socket_process: Received VNAK: resending outstanding frames
[Sep 14 14:00:13] DEBUG[7444]: chan_iax2.c:8358 socket_process: Received VNAK: resending outstanding frames
[Sep 14 14:00:13] DEBUG[7444]: chan_iax2.c:8358 socket_process: Received VNAK: resending outstanding frames
[Sep 14 14:00:13] DEBUG[7444]: chan_iax2.c:8358 socket_process: Received VNAK: resending outstanding frames
[Sep 14 14:00:13] DEBUG[7444]: chan_iax2.c:8358 socket_process: Received VNAK: resending outstanding frames
[Sep 14 14:00:13] DEBUG[7444]: chan_iax2.c:8358 socket_process: Received VNAK: resending outstanding frames
[Sep 14 14:00:13] DEBUG[7444]: chan_iax2.c:8358 socket_process: Received VNAK: resending outstanding frames
[Sep 14 14:00:13] DEBUG[7444]: chan_iax2.c:8358 socket_process: Received VNAK: resending outstanding frames
[Sep 14 14:00:13] DEBUG[7444]: chan_iax2.c:8358 socket_process: Received VNAK: resending outstanding frames
[Sep 14 14:00:13] DEBUG[7444]: chan_iax2.c:8358 socket_process: Received VNAK: resending outstanding frames
[Sep 14 14:00:13] DEBUG[7444]: chan_iax2.c:8358 socket_process: Received VNAK: resending outstanding frames
[Sep 14 14:00:13] DEBUG[7444]: chan_iax2.c:8358 socket_process: Received VNAK: resending outstanding frames
[Sep 14 14:00:13] DEBUG[7444]: chan_iax2.c:8358 socket_process: Received VNAK: resending outstanding frames
[Sep 14 14:00:13] DEBUG[7444]: chan_iax2.c:8358 socket_process: Received VNAK: resending outstanding frames
[Sep 14 14:00:13] DEBUG[7444]: chan_iax2.c:8358 socket_process: Received VNAK: resending outstanding frames
[Sep 14 14:00:13] DEBUG[7444]: chan_iax2.c:8358 socket_process: Received VNAK: resending outstanding frames
[Sep 14 14:00:13] DEBUG[7444]: chan_iax2.c:8358 socket_process: Received VNAK: resending outstanding frames
[Sep 14 14:00:13] DEBUG[7444]: chan_iax2.c:8358 socket_process: Received VNAK: resending outstanding frames
[Sep 14 14:00:13] DEBUG[7444]: chan_iax2.c:8358 socket_process: Received VNAK: resending outstanding frames
[Sep 14 14:00:13] DEBUG[7444]: chan_iax2.c:8358 socket_process: Received VNAK: resending outstanding frames
[Sep 14 14:00:13] DEBUG[7444]: chan_iax2.c:8358 socket_process: Received VNAK: resending outstanding frames
[Sep 14 14:00:13] DEBUG[7444]: chan_iax2.c:8358 socket_process: Received VNAK: resending outstanding frames
[Sep 14 14:00:13] DEBUG[7444]: chan_iax2.c:8358 socket_process: Received VNAK: resending outstanding frames
[Sep 14 14:00:13] DEBUG[7444]: chan_iax2.c:8358 socket_process: Received VNAK: resending outstanding frames
[Sep 14 14:00:13] DEBUG[7444]: chan_iax2.c:8358 socket_process: Received VNAK: resending outstanding frames
[Sep 14 14:00:13] DEBUG[7444]: chan_iax2.c:8358 socket_process: Received VNAK: resending outstanding frames
[Sep 14 14:00:13] DEBUG[7444]: chan_iax2.c:8358 socket_process: Received VNAK: resending outstanding frames
[Sep 14 14:00:13] DEBUG[7444]: chan_iax2.c:8358 socket_process: Received VNAK: resending outstanding frames
[Sep 14 14:00:18] DEBUG[7444]: chan_iax2.c:7881 socket_process: Immediately destroying 2, having received hangup
[Sep 14 14:00:18] DEBUG[7444]: channel.c:3872 ast_generic_bridge: Didn't get a frame from channel: IAX2/markoz-2
[Sep 14 14:00:18] DEBUG[7444]: channel.c:4204 ast_channel_bridge: Bridge stops bridging channels IAX2/markoz-2 and Mobile/LG310-6f69
[Sep 14 14:00:18] DEBUG[7444]: channel.c:1460 ast_hangup: Hanging up channel 'Mobile/LG310-6f69'
[Sep 14 14:00:18] DEBUG[7444]: chan_mobile.c:563 mbl_hangup: Hanging up device LG310.
]Sep 14 14:00:18] DEBUG[7444]: chan_mobile.c:870 rfcomm_write: rfcomm_write() (LG310) [AT+CHUP
[Sep 14 14:00:18] DEBUG[7444]: chan_mobile.c:768 mbl_devicestate: Checking device state for device LG310
[Sep 14 14:00:18] DEBUG[7444]: chan_mobile.c:768 mbl_devicestate: Checking device state for device LG310
[Sep 14 14:00:18] DEBUG[7444]: app_dial.c:1823 dial_exec_full: Exiting with DIALSTATUS=ANSWER.
[Sep 14 14:00:18] DEBUG[7444]: pbx.c:2382 __ast_pbx_run: Spawn extension (default,hidden,1) exited non-zero on 'IAX2/markoz-2'
  == Spawn extension (default, hidden, 1) exited non-zero on 'IAX2/markoz-2'
[Sep 14 14:00:18] DEBUG[7444]: channel.c:1363 ast_softhangup_nolock: Soft-Hanging up channel 'IAX2/markoz-2'
[Sep 14 14:00:18] DEBUG[7444]: channel.c:1460 ast_hangup: Hanging up channel 'IAX2/markoz-2'
[Sep 14 14:00:18] DEBUG[7444]: chan_iax2.c:3380 iax2_hangup: We're hanging up IAX2/markoz-2 now...
[Sep 14 14:00:18] DEBUG[7444]: chan_iax2.c:3395 iax2_hangup: Really destroying IAX2/markoz-2 now...
    -- Hungup 'IAX2/markoz-2'
[Sep 14 14:00:18] DEBUG[7444]: chan_mobile.c:1245 do_monitor_phone: rfcomm_read() (LG310) [OK]
[Sep 14 14:00:18] DEBUG[7444]: chan_mobile.c:1245 do_monitor_phone: rfcomm_read() (LG310) [+CIEV: 2,0]
[Sep 14 14:00:18] DEBUG[7444]: chan_mobile.c:1338 do_monitor_phone: Device LG310 [+CIEV: 2,0]