Problem with chan_mobile

Basildane · August 23, 2010, 11:02pm

I have successfully paired my Blackberry 9000 with Asterisk but I have one problem.
When I leave the area, then return, the Blackberry doesn’t reconnect. It just goes through this connect / disconnect cycle every 30 seconds.

Asterisk 1.6.2.11
Asterisk addons 1.6.2.1
Centos 5
i-Concepts bluetooth dongle

chan_mobile.c:     -- Bluetooth Device blackberry has disconnected.
chan_mobile.c:     -- Bluetooth Device blackberry has connected, initializing...
chan_mobile.c:     -- Error initializing Bluetooth device blackberry.
chan_mobile.c:     -- Bluetooth Device blackberry has disconnected.
chan_mobile.c:     -- Bluetooth Device blackberry has connected, initializing...
chan_mobile.c:     -- Error initializing Bluetooth device blackberry.

Here is debug listing after I move out of the area, then return.

[Aug 23 16:42:02] VERBOSE[30894] chan_mobile.c:     -- Bluetooth Device blackberry has connected, initializing...
[Aug 23 16:42:02] DEBUG[21065] chan_mobile.c: rfcomm_write() (39) [AT+BRSF=4
]
[Aug 23 16:42:02] DEBUG[21065] chan_mobile.c: [blackberry] +BRSF:487
[Aug 23 16:42:02] DEBUG[21065] chan_mobile.c: [blackberry] OK
[Aug 23 16:42:02] DEBUG[21065] chan_mobile.c: [blackberry] BSRF sent successfully
[Aug 23 16:42:02] DEBUG[21065] chan_mobile.c: rfcomm_write() (39) [AT+CMER=3,0,0,1
]
[Aug 23 16:42:02] DEBUG[21065] chan_mobile.c: [blackberry] OK
[Aug 23 16:42:02] DEBUG[21065] chan_mobile.c: [blackberry] CMER sent successfully
[Aug 23 16:42:02] DEBUG[21065] chan_mobile.c: rfcomm_write() (39) [AT+CIND=?
]
[Aug 23 16:42:12] DEBUG[21065] chan_mobile.c: [blackberry] timeout waiting for rfcomm data, disconnecting
[Aug 23 16:42:12] DEBUG[21065] chan_mobile.c: [blackberry] timeout during CIND test
[Aug 23 16:42:12] VERBOSE[21065] chan_mobile.c:     -- Error initializing Bluetooth device blackberry.
[Aug 23 16:42:12] VERBOSE[21065] chan_mobile.c:     -- Bluetooth Device blackberry has disconnected.
[Aug 23 16:42:36] VERBOSE[30894] chan_mobile.c:     -- Bluetooth Device blackberry has connected, initializing...
[Aug 23 16:42:36] DEBUG[21066] chan_mobile.c: rfcomm_write() (39) [AT+BRSF=4
]
[Aug 23 16:42:36] DEBUG[21066] chan_mobile.c: [blackberry] +BRSF:487
[Aug 23 16:42:36] DEBUG[21066] chan_mobile.c: [blackberry] OK
[Aug 23 16:42:36] DEBUG[21066] chan_mobile.c: [blackberry] BSRF sent successfully
[Aug 23 16:42:36] DEBUG[21066] chan_mobile.c: rfcomm_write() (39) [AT+CMER=3,0,0,1
]
[Aug 23 16:42:36] DEBUG[21066] chan_mobile.c: [blackberry] OK
[Aug 23 16:42:36] DEBUG[21066] chan_mobile.c: [blackberry] CMER sent successfully
[Aug 23 16:42:36] DEBUG[21066] chan_mobile.c: rfcomm_write() (39) [AT+CIND=?
]
[Aug 23 16:42:46] DEBUG[21066] chan_mobile.c: [blackberry] timeout waiting for rfcomm data, disconnecting
[Aug 23 16:42:46] DEBUG[21066] chan_mobile.c: [blackberry] timeout during CIND test
[Aug 23 16:42:46] VERBOSE[21066] chan_mobile.c:     -- Error initializing Bluetooth device blackberry.
[Aug 23 16:42:46] VERBOSE[21066] chan_mobile.c:     -- Bluetooth Device blackberry has disconnected.
[Aug 23 16:43:12] VERBOSE[30894] chan_mobile.c:     -- Bluetooth Device blackberry has connected, initializing...
[Aug 23 16:43:12] DEBUG[21099] chan_mobile.c: rfcomm_write() (39) [AT+BRSF=4
]
[Aug 23 16:43:12] DEBUG[21099] chan_mobile.c: [blackberry] +BRSF:487
[Aug 23 16:43:12] DEBUG[21099] chan_mobile.c: [blackberry] OK
[Aug 23 16:43:12] DEBUG[21099] chan_mobile.c: [blackberry] BSRF sent successfully
[Aug 23 16:43:12] DEBUG[21099] chan_mobile.c: rfcomm_write() (39) [AT+CMER=3,0,0,1
]
[Aug 23 16:43:12] DEBUG[21099] chan_mobile.c: [blackberry] OK
[Aug 23 16:43:12] DEBUG[21099] chan_mobile.c: [blackberry] CMER sent successfully
[Aug 23 16:43:12] DEBUG[21099] chan_mobile.c: rfcomm_write() (39) [AT+CIND=?
]
[Aug 23 16:43:22] DEBUG[21099] chan_mobile.c: [blackberry] timeout waiting for rfcomm data, disconnecting
[Aug 23 16:43:22] DEBUG[21099] chan_mobile.c: [blackberry] timeout during CIND test
[Aug 23 16:43:22] VERBOSE[21099] chan_mobile.c:     -- Error initializing Bluetooth device blackberry.
[Aug 23 16:43:22] VERBOSE[21099] chan_mobile.c:     -- Bluetooth Device blackberry has disconnected.
[Aug 23 16:43:53] VERBOSE[30894] chan_mobile.c:     -- Bluetooth Device blackberry has connected, initializing...
[Aug 23 16:43:53] DEBUG[21100] chan_mobile.c: rfcomm_write() (39) [AT+BRSF=4
]
[Aug 23 16:43:53] DEBUG[21100] chan_mobile.c: [blackberry] +BRSF:487
[Aug 23 16:43:53] DEBUG[21100] chan_mobile.c: [blackberry] OK
[Aug 23 16:43:53] DEBUG[21100] chan_mobile.c: [blackberry] BSRF sent successfully
[Aug 23 16:43:53] DEBUG[21100] chan_mobile.c: rfcomm_write() (39) [AT+CMER=3,0,0,1
]
[Aug 23 16:43:53] DEBUG[21100] chan_mobile.c: [blackberry] OK
[Aug 23 16:43:53] DEBUG[21100] chan_mobile.c: [blackberry] CMER sent successfully
[Aug 23 16:43:53] DEBUG[21100] chan_mobile.c: rfcomm_write() (39) [AT+CIND=?
]
[Aug 23 16:44:03] DEBUG[21100] chan_mobile.c: [blackberry] timeout waiting for rfcomm data, disconnecting
[Aug 23 16:44:03] DEBUG[21100] chan_mobile.c: [blackberry] timeout during CIND test
[Aug 23 16:44:03] VERBOSE[21100] chan_mobile.c:     -- Error initializing Bluetooth device blackberry.
[Aug 23 16:44:03] VERBOSE[21100] chan_mobile.c:     -- Bluetooth Device blackberry has disconnected.

And here is an example of it finally connecting. After I manually turn off bluetooth, and turn it back on (on the BB).

[Aug 23 16:55:06] VERBOSE[30894] chan_mobile.c:     -- Bluetooth Device blackberry has connected, initializing...
[Aug 23 16:55:06] DEBUG[21247] chan_mobile.c: rfcomm_write() (39) [AT+BRSF=4
]
[Aug 23 16:55:06] DEBUG[21247] chan_mobile.c: [blackberry] +BRSF:487
[Aug 23 16:55:06] DEBUG[21247] chan_mobile.c: [blackberry] OK
[Aug 23 16:55:06] DEBUG[21247] chan_mobile.c: [blackberry] BSRF sent successfully
[Aug 23 16:55:06] DEBUG[21247] chan_mobile.c: rfcomm_write() (39) [AT+CMER=3,0,0,1
]
[Aug 23 16:55:06] DEBUG[21247] chan_mobile.c: [blackberry] OK
[Aug 23 16:55:06] DEBUG[21247] chan_mobile.c: [blackberry] CMER sent successfully
[Aug 23 16:55:06] DEBUG[21247] chan_mobile.c: rfcomm_write() (39) [AT+CIND=?
]
[Aug 23 16:55:06] DEBUG[21247] chan_mobile.c: [blackberry] +CIND: ("service",(0-1)),("call",(0-1)),("callsetup",(0-3)),("battchg",(0-5)),("signal",(0-5)),("batterywarning",(0-1)),("chargerconnected",(0-1)),("callheld",(0-2)),("roam",(0-1))
[Aug 23 16:55:06] DEBUG[21247] chan_mobile.c: ignoring unknown CIND indicator 'batterywarning'
[Aug 23 16:55:06] DEBUG[21247] chan_mobile.c: ignoring unknown CIND indicator 'chargerconnected'
[Aug 23 16:55:06] DEBUG[21247] chan_mobile.c: [blackberry] OK
[Aug 23 16:55:06] DEBUG[21247] chan_mobile.c: [blackberry] CIND test sent successfully
[Aug 23 16:55:06] DEBUG[21247] chan_mobile.c: [blackberry] call: 2
[Aug 23 16:55:06] DEBUG[21247] chan_mobile.c: [blackberry] callsetup: 3
[Aug 23 16:55:06] DEBUG[21247] chan_mobile.c: rfcomm_write() (39) [AT+CIND?
]
[Aug 23 16:55:06] DEBUG[21247] chan_mobile.c: [blackberry] +CIND: 1,0,0,1,3,0,0,0,0
[Aug 23 16:55:06] DEBUG[21247] chan_mobile.c: [blackberry] OK
[Aug 23 16:55:06] DEBUG[21247] chan_mobile.c: [blackberry] CIND sent successfully
[Aug 23 16:55:06] DEBUG[21247] chan_mobile.c: rfcomm_write() (39) [AT+CLIP=1
]
[Aug 23 16:55:06] DEBUG[21247] chan_mobile.c: [blackberry] OK
[Aug 23 16:55:06] DEBUG[21247] chan_mobile.c: [blackberry] caling line indication enabled
[Aug 23 16:55:06] DEBUG[21247] chan_mobile.c: rfcomm_write() (39) [AT+VGS=15
]
[Aug 23 16:55:06] VERBOSE[21247] chan_mobile.c:     -- Bluetooth Device blackberry initialized and ready.
[Aug 23 16:55:06] DEBUG[21247] chan_mobile.c: [blackberry] OK
[Aug 23 16:55:06] DEBUG[21247] chan_mobile.c: [blackberry] volume level synchronization successful
[Aug 23 16:55:11] DEBUG[21247] chan_mobile.c: [blackberry] error reading from device: Connection reset by peer (104)
[Aug 23 16:55:11] VERBOSE[21247] chan_mobile.c:     -- Bluetooth Device blackberry has disconnected.
[Aug 23 16:55:56] DEBUG[30894] chan_mobile.c: connect() failed (112).
[Aug 23 16:56:28] VERBOSE[30894] chan_mobile.c:     -- Bluetooth Device blackberry has connected, initializing...
[Aug 23 16:56:28] DEBUG[21252] chan_mobile.c: rfcomm_write() (39) [AT+BRSF=4
]
[Aug 23 16:56:28] DEBUG[21252] chan_mobile.c: [blackberry] +BRSF:487
[Aug 23 16:56:28] DEBUG[21252] chan_mobile.c: [blackberry] OK
[Aug 23 16:56:28] DEBUG[21252] chan_mobile.c: [blackberry] BSRF sent successfully
[Aug 23 16:56:28] DEBUG[21252] chan_mobile.c: rfcomm_write() (39) [AT+CMER=3,0,0,1
]
[Aug 23 16:56:28] DEBUG[21252] chan_mobile.c: [blackberry] OK
[Aug 23 16:56:28] DEBUG[21252] chan_mobile.c: [blackberry] CMER sent successfully
[Aug 23 16:56:28] DEBUG[21252] chan_mobile.c: rfcomm_write() (39) [AT+CIND=?
]
[Aug 23 16:56:28] DEBUG[21252] chan_mobile.c: [blackberry] +CIND: ("service",(0-1)),("call",(0-1)),("callsetup",(0-3)),("battchg",(0-5)),("signal",(0-5)),("batterywarning",(0-1)),("chargerconnected",(0-1)),("callheld",(0-2)),("roam",(0-1))
[Aug 23 16:56:28] DEBUG[21252] chan_mobile.c: ignoring unknown CIND indicator 'batterywarning'
[Aug 23 16:56:28] DEBUG[21252] chan_mobile.c: ignoring unknown CIND indicator 'chargerconnected'
[Aug 23 16:56:28] DEBUG[21252] chan_mobile.c: [blackberry] OK
[Aug 23 16:56:28] DEBUG[21252] chan_mobile.c: [blackberry] CIND test sent successfully
[Aug 23 16:56:28] DEBUG[21252] chan_mobile.c: [blackberry] call: 2
[Aug 23 16:56:28] DEBUG[21252] chan_mobile.c: [blackberry] callsetup: 3
[Aug 23 16:56:28] DEBUG[21252] chan_mobile.c: rfcomm_write() (39) [AT+CIND?
]
[Aug 23 16:56:29] DEBUG[21252] chan_mobile.c: [blackberry] +CIND: 1,0,0,1,5,0,0,0,0
[Aug 23 16:56:29] DEBUG[21252] chan_mobile.c: [blackberry] OK
[Aug 23 16:56:29] DEBUG[21252] chan_mobile.c: [blackberry] CIND sent successfully
[Aug 23 16:56:29] DEBUG[21252] chan_mobile.c: rfcomm_write() (39) [AT+CLIP=1
]
[Aug 23 16:56:29] DEBUG[21252] chan_mobile.c: [blackberry] OK
[Aug 23 16:56:29] DEBUG[21252] chan_mobile.c: [blackberry] caling line indication enabled
[Aug 23 16:56:29] DEBUG[21252] chan_mobile.c: rfcomm_write() (39) [AT+VGS=15
]
[Aug 23 16:56:29] VERBOSE[21252] chan_mobile.c:     -- Bluetooth Device blackberry initialized and ready.
[Aug 23 16:56:29] DEBUG[21252] chan_mobile.c: [blackberry] OK
[Aug 23 16:56:29] DEBUG[21252] chan_mobile.c: [blackberry] volume level synchronization successful

Basildane · August 25, 2010, 11:52am

In desperation, I upgrade my BlueZ code to the latest 4.69.
Still no change. I have to manually turn off bluetooth and turn it back on to get the phone to register, every time.

system · September 17, 2010, 4:25pm

I’m facing the same problem, even worse, because restarting the BT service on the mobile phone doesn’t fix the issue.
2nd, I’m not able to trace any debug info, even if I set the debug level to 15 (core set debug 15), and the verbose level, as well to 15, this is all I can see in the console:

    -- Bluetooth Device iga-mobile has disconnected.
    -- Bluetooth Device iga-mobile has connected, initializing...
    -- Bluetooth Device iga-mobile initialized and ready.
    -- Bluetooth Device iga-mobile has disconnected.
    -- Bluetooth Device iga-mobile has connected, initializing...

Are there any ways to enhance the debug output?

Thanks and regards!

lcvleo · November 7, 2010, 10:25pm

I have the same problem. I’m using a Motorola V3, CentOS 5.5 64-bit, Asterisk 1.8, Bluez 3.7.