chan_cellphone.so => (Bluetooth Cellphone Driver)
Asterisk Ready.
]Feb  5 06:32:37] DEBUG[18724]: chan_cellphone.c:651 rfcomm_write: rfcomm_write() (20) [AT+CIND=?
]Feb  5 06:32:37] DEBUG[18724]: chan_cellphone.c:826 do_monitor: rfcomm_read() (20) [
[Feb  5 06:32:37] DEBUG[18724]: chan_cellphone.c:826 do_monitor: rfcomm_read() (20) [+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)),("]oam",(0-1)),("smsfull",(0-1))
]Feb  5 06:32:37] DEBUG[18724]: chan_cellphone.c:826 do_monitor: rfcomm_read() (20) [
]Feb  5 06:32:37] DEBUG[18724]: chan_cellphone.c:826 do_monitor: rfcomm_read() (20) [OK
]Feb  5 06:32:37] DEBUG[18724]: chan_cellphone.c:651 rfcomm_write: rfcomm_write() (20) [AT+CIND=?
]Feb  5 06:32:37] DEBUG[18724]: chan_cellphone.c:826 do_monitor: rfcomm_read() (20) [
[Feb  5 06:32:37] DEBUG[18724]: chan_cellphone.c:826 do_monitor: rfcomm_read() (20) [+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)),("]oam",(0-1)),("smsfull",(0-1))
[Feb  5 06:32:37] DEBUG[18724]: chan_cellphone.c:863 do_monitor: CIEV_CALL=8 CIEV_CALLSETUP=0
]Feb  5 06:32:37] DEBUG[18724]: chan_cellphone.c:826 do_monitor: rfcomm_read() (20) [
]Feb  5 06:32:37] DEBUG[18724]: chan_cellphone.c:826 do_monitor: rfcomm_read() (20) [OK
]Feb  5 06:32:37] DEBUG[18724]: chan_cellphone.c:651 rfcomm_write: rfcomm_write() (20) [AT+CIND?
]Feb  5 06:32:37] DEBUG[18724]: chan_cellphone.c:826 do_monitor: rfcomm_read() (20) [
]Feb  5 06:32:37] DEBUG[18724]: chan_cellphone.c:826 do_monitor: rfcomm_read() (20) [+CIND: 3,5,0,0,1,1,0,0,0,0
]Feb  5 06:32:38] DEBUG[18724]: chan_cellphone.c:826 do_monitor: rfcomm_read() (20) [
]Feb  5 06:32:38] DEBUG[18724]: chan_cellphone.c:826 do_monitor: rfcomm_read() (20) [OK
]Feb  5 06:32:38] DEBUG[18724]: chan_cellphone.c:651 rfcomm_write: rfcomm_write() (20) [AT+CMER=3,0,0,1
]Feb  5 06:32:38] DEBUG[18724]: chan_cellphone.c:826 do_monitor: rfcomm_read() (20) [
]Feb  5 06:32:38] DEBUG[18724]: chan_cellphone.c:826 do_monitor: rfcomm_read() (20) [OK
]Feb  5 06:32:38] DEBUG[18724]: chan_cellphone.c:651 rfcomm_write: rfcomm_write() (20) [AT+CLIP=1
]Feb  5 06:32:38] DEBUG[18724]: chan_cellphone.c:826 do_monitor: rfcomm_read() (20) [
]Feb  5 06:32:38] DEBUG[18724]: chan_cellphone.c:826 do_monitor: rfcomm_read() (20) [OK
]Feb  5 06:32:38] DEBUG[18724]: chan_cellphone.c:651 rfcomm_write: rfcomm_write() (20) [AT+VGS=7
]Feb  5 06:32:38] DEBUG[18724]: chan_cellphone.c:826 do_monitor: rfcomm_read() (20) [
]Feb  5 06:32:38] DEBUG[18724]: chan_cellphone.c:826 do_monitor: rfcomm_read() (20) [OK
[Feb  5 06:32:38] DEBUG[18724]: chan_cellphone.c:895 do_monitor: Device cella online and ready.
[Feb  5 06:32:53] DEBUG[18724]: chan_sip.c:2703 do_setnat: Setting NAT on RTP to Off
[Feb  5 06:32:53] DEBUG[18724]: chan_sip.c:4404 sip_alloc: Allocating new SIP dialog for a2fedcfe-47b3-db11-9a30-000fcbb01957@eoyslebo-laptop - INVITE (With RTP)
[Feb  5 06:32:53] DEBUG[18724]: chan_sip.c:13543 handle_request_invite: Checking SIP call limits for device 
[Feb  5 06:32:53] DEBUG[18724]: pbx.c:1693 pbx_extension_helper: Launching 'Dial'
[Feb  5 06:32:53] DEBUG[18724]: rtp.c:1619 ast_rtp_make_compatible: Channel 'CELL/cella-dc4b' has no RTP, not doing anything
[Feb  5 06:32:53] DEBUG[18724]: channel.c:3205 ast_channel_inherit_variables: Not copying variable STACK-default-912345678-1.
[Feb  5 06:32:53] DEBUG[18724]: channel.c:3205 ast_channel_inherit_variables: Not copying variable SIPCALLID.
[Feb  5 06:32:53] DEBUG[18724]: channel.c:3205 ast_channel_inherit_variables: Not copying variable SIPDOMAIN.
[Feb  5 06:32:53] DEBUG[18724]: channel.c:3205 ast_channel_inherit_variables: Not copying variable SIPURI.
[Feb  5 06:32:53] DEBUG[18724]: chan_cellphone.c:380 cel_call: Calling cella/12345678 on CELL/cella-dc4b
[Feb  5 06:32:53] DEBUG[18724]: channel.c:2755 set_format: Set channel CELL/cella-dc4b to read format ulaw
[Feb  5 06:32:53] DEBUG[18724]: channel.c:2755 set_format: Set channel SIP/localhost-081c0458 to read format slin
[Feb  5 06:32:53] DEBUG[18724]: app_queue.c:568 changethread: Device 'SIP/localhost' changed to state '2' (In use) but we don't care because they're not a member of any queue.
]Feb  5 06:32:54] DEBUG[18724]: chan_cellphone.c:826 do_monitor: rfcomm_read() (20) [
]Feb  5 06:32:54] DEBUG[18724]: chan_cellphone.c:826 do_monitor: rfcomm_read() (20) [+CIEV: 2,4
]Feb  5 06:32:55] DEBUG[18724]: chan_cellphone.c:651 rfcomm_write: rfcomm_write() (20) [ATD12345678;
]Feb  5 06:32:55] DEBUG[18724]: chan_cellphone.c:826 do_monitor: rfcomm_read() (20) [
]Feb  5 06:32:55] DEBUG[18724]: chan_cellphone.c:826 do_monitor: rfcomm_read() (20) [+CIEV: 2,5
]Feb  5 06:32:55] DEBUG[18724]: chan_cellphone.c:826 do_monitor: rfcomm_read() (20) [
]Feb  5 06:32:55] DEBUG[18724]: chan_cellphone.c:826 do_monitor: rfcomm_read() (20) [OK
[Feb  5 06:33:02] DEBUG[18724]: chan_cellphone.c:1134 do_sco_listen: Incoming Audio Connection from device 00:0F:DE:2A:C1:4C
[Feb  5 06:33:02] DEBUG[18724]: chan_cellphone.c:1149 do_sco_listen: Set Audio Connection fd OK.
]Feb  5 06:33:04] DEBUG[18724]: chan_cellphone.c:826 do_monitor: rfcomm_read() (20) [
]Feb  5 06:33:04] DEBUG[18724]: chan_cellphone.c:826 do_monitor: rfcomm_read() (20) [+CIEV: 8,1
]Feb  5 06:33:18] DEBUG[18724]: chan_cellphone.c:826 do_monitor: rfcomm_read() (20) [
]Feb  5 06:33:18] DEBUG[18724]: chan_cellphone.c:826 do_monitor: rfcomm_read() (20) [+CIEV: 8,0
[Feb  5 06:33:18] DEBUG[18724]: channel.c:1589 ast_hangup: Hanging up channel 'CELL/cella-dc4b'
[Feb  5 06:33:18] DEBUG[18724]: chan_cellphone.c:402 cel_hangup: Hanging up device cella.
]Feb  5 06:33:18] DEBUG[18724]: chan_cellphone.c:651 rfcomm_write: rfcomm_write() (20) [AT+CHUP
[Feb  5 06:33:18] DEBUG[18724]: rtp.c:1556 ast_rtp_early_bridge: Channel '<unspecified>' has no RTP, not doing anything
[Feb  5 06:33:18] DEBUG[18724]: app_dial.c:1682 dial_exec_full: Exiting with DIALSTATUS=CHANUNAVAIL.
[Feb  5 06:33:18] DEBUG[18724]: pbx.c:1693 pbx_extension_helper: Launching 'Hangup'
[Feb  5 06:33:18] DEBUG[18724]: pbx.c:2289 __ast_pbx_run: Spawn extension (default,912345678,2) exited non-zero on 'SIP/localhost-081c0458'
[Feb  5 06:33:18] DEBUG[18724]: pbx.c:1543 pbx_substitute_variables_helper_full: Function result is '6000'
[Feb  5 06:33:18] DEBUG[18724]: pbx.c:1543 pbx_substitute_variables_helper_full: Function result is '912345678'
[Feb  5 06:33:18] DEBUG[18724]: pbx.c:1543 pbx_substitute_variables_helper_full: Function result is 'default'
[Feb  5 06:33:18] DEBUG[18724]: pbx.c:1543 pbx_substitute_variables_helper_full: Function result is 'SIP/localhost-081c0458'
[Feb  5 06:33:18] DEBUG[18724]: pbx.c:1543 pbx_substitute_variables_helper_full: Function result is 'CELL/cella-dc4b'
[Feb  5 06:33:18] DEBUG[18724]: pbx.c:1543 pbx_substitute_variables_helper_full: Function result is 'Hangup'
[Feb  5 06:33:18] DEBUG[18724]: pbx.c:1543 pbx_substitute_variables_helper_full: Function result is ''
[Feb  5 06:33:18] DEBUG[18724]: pbx.c:1543 pbx_substitute_variables_helper_full: Function result is '2007-02-05 06:32:53'
[Feb  5 06:33:18] DEBUG[18724]: pbx.c:1543 pbx_substitute_variables_helper_full: Function result is ''
[Feb  5 06:33:18] DEBUG[18724]: pbx.c:1543 pbx_substitute_variables_helper_full: Function result is '2007-02-05 06:33:18'
[Feb  5 06:33:18] DEBUG[18724]: pbx.c:1543 pbx_substitute_variables_helper_full: Function result is '25'
[Feb  5 06:33:18] DEBUG[18724]: pbx.c:1543 pbx_substitute_variables_helper_full: Function result is '0'
[Feb  5 06:33:18] DEBUG[18724]: pbx.c:1543 pbx_substitute_variables_helper_full: Function result is 'NO ANSWER'
[Feb  5 06:33:18] DEBUG[18724]: pbx.c:1543 pbx_substitute_variables_helper_full: Function result is 'DOCUMENTATION'
[Feb  5 06:33:18] DEBUG[18724]: pbx.c:1543 pbx_substitute_variables_helper_full: Function result is ''
[Feb  5 06:33:18] DEBUG[18724]: pbx.c:1543 pbx_substitute_variables_helper_full: Function result is '1170653573.0'
[Feb  5 06:33:18] DEBUG[18724]: pbx.c:1543 pbx_substitute_variables_helper_full: Function result is ''
[Feb  5 06:33:18] DEBUG[18724]: channel.c:1589 ast_hangup: Hanging up channel 'SIP/localhost-081c0458'
[Feb  5 06:33:18] DEBUG[18724]: chan_sip.c:3420 sip_hangup: Hangup call SIP/localhost-081c0458, SIP callid a2fedcfe-47b3-db11-9a30-000fcbb01957@eoyslebo-laptop)
[Feb  5 06:33:18] DEBUG[18724]: chan_sip.c:3379 hangup_cause2sip: AST hangup cause 16 (no match found in SIP)
[Feb  5 06:33:18] DEBUG[18724]: chan_cellphone.c:595 cel_devicestate: Checking device state for device cella
[Feb  5 06:33:18] DEBUG[18724]: chan_sip.c:2139 __sip_ack: Stopping retransmission on 'a2fedcfe-47b3-db11-9a30-000fcbb01957@eoyslebo-laptop' of Response 1: Match Found
[Feb  5 06:33:18] DEBUG[18724]: app_queue.c:568 changethread: Device 'CELL/cella' changed to state '1' (Not in use) but we don't care because they're not a member of any queue.
[Feb  5 06:33:18] DEBUG[18724]: app_queue.c:568 changethread: Device 'SIP/localhost' changed to state '1' (Not in use) but we don't care because they're not a member of any queue.
]Feb  5 06:33:18] DEBUG[18724]: chan_cellphone.c:826 do_monitor: rfcomm_read() (20) [
]Feb  5 06:33:18] DEBUG[18724]: chan_cellphone.c:826 do_monitor: rfcomm_read() (20) [+CIEV: 2,4
]Feb  5 06:33:18] DEBUG[18724]: chan_cellphone.c:826 do_monitor: rfcomm_read() (20) [
]Feb  5 06:33:18] DEBUG[18724]: chan_cellphone.c:826 do_monitor: rfcomm_read() (20) [OK
]Feb  5 06:33:20] DEBUG[18724]: chan_cellphone.c:826 do_monitor: rfcomm_read() (20) [
]Feb  5 06:33:20] DEBUG[18724]: chan_cellphone.c:900 do_monitor: Device 00:0F:DE:2A:C1:4C cella [
]Feb  5 06:33:20] DEBUG[18724]: chan_cellphone.c:826 do_monitor: rfcomm_read() (20) [+CIEV: 2,5
]Feb  5 06:33:20] DEBUG[18724]: chan_cellphone.c:900 do_monitor: Device 00:0F:DE:2A:C1:4C cella [+CIEV: 2,5
[Feb  5 06:33:34] DEBUG[18724]: chan_sip.c:2703 do_setnat: Setting NAT on RTP to Off
[Feb  5 06:33:34] DEBUG[18724]: chan_sip.c:4404 sip_alloc: Allocating new SIP dialog for 745d0717-48b3-db11-9a30-000fcbb01957@eoyslebo-laptop - INVITE (With RTP)
[Feb  5 06:33:34] DEBUG[18724]: chan_sip.c:13543 handle_request_invite: Checking SIP call limits for device 
[Feb  5 06:33:34] DEBUG[18724]: pbx.c:1693 pbx_extension_helper: Launching 'Dial'
[Feb  5 06:33:34] DEBUG[18724]: rtp.c:1619 ast_rtp_make_compatible: Channel 'CELL/cella-6ccd' has no RTP, not doing anything
[Feb  5 06:33:34] DEBUG[18724]: channel.c:3205 ast_channel_inherit_variables: Not copying variable STACK-default-912345678-1.
[Feb  5 06:33:34] DEBUG[18724]: channel.c:3205 ast_channel_inherit_variables: Not copying variable SIPCALLID.
[Feb  5 06:33:34] DEBUG[18724]: channel.c:3205 ast_channel_inherit_variables: Not copying variable SIPDOMAIN.
[Feb  5 06:33:34] DEBUG[18724]: channel.c:3205 ast_channel_inherit_variables: Not copying variable SIPURI.
[Feb  5 06:33:34] DEBUG[18724]: chan_cellphone.c:380 cel_call: Calling cella/12345678 on CELL/cella-6ccd
[Feb  5 06:33:34] DEBUG[18724]: channel.c:2755 set_format: Set channel CELL/cella-6ccd to read format ulaw
[Feb  5 06:33:34] DEBUG[18724]: channel.c:2755 set_format: Set channel SIP/localhost-081c5298 to read format slin
[Feb  5 06:33:34] DEBUG[18724]: app_queue.c:568 changethread: Device 'SIP/localhost' changed to state '2' (In use) but we don't care because they're not a member of any queue.
]Feb  5 06:33:35] DEBUG[18724]: chan_cellphone.c:651 rfcomm_write: rfcomm_write() (20) [ATD12345678;
]Feb  5 06:33:35] DEBUG[18724]: chan_cellphone.c:826 do_monitor: rfcomm_read() (20) [
]Feb  5 06:33:35] DEBUG[18724]: chan_cellphone.c:826 do_monitor: rfcomm_read() (20) [OK
[Feb  5 06:33:50] DEBUG[18724]: chan_sip.c:2075 __sip_autodestruct: Auto destroying SIP dialog 'a2fedcfe-47b3-db11-9a30-000fcbb01957@eoyslebo-laptop'
[Feb  5 06:34:05] ERROR[18724]: chan_cellphone.c:1016 do_monitor: Dial failed on CELL/cella-6ccd state 9
[Feb  5 06:34:05] DEBUG[18724]: channel.c:1589 ast_hangup: Hanging up channel 'CELL/cella-6ccd'
[Feb  5 06:34:05] DEBUG[18724]: chan_cellphone.c:402 cel_hangup: Hanging up device cella.
]Feb  5 06:34:05] DEBUG[18724]: chan_cellphone.c:651 rfcomm_write: rfcomm_write() (20) [AT+CHUP
[Feb  5 06:34:05] DEBUG[18724]: rtp.c:1556 ast_rtp_early_bridge: Channel '<unspecified>' has no RTP, not doing anything
[Feb  5 06:34:05] DEBUG[18724]: app_dial.c:1682 dial_exec_full: Exiting with DIALSTATUS=CHANUNAVAIL.
[Feb  5 06:34:05] DEBUG[18724]: pbx.c:1693 pbx_extension_helper: Launching 'Hangup'
[Feb  5 06:34:05] DEBUG[18724]: pbx.c:2289 __ast_pbx_run: Spawn extension (default,912345678,2) exited non-zero on 'SIP/localhost-081c5298'
[Feb  5 06:34:05] DEBUG[18724]: pbx.c:1543 pbx_substitute_variables_helper_full: Function result is '"" <6000>'
[Feb  5 06:34:05] DEBUG[18724]: pbx.c:1543 pbx_substitute_variables_helper_full: Function result is '6000'
[Feb  5 06:34:05] DEBUG[18724]: pbx.c:1543 pbx_substitute_variables_helper_full: Function result is '912345678'
[Feb  5 06:34:05] DEBUG[18724]: pbx.c:1543 pbx_substitute_variables_helper_full: Function result is 'default'
[Feb  5 06:34:05] DEBUG[18724]: pbx.c:1543 pbx_substitute_variables_helper_full: Function result is 'SIP/localhost-081c5298'
[Feb  5 06:34:05] DEBUG[18724]: pbx.c:1543 pbx_substitute_variables_helper_full: Function result is 'CELL/cella-6ccd'
[Feb  5 06:34:05] DEBUG[18724]: pbx.c:1543 pbx_substitute_variables_helper_full: Function result is 'Hangup'
[Feb  5 06:34:05] DEBUG[18724]: pbx.c:1543 pbx_substitute_variables_helper_full: Function result is ''
[Feb  5 06:34:05] DEBUG[18724]: pbx.c:1543 pbx_substitute_variables_helper_full: Function result is '2007-02-05 06:33:34'
[Feb  5 06:34:05] DEBUG[18724]: pbx.c:1543 pbx_substitute_variables_helper_full: Function result is ''
[Feb  5 06:34:05] DEBUG[18724]: pbx.c:1543 pbx_substitute_variables_helper_full: Function result is '2007-02-05 06:34:05'
[Feb  5 06:34:05] DEBUG[18724]: pbx.c:1543 pbx_substitute_variables_helper_full: Function result is '31'
[Feb  5 06:34:05] DEBUG[18724]: pbx.c:1543 pbx_substitute_variables_helper_full: Function result is '0'
[Feb  5 06:34:05] DEBUG[18724]: pbx.c:1543 pbx_substitute_variables_helper_full: Function result is 'NO ANSWER'
[Feb  5 06:34:05] DEBUG[18724]: pbx.c:1543 pbx_substitute_variables_helper_full: Function result is 'DOCUMENTATION'
[Feb  5 06:34:05] DEBUG[18724]: pbx.c:1543 pbx_substitute_variables_helper_full: Function result is ''
[Feb  5 06:34:05] DEBUG[18724]: pbx.c:1543 pbx_substitute_variables_helper_full: Function result is '1170653614.2'
[Feb  5 06:34:05] DEBUG[18724]: pbx.c:1543 pbx_substitute_variables_helper_full: Function result is ''
[Feb  5 06:34:05] DEBUG[18724]: channel.c:1589 ast_hangup: Hanging up channel 'SIP/localhost-081c5298'
[Feb  5 06:34:05] DEBUG[18724]: chan_sip.c:3420 sip_hangup: Hangup call SIP/localhost-081c5298, SIP callid 745d0717-48b3-db11-9a30-000fcbb01957@eoyslebo-laptop)
[Feb  5 06:34:05] DEBUG[18724]: chan_sip.c:3379 hangup_cause2sip: AST hangup cause 16 (no match found in SIP)
[Feb  5 06:34:05] DEBUG[18724]: chan_cellphone.c:595 cel_devicestate: Checking device state for device cella
[Feb  5 06:34:05] DEBUG[18724]: chan_sip.c:2139 __sip_ack: Stopping retransmission on '745d0717-48b3-db11-9a30-000fcbb01957@eoyslebo-laptop' of Response 1: Match Found
[Feb  5 06:34:05] DEBUG[18724]: app_queue.c:568 changethread: Device 'CELL/cella' changed to state '1' (Not in use) but we don't care because they're not a member of any queue.
[Feb  5 06:34:05] DEBUG[18724]: app_queue.c:568 changethread: Device 'SIP/localhost' changed to state '1' (Not in use) but we don't care because they're not a member of any queue.