*CLI> core set debug 1
Core debug was 0 and is now 1
*CLI>    -- Bluetooth Device MarksLG has connected, initializing...
[Nov  5 20:35:45] DEBUG[9586]: chan_mobile.c:1400 rfcomm_write_full: rfcomm_writ]() (32) [AT+BRSF=4
[Nov  5 20:35:45] DEBUG[9586]: chan_mobile.c:3585 do_monitor_phone: [MarksLG] +BRSF: 359
[Nov  5 20:35:45] DEBUG[9586]: chan_mobile.c:3585 do_monitor_phone: [MarksLG] OK
[Nov  5 20:35:45] DEBUG[9586]: chan_mobile.c:2999 handle_response_ok: [MarksLG] BSRF sent successfully
[Nov  5 20:35:45] DEBUG[9586]: chan_mobile.c:1400 rfcomm_write_full: rfcomm_writ]() (32) [AT+CIND=?
[Nov  5 20:35:45] DEBUG[9586]: chan_mobile.c:3585 do_monitor_phone: [MarksLG] +CIND: ("call",(0,1)),("callsetup",(0-3)),("service",(0,1)),("signal",(0-5)),("roam",(0,1)),("battchg",(0-5)),("callheld",(0-2))
[Nov  5 20:35:45] DEBUG[9586]: chan_mobile.c:3585 do_monitor_phone: [MarksLG] OK
[Nov  5 20:35:45] DEBUG[9586]: chan_mobile.c:3016 handle_response_ok: [MarksLG] CIND test sent successfully
[Nov  5 20:35:45] DEBUG[9586]: chan_mobile.c:1400 rfcomm_write_full: rfcomm_writ]() (32) [AT+CIND?
[Nov  5 20:35:45] DEBUG[9586]: chan_mobile.c:3585 do_monitor_phone: [MarksLG] +CIND: 0,0,1,3,0,2,0
[Nov  5 20:35:45] DEBUG[9586]: chan_mobile.c:3585 do_monitor_phone: [MarksLG] OK
[Nov  5 20:35:45] DEBUG[9586]: chan_mobile.c:3027 handle_response_ok: [MarksLG] CIND sent successfully
[Nov  5 20:35:45] DEBUG[9586]: chan_mobile.c:1400 rfcomm_write_full: rfcomm_writ]() (32) [AT+CMER=3,0,0,1
[Nov  5 20:35:45] DEBUG[9586]: chan_mobile.c:3585 do_monitor_phone: [MarksLG] OK
[Nov  5 20:35:45] DEBUG[9586]: chan_mobile.c:3050 handle_response_ok: [MarksLG] CMER sent successfully
[Nov  5 20:35:45] DEBUG[9586]: chan_mobile.c:1400 rfcomm_write_full: rfcomm_writ]() (32) [AT+CLIP=1
[Nov  5 20:35:45] DEBUG[9586]: chan_mobile.c:3585 do_monitor_phone: [MarksLG] +VGS: 15
[Nov  5 20:35:45] DEBUG[9586]: chan_mobile.c:3585 do_monitor_phone: [MarksLG] +CIEV: 4,3
[Nov  5 20:35:45] DEBUG[9586]: chan_mobile.c:3585 do_monitor_phone: [MarksLG] OK
[Nov  5 20:35:45] DEBUG[9586]: chan_mobile.c:3067 handle_response_ok: [MarksLG] caling line indication enabled
[Nov  5 20:35:45] DEBUG[9586]: chan_mobile.c:1400 rfcomm_write_full: rfcomm_writ]() (32) [AT+VGS=15
    -- Bluetooth Device MarksLG initialized and ready.
[Nov  5 20:35:45] DEBUG[9586]: chan_mobile.c:3585 do_monitor_phone: [MarksLG] OK
[Nov  5 20:35:45] DEBUG[9586]: chan_mobile.c:3079 handle_response_ok: [MarksLG] volume level synchronization successful
[Nov  5 20:35:45] DEBUG[9586]: chan_mobile.c:1400 rfcomm_write_full: rfcomm_writ]() (32) [AT+CMGF=1
[Nov  5 20:35:45] DEBUG[9586]: chan_mobile.c:3585 do_monitor_phone: [MarksLG] ERROR
[Nov  5 20:35:45] DEBUG[9586]: chan_mobile.c:3195 handle_response_error: [MarksLG] error setting CMGF
[Nov  5 20:35:45] DEBUG[9586]: chan_mobile.c:3196 handle_response_error: [MarksLG] no SMS support
[Nov  5 20:35:48] DEBUG[9586]: chan_mobile.c:3585 do_monitor_phone: [MarksLG] +CIEV: 4,4
*CLI> mobile show devices
ID              Address           Group Adapter         Connected State SMS
MarksLG         00:25:E5:FA:BD:9A 1     zonet           Yes       Free  No 
*CLI> console dial 1425yyyyyyy@default
    -- Call accepted by 64.21.13.43 (format ulaw)
    -- Format for call is ulaw
    -- IAX2/rapidvox-1048 is making progress passing it to ALSA/default

[Nov  5 20:36:49] DEBUG[9586]: chan_mobile.c:3585 do_monitor_phone: [MarksLG] +CIEV: 4,5
[Nov  5 20:36:50] DEBUG[9586]: chan_mobile.c:3585 do_monitor_phone: [MarksLG] +CIEV: 2,1
[Nov  5 20:36:50] DEBUG[9586]: chan_mobile.c:3310 handle_response_ciev: [MarksLG] incoming call, waiting for caller id
[Nov  5 20:36:50] DEBUG[9586]: chan_mobile.c:3585 do_monitor_phone: [MarksLG] RING
[Nov  5 20:36:50] DEBUG[9586]: chan_mobile.c:3389 handle_response_ring: [MarksLG] got ring while waiting for caller id
[Nov  5 20:36:50] DEBUG[9586]: chan_mobile.c:3585 do_monitor_phone: [MarksLG] +CLIP: "425xxxxxxx",129
[Nov  5 20:36:50] DEBUG[9589]: pbx.c:3671 pbx_extension_helper: Launching 'Playback'
    -- Executing [s@incoming-mobile:1] Playback("Mobile/MarksLG-96c3", "demo-echotest") in new stack
[Nov  5 20:36:50] DEBUG[9589]: chan_mobile.c:1400 rfcomm_write_full: rfcomm_writ]() (32) [ATA
[Nov  5 20:36:50] DEBUG[9553]: chan_mobile.c:1181 mbl_devicestate: Checking device state for device MarksLG
[Nov  5 20:36:50] DEBUG[9586]: chan_mobile.c:3585 do_monitor_phone: [MarksLG] OK
[Nov  5 20:36:50] DEBUG[9586]: chan_mobile.c:3104 handle_response_ok: [MarksLG] answer sent successfully
[Nov  5 20:36:50] DEBUG[9586]: chan_mobile.c:3585 do_monitor_phone: [MarksLG] +CIEV: 1,1
[Nov  5 20:36:50] DEBUG[9586]: chan_mobile.c:3585 do_monitor_phone: [MarksLG] +CIEV: 2,0
[Nov  5 20:36:50] DEBUG[9589]: channel.c:3661 set_format: Set channel Mobile/MarksLG-96c3 to write format gsm
[Nov  5 20:36:50] DEBUG[9589]: channel.c:2387 ast_settimeout: Scheduling timer at (50 requested / 50 actual) timer ticks per second
    -- <Mobile/MarksLG-96c3> Playing 'demo-echotest.gsm' (language 'en')
    -- IAX2/rapidvox-1048 answered ALSA/default
 << Console call has been answered >> 
[Nov  5 20:36:58] DEBUG[9586]: chan_mobile.c:3585 do_monitor_phone: [MarksLG] +CIEV: 4,4
[Nov  5 20:37:07] DEBUG[9586]: chan_mobile.c:3585 do_monitor_phone: [MarksLG] +CIEV: 4,5
[Nov  5 20:37:10] DEBUG[9589]: channel.c:2387 ast_settimeout: Scheduling timer at (0 requested / 0 actual) timer ticks per second
[Nov  5 20:37:10] DEBUG[9589]: channel.c:2387 ast_settimeout: Scheduling timer at (0 requested / 0 actual) timer ticks per second
[Nov  5 20:37:10] DEBUG[9589]: channel.c:3661 set_format: Set channel Mobile/MarksLG-96c3 to write format slin
[Nov  5 20:37:10] DEBUG[9589]: pbx.c:3671 pbx_extension_helper: Launching 'Echo'
    -- Executing [s@incoming-mobile:2] Echo("Mobile/MarksLG-96c3", "") in new stack
[Nov  5 20:37:16] DEBUG[9586]: chan_mobile.c:3585 do_monitor_phone: [MarksLG] +CIEV: 1,0
[Nov  5 20:37:16] DEBUG[9586]: chan_mobile.c:3264 handle_response_ciev: [MarksLG] line disconnected
[Nov  5 20:37:16] DEBUG[9586]: chan_mobile.c:3266 handle_response_ciev: [MarksLG] hanging up owner
[Nov  5 20:37:16] DEBUG[9589]: pbx.c:4280 __ast_pbx_run: Spawn extension (incoming-mobile,s,2) exited non-zero on 'Mobile/MarksLG-96c3'
  == Spawn extension (incoming-mobile, s, 2) exited non-zero on 'Mobile/MarksLG-96c3'
[Nov  5 20:37:16] DEBUG[9589]: channel.c:1626 ast_softhangup_nolock: Soft-Hanging up channel 'Mobile/MarksLG-96c3'
[Nov  5 20:37:16] DEBUG[9589]: channel.c:1721 ast_hangup: Hanging up channel 'Mobile/MarksLG-96c3'
[Nov  5 20:37:16] DEBUG[9589]: chan_mobile.c:1000 mbl_hangup: [MarksLG] hanging up device
[Nov  5 20:37:16] DEBUG[9553]: chan_mobile.c:1181 mbl_devicestate: Checking device state for device MarksLG
[Nov  5 20:37:16] DEBUG[9553]: chan_mobile.c:1181 mbl_devicestate: Checking device state for device MarksLG
[Nov  5 20:37:16] DEBUG[9586]: chan_mobile.c:3585 do_monitor_phone: [MarksLG] +CIEV: 4,4
[Nov  5 20:37:18] DEBUG[9586]: chan_mobile.c:3585 do_monitor_phone: [MarksLG] +CIEV: 4,5
  == Spawn extension (default, 1425yyyyyyy, 2) exited non-zero on 'ALSA/default'
 << Hangup on console >>