pbx:~ # tail -f /var/log/asterisk/full [Nov 25 16:06:50] DEBUG[17318] chan_sip.c: Initializing initreq for method REGISTER - callid 228c20f55a90b8c348ccf01e50dcbe17@127.0.0.2 [Nov 25 16:06:50] DEBUG[17318] chan_sip.c: Stopping retransmission on '228c20f55a90b8c348ccf01e50dcbe17@127.0.0.2' of Request 137: Match Found [Nov 25 16:06:50] DEBUG[17318] chan_sip.c: Registration successful [Nov 25 16:06:50] DEBUG[17318] chan_sip.c: Cancelling timeout 154 [Nov 25 16:06:54] DEBUG[17318] chan_mobile.c: rfcomm_read() (SGH-F200) [+CIEV: 5,3] [Nov 25 16:06:54] DEBUG[17318] chan_mobile.c: Device SGH-F200 [+CIEV: 5,3] [Nov 25 16:06:59] DEBUG[17318] chan_mobile.c: rfcomm_read() (SGH-F200) [+CIEV: 5,1] [Nov 25 16:06:59] DEBUG[17318] chan_mobile.c: Device SGH-F200 [+CIEV: 5,1] [Nov 25 16:07:04] DEBUG[17318] chan_mobile.c: rfcomm_read() (SGH-F200) [+CIEV: 5,3] [Nov 25 16:07:04] DEBUG[17318] chan_mobile.c: Device SGH-F200 [+CIEV: 5,3] [Nov 25 16:07:22] DEBUG[17318] chan_mobile.c: rfcomm_read() (SGH-F200) [+CIEV: 5,1] [Nov 25 16:07:22] DEBUG[17318] chan_mobile.c: Device SGH-F200 [+CIEV: 5,1] [Nov 25 16:07:25] DEBUG[17318] chan_mobile.c: rfcomm_read() (SGH-F200) [+CIEV: 5,3] [Nov 25 16:07:25] DEBUG[17318] chan_mobile.c: Device SGH-F200 [+CIEV: 5,3] [Nov 25 16:07:31] DEBUG[17318] chan_mobile.c: rfcomm_read() (SGH-F200) [+CIEV: 5,1] [Nov 25 16:07:31] DEBUG[17318] chan_mobile.c: Device SGH-F200 [+CIEV: 5,1] [Nov 25 16:07:34] DEBUG[17318] chan_mobile.c: rfcomm_read() (SGH-F200) [RING] [Nov 25 16:07:34] DEBUG[17318] chan_mobile.c: Device SGH-F200 [RING] [Nov 25 16:07:34] DEBUG[17318] chan_mobile.c: rfcomm_read() (SGH-F200) [+CIEV: 4,1] [Nov 25 16:07:34] DEBUG[17318] chan_mobile.c: rfcomm_read() (SGH-F200) [+CLIP: "",145,,,""] [Nov 25 16:07:34] DEBUG[17318] pbx.c: Launching 'Answer' [Nov 25 16:07:34] VERBOSE[17318] logger.c: -- Executing [s@incoming_mobile:1] Answer("Mobile/SGH-F200-189e", "") in new stack ]Nov 25 16:07:34] DEBUG[17318] chan_mobile.c: rfcomm_write() (SGH-F200) [ATA [Nov 25 16:07:34] DEBUG[17318] pbx.c: Launching 'Wait' [Nov 25 16:07:34] VERBOSE[17318] logger.c: -- Executing [s@incoming_mobile:2] Wait("Mobile/SGH-F200-189e", "1") in new stack [Nov 25 16:07:34] DEBUG[17318] chan_mobile.c: Checking device state for device SGH-F200 [Nov 25 16:07:35] DEBUG[17318] chan_mobile.c: rfcomm_read() (SGH-F200) [OK] [Nov 25 16:07:35] DEBUG[17318] chan_mobile.c: rfcomm_read() (SGH-F200) [+CIEV: 5,4] [Nov 25 16:07:35] DEBUG[17318] chan_mobile.c: accept()ed socket. [Nov 25 16:07:35] DEBUG[17318] chan_mobile.c: Incoming Audio Connection from device 00:1B:59:14:77:38 MTU is 64 [Nov 25 16:07:35] DEBUG[17318] chan_mobile.c: About to accept() socket. [Nov 25 16:07:35] DEBUG[17318] chan_mobile.c: rfcomm_read() (SGH-F200) [+CIEV: 3,1] [Nov 25 16:07:35] DEBUG[17318] chan_mobile.c: rfcomm_read() (SGH-F200) [+CIEV: 4,0] [Nov 25 16:07:35] DEBUG[17318] pbx.c: Launching 'Verbose' [Nov 25 16:07:35] VERBOSE[17318] logger.c: -- Executing [s@incoming_mobile:3] Verbose("Mobile/SGH-F200-189e", "s") in new stack [Nov 25 16:07:35] VERBOSE[17318] logger.c: s [Nov 25 16:07:35] DEBUG[17318] pbx.c: Launching 'Verbose' [Nov 25 16:07:35] VERBOSE[17318] logger.c: -- Executing [s@incoming_mobile:4] Verbose("Mobile/SGH-F200-189e", "") in new stack [Nov 25 16:07:35] DEBUG[17318] pbx.c: Launching 'Authenticate' [Nov 25 16:07:35] VERBOSE[17318] logger.c: -- Executing [s@incoming_mobile:5] Authenticate("Mobile/SGH-F200-189e", "1234") in new stack [Nov 25 16:07:35] DEBUG[17318] channel.c: Set channel Mobile/SGH-F200-189e to write format gsm [Nov 25 16:07:35] VERBOSE[17318] logger.c: -- Playing 'agent-pass.gsm' (language 'en') [Nov 25 16:07:38] DEBUG[17318] channel.c: Set channel Mobile/SGH-F200-189e to write format slin [Nov 25 16:07:48] DEBUG[17318] channel.c: Set channel Mobile/SGH-F200-189e to write format gsm [Nov 25 16:07:48] VERBOSE[17318] logger.c: -- Playing 'auth-incorrect.gsm' (language 'en') [Nov 25 16:07:50] DEBUG[17318] chan_mobile.c: rfcomm_read() (SGH-F200) [+CIEV: 5,5] [Nov 25 16:07:52] DEBUG[17318] channel.c: Set channel Mobile/SGH-F200-189e to write format slin [Nov 25 16:08:02] DEBUG[17318] channel.c: Set channel Mobile/SGH-F200-189e to write format gsm [Nov 25 16:08:02] VERBOSE[17318] logger.c: -- Playing 'auth-incorrect.gsm' (language 'en') [Nov 25 16:08:07] DEBUG[17318] chan_mobile.c: rfcomm_read() (SGH-F200) [+CIEV: 3,0] [Nov 25 16:08:07] VERBOSE[17318] logger.c: << [ HANGUP (NULL) ] [Mobile/SGH-F200-189e] [Nov 25 16:08:07] DEBUG[17318] channel.c: Set channel Mobile/SGH-F200-189e to write format slin [Nov 25 16:08:07] DEBUG[17318] channel.c: Set channel Mobile/SGH-F200-189e to write format gsm [Nov 25 16:08:07] WARNING[17318] file.c: Failed to write frame [Nov 25 16:08:07] VERBOSE[17318] logger.c: -- Playing 'vm-goodbye.gsm' (language 'en') [Nov 25 16:08:07] DEBUG[17318] pbx.c: Spawn extension (incoming_mobile,s,5) exited non-zero on 'Mobile/SGH-F200-189e' [Nov 25 16:08:07] VERBOSE[17318] logger.c: == Spawn extension (incoming_mobile, s, 5) exited non-zero on 'Mobile/SGH-F200-189e' [Nov 25 16:08:07] DEBUG[17318] channel.c: Soft-Hanging up channel 'Mobile/SGH-F200-189e' [Nov 25 16:08:07] DEBUG[17318] pbx.c: Launching 'Answer' [Nov 25 16:08:07] VERBOSE[17318] logger.c: -- Executing [h@incoming_mobile:1] Answer("Mobile/SGH-F200-189e", "") in new stack [Nov 25 16:08:07] DEBUG[17318] pbx.c: Spawn extension (incoming_mobile,h,1) exited non-zero on 'Mobile/SGH-F200-189e' [Nov 25 16:08:07] VERBOSE[17318] logger.c: == Spawn extension (incoming_mobile, h, 1) exited non-zero on 'Mobile/SGH-F200-189e' [Nov 25 16:08:07] DEBUG[17318] channel.c: Hanging up channel 'Mobile/SGH-F200-189e' [Nov 25 16:08:07] DEBUG[17318] chan_mobile.c: Hanging up device SGH-F200. [Nov 25 16:08:07] DEBUG[17318] chan_mobile.c: Checking device state for device SGH-F200 [Nov 25 16:08:07] DEBUG[17318] chan_mobile.c: Checking device state for device SGH-F200 [Nov 25 16:08:10] DEBUG[17318] chan_mobile.c: rfcomm_read() (SGH-F200) [+CIEV: 5,2] [Nov 25 16:08:10] DEBUG[17318] chan_mobile.c: Device SGH-F200 [+CIEV: 5,2]