Asterisk Ready.
]Mar 15 22:19:48] DEBUG[2478]: chan_cellphone.c:793 rfcomm_write: rfcomm_write() (cella) [AT+BRSF=4
[Mar 15 22:19:48] DEBUG[2478]: chan_cellphone.c:1032 do_monitor_phone: rfcomm_read() (cella) [ERROR]
]Mar 15 22:19:48] DEBUG[2478]: chan_cellphone.c:793 rfcomm_write: rfcomm_write() (cella) [AT+CIND=?
[Mar 15 22:19:49] DEBUG[2478]: chan_cellphone.c:1032 do_monitor_phone: rfcomm_read() (cella) [+CIND: ("battchg",(0-5)),("signal",(0-5)),("batterywarning",(0-1)),("chargerconnected",(0-1)),("service",(0-1)),("sounder",(0-1)),("message",(0-1)),("call",(0-1)),("roam",(0-1)),("smsfull",(0-1))]
[Mar 15 22:19:49] DEBUG[2478]: chan_cellphone.c:1074 do_monitor_phone: CIEV_CALL=8 CIEV_CALLSETUP=0
[Mar 15 22:19:49] DEBUG[2478]: chan_cellphone.c:1032 do_monitor_phone: rfcomm_read() (cella) [OK]
]Mar 15 22:19:49] DEBUG[2478]: chan_cellphone.c:793 rfcomm_write: rfcomm_write() (cella) [AT+CIND?
[Mar 15 22:19:49] DEBUG[2478]: chan_cellphone.c:1032 do_monitor_phone: rfcomm_read() (cella) [+CIND: 2,5,0,0,1,1,0,0,0,0]
[Mar 15 22:19:49] DEBUG[2478]: chan_cellphone.c:1032 do_monitor_phone: rfcomm_read() (cella) [OK]
]Mar 15 22:19:49] DEBUG[2478]: chan_cellphone.c:793 rfcomm_write: rfcomm_write() (cella) [AT+CMER=3,0,0,1
[Mar 15 22:19:49] DEBUG[2478]: chan_cellphone.c:1032 do_monitor_phone: rfcomm_read() (cella) [OK]
]Mar 15 22:19:49] DEBUG[2478]: chan_cellphone.c:793 rfcomm_write: rfcomm_write() (cella) [AT+CLIP=1
[Mar 15 22:19:49] DEBUG[2478]: chan_cellphone.c:1032 do_monitor_phone: rfcomm_read() (cella) [+CLIP: "41066745",161,,,""]
[Mar 15 22:19:49] DEBUG[2478]: chan_cellphone.c:1032 do_monitor_phone: rfcomm_read() (cella) [OK]
]Mar 15 22:19:49] DEBUG[2478]: chan_cellphone.c:793 rfcomm_write: rfcomm_write() (cella) [AT+CMGF=1
[Mar 15 22:19:50] DEBUG[2478]: chan_cellphone.c:1032 do_monitor_phone: rfcomm_read() (cella) [OK]
]Mar 15 22:19:50] DEBUG[2478]: chan_cellphone.c:793 rfcomm_write: rfcomm_write() (cella) [AT+CNMI=2,1,0,1,0
[Mar 15 22:19:50] DEBUG[2478]: chan_cellphone.c:1032 do_monitor_phone: rfcomm_read() (cella) [ERROR]
    -- Bluetooth Device cella initialised and ready.
[Mar 15 22:20:01] DEBUG[2478]: chan_cellphone.c:1032 do_monitor_phone: rfcomm_read() (cella) [+CIEV: 6,0]
[Mar 15 22:20:01] DEBUG[2478]: chan_cellphone.c:1126 do_monitor_phone: Device 00:0F:DE:XXXX cella [+CIEV: 6,0]
[Mar 15 22:20:01] DEBUG[2478]: chan_cellphone.c:1032 do_monitor_phone: rfcomm_read() (cella) [RING]
[Mar 15 22:20:01] DEBUG[2478]: chan_cellphone.c:1126 do_monitor_phone: Device 00:0F:DE:XXXX cella [RING]
[Mar 15 22:20:01] DEBUG[2478]: chan_cellphone.c:1032 do_monitor_phone: rfcomm_read() (cella) [+CLIP: XXXXXXX]
[Mar 15 22:20:01] DEBUG[2478]: pbx.c:1716 pbx_extension_helper: Launching 'Wait'
[Mar 15 22:20:02] DEBUG[2478]: pbx.c:1716 pbx_extension_helper: Launching 'Answer'
]Mar 15 22:20:02] DEBUG[2478]: chan_cellphone.c:793 rfcomm_write: rfcomm_write() (cella) [ATA
[Mar 15 22:20:02] DEBUG[2478]: pbx.c:1716 pbx_extension_helper: Launching 'Set'
[Mar 15 22:20:02] DEBUG[2478]: pbx.c:1716 pbx_extension_helper: Launching 'Set'
[Mar 15 22:20:02] DEBUG[2478]: pbx.c:1716 pbx_extension_helper: Launching 'BackGround'
[Mar 15 22:20:02] DEBUG[2478]: channel.c:2791 set_format: Set channel CELL/cella-31a4 to write format gsm
[Mar 15 22:20:02] DEBUG[2478]: chan_cellphone.c:704 cel_devicestate: Checking device state for device cella
[Mar 15 22:20:03] DEBUG[2478]: chan_cellphone.c:1032 do_monitor_phone: rfcomm_read() (cella) [OK]
[Mar 15 22:20:03] DEBUG[2478]: chan_cellphone.c:1543 do_sco_listen: Incoming Audio Connection from device 00:0F:DE:2A:C1:4C MTU is 60
[Mar 15 22:20:03] DEBUG[2478]: sched.c:205 sched_settime: Request to schedule in the past?!?!
[Mar 15 22:20:03] DEBUG[2478]: sched.c:205 sched_settime: Request to schedule in the past?!?!
[Mar 15 22:20:03] DEBUG[2478]: sched.c:205 sched_settime: Request to schedule in the past?!?!

(above line repeats until Control-C is pressed)

[Mar 15 22:20:18] DEBUG[2478]: channel.c:1408 ast_softhangup_nolock: Soft-Hanging up channel 'CELL/cella-31a4'
[Mar 15 22:20:18] WARNING[2478]: file.c:608 ast_readaudio_callback: Failed to write frame
[Mar 15 22:20:18] DEBUG[2478]: channel.c:2791 set_format: Set channel CELL/cella-31a4 to write format slin
[Mar 15 22:20:18] DEBUG[2478]: pbx.c:2312 __ast_pbx_run: Spawn extension (default,s,5) exited non-zero on 'CELL/cella-31a4'
[Mar 15 22:20:18] DEBUG[2478]: pbx.c:1566 pbx_substitute_variables_helper_full: Function result is '"cella" <92432403>'
[Mar 15 22:20:18] DEBUG[2478]: pbx.c:1566 pbx_substitute_variables_helper_full: Function result is XXXX
[Mar 15 22:20:18] DEBUG[2478]: pbx.c:1566 pbx_substitute_variables_helper_full: Function result is 's'
[Mar 15 22:20:18] DEBUG[2478]: pbx.c:1566 pbx_substitute_variables_helper_full: Function result is 'default'
[Mar 15 22:20:18] DEBUG[2478]: pbx.c:1566 pbx_substitute_variables_helper_full: Function result is 'CELL/cella-31a4'
[Mar 15 22:20:18] DEBUG[2478]: pbx.c:1566 pbx_substitute_variables_helper_full: Function result is ''
[Mar 15 22:20:18] DEBUG[2478]: pbx.c:1566 pbx_substitute_variables_helper_full: Function result is 'BackGround'
[Mar 15 22:20:18] DEBUG[2478]: pbx.c:1566 pbx_substitute_variables_helper_full: Function result is 'demo-congrats'
[Mar 15 22:20:18] DEBUG[2478]: pbx.c:1566 pbx_substitute_variables_helper_full: Function result is '2007-03-15 22:20:01'
[Mar 15 22:20:18] DEBUG[2478]: pbx.c:1566 pbx_substitute_variables_helper_full: Function result is '2007-03-15 22:20:02'
[Mar 15 22:20:18] DEBUG[2478]: pbx.c:1566 pbx_substitute_variables_helper_full: Function result is '2007-03-15 22:20:18'
[Mar 15 22:20:18] DEBUG[2478]: pbx.c:1566 pbx_substitute_variables_helper_full: Function result is '17'
[Mar 15 22:20:18] DEBUG[2478]: pbx.c:1566 pbx_substitute_variables_helper_full: Function result is '16'
[Mar 15 22:20:18] DEBUG[2478]: pbx.c:1566 pbx_substitute_variables_helper_full: Function result is 'ANSWERED'
[Mar 15 22:20:18] DEBUG[2478]: pbx.c:1566 pbx_substitute_variables_helper_full: Function result is 'DOCUMENTATION'
[Mar 15 22:20:18] DEBUG[2478]: pbx.c:1566 pbx_substitute_variables_helper_full: Function result is ''
[Mar 15 22:20:18] DEBUG[2478]: pbx.c:1566 pbx_substitute_variables_helper_full: Function result is '1173993601.0'
[Mar 15 22:20:18] DEBUG[2478]: pbx.c:1566 pbx_substitute_variables_helper_full: Function result is ''
[Mar 15 22:20:18] DEBUG[2478]: channel.c:1622 ast_hangup: Hanging up channel 'CELL/cella-31a4'
[Mar 15 22:20:18] DEBUG[2478]: chan_cellphone.c:481 cel_hangup: Hanging up device cella.
[Mar 15 22:20:18] DEBUG[2478]: chan_cellphone.c:704 cel_devicestate: Checking device state for device cella
[Mar 15 22:20:18] DEBUG[2478]: chan_cellphone.c:704 cel_devicestate: Checking device state for device cella
Executing last minute cleanups
[Mar 15 22:20:18] DEBUG[2478]: asterisk.c:1234 quit_handler: Asterisk ending (0).