Lets get FM Radio on the Optimus S

[zefie]

C:\Users\zefie>adb shell dmesg | grep -i fm
<6>[ 7.393071] snd_set_fm_radio_volume 1
<6>[ 29.410538] snd_set_fm_radio_volume 4

idk the kernel seems to acknowledge something.

Interesting logcat:

This is when turning bluetooth on.

I/bluedroid( 1738): BTOFF_FMOFF_handling
I/bluedroid( 1738): BTOFF_FMOFF_handling : receiving BT_ON
I/BTAPP_FM( 7033): BTAPP_FM: Initialized IFS (res = 6)
I/BTL-IFS ( 7033): BTL_IF_RegisterSubSystem: Registered subsystem [FM]
I/BTAPP_FM( 7033): BTAPP_FM: Registered IFS (res = 0)
I/bluedroid( 1738): New state is BTON_FMOFF_handling
W/BluetoothServiceManager( 1738): DO NOT START FM SERVICE!!!!
D/BluetoothServiceManager( 1738): ***startService(): Bluetooth service bluetooth
_fm_receiver_service not enabled. Skipping...***

edit:
scanned P500 code for FMOFF FMON, found /system/lib/libbluedroid.so.. trying stuff

edit2: get this now
I/BTAPP_FM( 1961): BTAPP_FM: Initialized IFS (res = 6)
I/BTL-IFS ( 1961): BTL_IF_RegisterSubSystem: Registered subsystem [FM]
I/BTAPP_FM( 1961): BTAPP_FM: Registered IFS (res = 0)

but still no radio and still complaining service not enabled.
not listed in service list

yet when i disable BT

W/BluetoothServiceManager( 1715): DO NOT STOP FM SERVICE!!!!
D/BluetoothServiceManager( 1715): ***stopService(): Stopping service: bluetooth_
fm_receiver_service***
V/FmReceiverEventLoop( 1715): stop
D/FmReceiverEventLoop( 1715): stopFM_Loop()
V/FmReceiverEventLoop( 1715): getName
D/BluetoothServiceManager( 1715): Finishing service bluetooth_fm_receiver_servic
e
D/OppReceiver( 2300): SVC NAME=bluetooth_fm_receiver_service
I/BTL-IFS ( 2408): BTL_IF_UnregisterSubSystem: Unregistered subsystem [FM]

Getting closer.

 

[KSmithInNY]

Now that it's not complaining about BT ... are you trying this with headphones plugged in? If not, try it.

 

[zefie]

Now that it's not complaining about BT ... are you trying this with headphones plugged in? If not, try it.

of course, it wouldn't even get that far. And it is still complaining the service isn't running, yet its shutting down this non-running service when i turn off BT.

Nothing in the logs (logcat verbose or dmesg) show any errors. Hmm.

 

[KSmithInNY]

of course, it wouldn't even get that far. And it is still complaining the service isn't running, yet its shutting down this non-running service when i turn off BT.

Nothing in the logs (logcat verbose or dmesg) show any errors. Hmm.

hmmmm a mystery wrapped in an enigma.

 

[zefie]

I copied the entire bluetooth stack from the P500 (which also needs liblgdrm*) but still nothing. alot of stuff in logcat now though need to go through it.

I/ActivityManager( 1714): Start proc com.android.bluetooth for broadcast com.android.bluetooth/com.broadcom.bt.app.opp.OppReceiver: pid=2255 uid=10051 gids={3003, 3002, 3001, 1015}
I//system/bin/btld( 2374): gki_alloc_free_queue ret OK, id:3  size:1712, totol:50
I//system/bin/btld( 2374): gki_alloc_free_queue in, id:2 
I//system/bin/btld( 2374): gki_alloc_free_queue calling  gki_init_free_queue, id:2  size:660, totol:45
I//system/bin/btld( 2374): gki_alloc_free_queue ret OK, id:2  size:660, totol:45
I//system/bin/btld( 2374): gki_alloc_free_queue in, id:0 
I//system/bin/btld( 2374): gki_alloc_free_queue calling  gki_init_free_queue, id:0  size:64, totol:48
I//system/bin/btld( 2374): gki_alloc_free_queue ret OK, id:0  size:64, totol:48
V/BTL_CFG ( 2375): btl_cfg_get_soft_onoff_enabled : service.brcm.bt.soft_onoff = 0
I//system/bin/btld( 2374): userial_change_rate: Closing UART Port
I/BTL-SERVER( 2375): bte_main_rln_cplt: patchfile over-ride /system/bin/BCM4325D1_004.002.004.0218.0248.hcd
I/BTL-SERVER( 2375): bte_main_rln_cplt: Downloading patchfile /system/bin/BCM4325D1_004.002.004.0218.0248.hcd
I/BTLD    ( 2375): BCM2045_PRM_Init
I//system/bin/btld( 2374): gki_alloc_free_queue in, id:1 
I//system/bin/btld( 2374): gki_alloc_free_queue calling  gki_init_free_queue, id:1  size:288, totol:26
I//system/bin/btld( 2374): gki_alloc_free_queue ret OK, id:1  size:288, totol:26
I/BTLD    ( 2375): Minidrv download completed
E/BTLD    ( 2375): [hcis] Unknown HCI message type drop this byte 0x34
E/BTLD    ( 2375): [hcis] Unknown HCI message type drop this byte 0x31
I/BTLD    ( 2375): Launch RAM successful
I//system/bin/btld( 2374): userial_change_rate: Closing UART Port
I//system/bin/btld( 2374): userial_change_rate: Closing UART Port
I/BTL-SERVER( 2375): Setting local bd addr to 5C:17:D3:3E:98:C4
V/BTL_CFG ( 2375): btl_cfg_get_soft_onoff_enabled : service.brcm.bt.soft_onoff = 0
V/BTL_CFG ( 2375): btl_cfg_get_soft_onoff_enabled : service.brcm.bt.soft_onoff = 0
I/BTL-IFS ( 2375): BTL_IF_RegisterSubSystem: Registered subsystem [DTUN]
I/BTAPP_FM( 2375): BTAPP_FM: Initialized IFS (res = 6)
I/BTL-IFS ( 2375): BTL_IF_RegisterSubSystem: Registered subsystem [FM]
I/BTAPP_FM( 2375): BTAPP_FM: Registered IFS (res = 0)
I/BTL-IFS ( 2375): BTL_IF_RegisterSubSystem: Registered subsystem [TEST]
I/BTL_CFG ( 2375): btl_cfg_get_btport_redirection_enable: service.brcm.bt.btport_redir_on=
I/BTL-IFS ( 2375): BTL_IF_RegisterSubSystem: Registered subsystem [DUN]
I/BTL-IFS ( 2375): BTL_IF_RegisterSubSystem: Registered subsystem [SPP]
I/BTL-IFS ( 2375): BTL_IF_RegisterSubSystem: Registered subsystem [AG]
I/BTL-IFS ( 2375): BTL_IF_RegisterSubSystem: Registered subsystem [SCO]
I/BTA_AG_CO: ( 2375): bta_ag_co_init: ag_voice_settings = 96
I/BTA_AG_CO: ( 2375): bta_ag_co_init: ag_vsc_sco_pcm(0x18201b) = {0, 4, 0, 0, 0}
I/BTA_AG_CO: ( 2375): bta_ag_co_init: ag_vsc_pcm_config(0x182016) = {0, 0, 3, 3, 0}
I/BTL-BTAPP_OPC:( 2375): **** Starting DTUN [OPC] Interface ****
I/BTL-BTAPP_OPS:( 2375): OPS Supported Formats = 0x45
I/BTL-BTAPP_OPS:( 2375): OPS event:0
I/BTL-IFS ( 2375): BTL_IF_RegisterSubSystem: Registered subsystem [FTPS]
I/BTAPP_PR:( 2375): Initialized IFS (res = 6)
I/BTL-IFS ( 2375): BTL_IF_RegisterSubSystem: Registered subsystem [PR]
I/BTAPP_PR:( 2375): Registered IFS (res = 0)
I/BTL-IFS ( 2375): BTL_IF_RegisterSubSystem: Registered subsystem [SAPS]
I/BTAPP_SC:( 2375): btapp_sc_init: result = 0, btl_if_handle = 14
I/BTL-BTAPP_AV( 2375): AVRCP enabled
I/BTL-BTAPP_AV( 2375): Advanced Audio enabled
D/BTAPP_PBS:( 2375): btapp_pbs_init IFS init result = 6
I/BTL-IFS ( 2375): BTL_IF_RegisterSubSystem: Registered subsystem [PBS]
D/BTAPP_PBS:( 2375): BTL_IF_RegisterSubSystem result = 0 btl_if_pbs_handle = 8
I/        ( 2375): btapp_bts_init: btapp_bts_init
I/BTL-IFS ( 2375): BTL_IF_RegisterSubSystemMultiClnt: Registered subsystem [BTS]
D/        ( 2375): jvapi_if_dm_cback: #### jw_if_dm_cback ev BTA_JV_ENABLE_EVT ####
I/BTL-IFS ( 2375): main_server_thread: [CTRL] Client connected (14)
I/BTL-IFS ( 2375): attach_client: ######## Attached client subsystem DTUN (14) ######## 
D/BTL-IFS ( 2375): send_registration_rsp: send_registration_rsp [0]
I/BTL-IFS ( 2375): send_ctrl_msg: [BTL_IFS CTRL] send BTLIF_REGISTER_SUBSYS_RSP (CTRL) 2 pbytes (hdl 14)
I/BTL-IFS ( 2375): send_ctrl_msg: [BTL_IFS CTRL] send BTLIF_DTUN_SIGNAL_EVT (CTRL) 10 pbytes (hdl 14)
I//system/bin/btld( 2374): 		btapp_dm_GetLocalInfo()
I//system/bin/btld( 2374): 		btapp_dm_SetName()
I//system/bin/btld( 2374): 		btapp_dm_SetMode()
I//system/bin/btld( 2374): 		btapp_dm_AddDev()
I//system/bin/btld( 2374): 		btapp_dm_AddDev()
D/BT HSHFP( 1818): Loaded priority 00:19:7F:39:B9:A3 = 1003
D/BT HSHFP( 1818): Loaded priority 00:02:72:A0:EF:E6 = 1002
I/BTL_IFC ( 1818): BTL_IFC_RegisterSubSystem: Register subsystem [AG]
I/BTL_IFC ( 1818): btl_ifc_ctrl_connect: Connect control channel for subsystem [AG]
D/BTL_IFC_WRP( 1818): wrp_sock_create: CTRL
D/BTL_IFC_WRP( 1818): wrp_alloc_new_sock: wrp_alloc_new_sock sub 1
I/BTL_IFC ( 1714): BTL_IFC_RegisterSubSystem: Register subsystem [SPP]
I/BTL_IFC ( 1714): btl_ifc_ctrl_connect: Connect control channel for subsystem [SPP]
D/BTL_IFC_WRP( 1714): wrp_sock_create: CTRL
D/BTL_IFC_WRP( 1714): wrp_alloc_new_sock: wrp_alloc_new_sock sub 1
D/BTL_IFC_WRP( 1714): wrp_sock_create: 112
D/BTL_IFC_WRP( 1714): wrp_sock_connect: wrp_sock_connect brcm.bt.dtun:9000 (112)
D/BTL_IFC_WRP( 1818): wrp_sock_create: 34
D/BTL_IFC_WRP( 1818): wrp_sock_connect: wrp_sock_connect brcm.bt.dtun:9000 (34)
D/BTL_IFC_WRP( 1818): DTUN_MAKE_LOCAL_SERVER_NAME return name: brcm.bt.dtun.9000
I/BTL-IFS ( 2375): main_server_thread: [CTRL] Client connected (15)
I/BTL_IFC_WRP( 1818): wrp_sock_connect: Connected. (34)
I/BTL_IFC ( 1818): send_ctrl_msg: [BTL_IFC CTRL] send BTLIF_REGISTER_SUBSYS_REQ (AG) 0 pbytes (hdl 34)
I/BTL-IFS ( 2375): attach_client: ######## Attached client subsystem AG (15) ######## 
D/BTL-IFS ( 2375): send_registration_rsp: send_registration_rsp [0]
I/BTL-IFS ( 2375): send_ctrl_msg: [BTL_IFS CTRL] send BTLIF_REGISTER_SUBSYS_RSP (CTRL) 2 pbytes (hdl 15)
D/BTL_IFC ( 1818): BTL_IFC_RegisterSubSystem: add new ctrl fd to active set
I/BLZ_WRAPPER( 1818): btl_if_socket: btl_if_socket :: fam 31, type 1, proto 3
D/BTL_IFC_WRP( 1818): wrp_sock_create: AG
D/BTL_IFC_WRP( 1818): wrp_alloc_new_sock: wrp_alloc_new_sock sub 3
D/BTL_IFC_WRP( 1818): wrp_sock_create: 44
D/BLZ_WRAPPER( 1818): btl_if_setsockopt: btl_if_setsockopt level:18, optname:3
I/BLZ_WRAPPER( 1818): btl_if_setsockopt: configure rfcomm lm mode
D/BLZ_WRAPPER( 1818): btl_if_bind: [btl_if_bind] : (44:-1), disc_pending 0, asnc 0, selct 0, flags 0
I/BLZ_WRAPPER( 1818): btl_if_bind: btl_if_bind : [rfc] rc chan 10, bd[0:0]
D/BTL_IFC_WRP( 1818): DTUN_MAKE_LOCAL_SERVER_NAME return name: brcm.bt.dtun.9017
D/BTL_IFC_WRP( 1818): wrp_sock_bind: wrp_sock_bind:socket_local_server_bind result:0 server_name:brcm.bt.dtun.9017
D/BTL_IFC_WRP( 1818): wrp_sock_bind: socket_local_server_bind on port 9017
D/BTL_IFC ( 1818): BTL_IFC_CtrlSend: BTL_IFC_CtrlSend
I/BTL_IFC ( 1818): send_ctrl_msg: [BTL_IFC CTRL] send BTLIF_LISTEN_REQ (AG) 8 pbytes (hdl 34)
D/BLZ_WRAPPER( 1818): btl_if_listen: [btl_if_listen] : (44:-1), disc_pending 0, asnc 0, selct 0, flags 0
D/BLZ_WRAPPER( 1818): btl_if_listen: btl_if_listen : fd 44 [brcm.bt.dtun]
D/BTL_IFC_WRP( 1818): wrp_sock_listen: wrp_sock_listen : fd 44 brcm.bt.dtun:9017
I/BLZ_WRAPPER( 1818): btl_if_socket: btl_if_socket :: fam 31, type 1, proto 3
D/BTL_IFC_WRP( 1818): wrp_sock_create: AG
D/BTL_IFC_WRP( 1818): wrp_alloc_new_sock: wrp_alloc_new_sock sub 3
D/BTL_IFC_WRP( 1818): wrp_sock_create: 45
D/BLZ_WRAPPER( 1818): btl_if_setsockopt: btl_if_setsockopt level:18, optname:3
I/BLZ_WRAPPER( 1818): btl_if_setsockopt: configure rfcomm lm mode
D/BLZ_WRAPPER( 1818): btl_if_bind: [btl_if_bind] : (45:-1), disc_pending 0, asnc 0, selct 0, flags 0
I/BLZ_WRAPPER( 1818): btl_if_bind: btl_if_bind : [rfc] rc chan 11, bd[0:0]
D/BTL_IFC_WRP( 1818): DTUN_MAKE_LOCAL_SERVER_NAME return name: brcm.bt.dtun.9018
D/BTL_IFC_WRP( 1818): wrp_sock_bind: wrp_sock_bind:socket_local_server_bind result:0 server_name:brcm.bt.dtun.9018
D/BTL_IFC_WRP( 1818): wrp_sock_bind: socket_local_server_bind on port 9018
D/BTL_IFC ( 1818): BTL_IFC_CtrlSend: BTL_IFC_CtrlSend
I/BTL_IFC ( 1818): send_ctrl_msg: [BTL_IFC CTRL] send BTLIF_LISTEN_REQ (AG) 8 pbytes (hdl 34)
D/BTL_IFC_WRP( 1714): DTUN_MAKE_LOCAL_SERVER_NAME return name: brcm.bt.dtun.9000
I/BTL-IFS ( 2375): main_server_thread: [CTRL] Client connected (16)
I/BTL_IFC_WRP( 1714): wrp_sock_connect: Connected. (112)
I/BTL_IFC ( 1714): send_ctrl_msg: [BTL_IFC CTRL] send BTLIF_REGISTER_SUBSYS_REQ (SPP) 0 pbytes (hdl 112)
I/BTL-IFS ( 2375): attach_client: ######## Attached client subsystem SPP (16) ######## 
D/BTL-IFS ( 2375): send_registration_rsp: send_registration_rsp [0]
I/BTL-IFS ( 2375): send_ctrl_msg: [BTL_IFS CTRL] send BTLIF_REGISTER_SUBSYS_RSP (CTRL) 2 pbytes (hdl 16)
D/BLZ_WRAPPER( 1818): btl_if_listen: [btl_if_listen] : (45:-1), disc_pending 0, asnc 0, selct 0, flags 0
D/BLZ_WRAPPER( 1818): btl_if_listen: btl_if_listen : fd 45 [brcm.bt.dtun]
D/BTL_IFC_WRP( 1818): wrp_sock_listen: wrp_sock_listen : fd 45 brcm.bt.dtun:9018
D/BTL_IFC ( 1714): BTL_IFC_RegisterSubSystem: add new ctrl fd to active set
I/BrcmBtServiceLoader( 1714): ***Received Bluetooth ON Event****
I/BTL_IFC ( 1818): BTL_IFC_RegisterSubSystem: Register subsystem [SCO]
I/BTL_IFC ( 1818): btl_ifc_ctrl_connect: Connect control channel for subsystem [SCO]
D/BTL_IFC_WRP( 1818): wrp_sock_create: CTRL
D/BTL_IFC_WRP( 1818): wrp_alloc_new_sock: wrp_alloc_new_sock sub 1
D/BTL_IFC_WRP( 1818): wrp_sock_create: 46
D/BTL_IFC_WRP( 1818): wrp_sock_connect: wrp_sock_connect brcm.bt.dtun:9000 (46)
D/BTL_IFC_WRP( 1818): DTUN_MAKE_LOCAL_SERVER_NAME return name: brcm.bt.dtun.9000
I/BTL-IFS ( 2375): main_server_thread: [CTRL] Client connected (17)
I/BTL_IFC_WRP( 1818): wrp_sock_connect: Connected. (46)
I/BTL_IFC ( 1818): send_ctrl_msg: [BTL_IFC CTRL] send BTLIF_REGISTER_SUBSYS_REQ (SCO) 0 pbytes (hdl 46)
I/BTL-IFS ( 2375): attach_client: ######## Attached client subsystem SCO (17) ######## 
D/BTL-IFS ( 2375): send_registration_rsp: send_registration_rsp [0]
I/BTL-IFS ( 2375): send_ctrl_msg: [BTL_IFS CTRL] send BTLIF_REGISTER_SUBSYS_RSP (CTRL) 2 pbytes (hdl 17)
D/BTL_IFC ( 1818): BTL_IFC_RegisterSubSystem: add new ctrl fd to active set
I/bluetooth_ScoSocket.cpp( 1818): BTL_IFC_RegisterSubSystem SUB_SCO chdl:0x2e
E/ScoSocket( 1818): [BTUI] [### SCO ###] doAccept()... mState(2) [1:READY/2:ACCEPT/3:CONNECTING/4:CONNECTED/5:CLOSED]
D/com_broadcom_bt_service_ftp_FTPService.cpp( 1714): enableDefaultFtpServerNative
I/BTL_IFC ( 1714): BTL_IFC_RegisterSubSystem: Register subsystem [FTPS]
I/BTL_IFC ( 1714): btl_ifc_ctrl_connect: Connect control channel for subsystem [FTPS]
D/BTL_IFC_WRP( 1714): wrp_sock_create: CTRL
D/BTL_IFC_WRP( 1714): wrp_alloc_new_sock: wrp_alloc_new_sock sub 1
D/BTL_IFC_WRP( 1714): wrp_sock_create: 113
D/BTL_IFC_WRP( 1714): wrp_sock_connect: wrp_sock_connect brcm.bt.dtun:9000 (113)
D/BTL_IFC_WRP( 1714): DTUN_MAKE_LOCAL_SERVER_NAME return name: brcm.bt.dtun.9000
I/BTL-IFS ( 2375): main_server_thread: [CTRL] Client connected (18)
I/BTL_IFC_WRP( 1714): wrp_sock_connect: Connected. (113)
I/BTL_IFC ( 1714): send_ctrl_msg: [BTL_IFC CTRL] send BTLIF_REGISTER_SUBSYS_REQ (FTPS) 0 pbytes (hdl 113)
I/BTL-IFS ( 2375): attach_client: ######## Attached client subsystem FTPS (18) ######## 
D/BTL-IFS ( 2375): send_registration_rsp: send_registration_rsp [0]
I/BTL-IFS ( 2375): send_ctrl_msg: [BTL_IFS CTRL] send BTLIF_REGISTER_SUBSYS_RSP (CTRL) 2 pbytes (hdl 18)
D/BTL_IFC ( 1714): BTL_IFC_RegisterSubSystem: add new ctrl fd to active set
D/BTL_IFC ( 1714): BTL_IFC_CtrlSend: BTL_IFC_CtrlSend
I/BTL_IFC ( 1714): send_ctrl_msg: [BTL_IFC CTRL] send BTLIF_FTPS_ENABLE (FTPS) 0 pbytes (hdl 113)
D/com_broadcom_bt_service_pbap_PBAPService.cpp( 1714): enableDefaultPbapServerNative
I/BTL_IFC ( 1714): BTL_IFC_RegisterSubSystem: Register subsystem [PBS]
I/BTL_IFC ( 1714): btl_ifc_ctrl_connect: Connect control channel for subsystem [PBS]
D/BTL_IFC_WRP( 1714): wrp_sock_create: CTRL
D/BTL_IFC_WRP( 1714): wrp_alloc_new_sock: wrp_alloc_new_sock sub 1
D/BTL_IFC_WRP( 1714): wrp_sock_create: 114
D/BTL_IFC_WRP( 1714): wrp_sock_connect: wrp_sock_connect brcm.bt.dtun:9000 (114)
D/BTL_IFC_WRP( 1714): DTUN_MAKE_LOCAL_SERVER_NAME return name: brcm.bt.dtun.9000
I/BTL_IFC_WRP( 1714): wrp_sock_connect: Connected. (114)
I/BTL_IFC ( 1714): send_ctrl_msg: [BTL_IFC CTRL] send BTLIF_REGISTER_SUBSYS_REQ (PBS) 0 pbytes (hdl 114)
I/BTL-IFS ( 2375): send_ctrl_msg: [BTL_IFS CTRL] send BTLIF_FTPS_ENABLE_EVT (FTPS) 0 pbytes (hdl 18)
I/BTL-IFS ( 2375): main_server_thread: [CTRL] Client connected (19)
I/BTL-IFS ( 2375): attach_client: ######## Attached client subsystem PBS (19) ######## 
D/BTL-IFS ( 2375): send_registration_rsp: send_registration_rsp [0]
I/BTL-IFS ( 2375): send_ctrl_msg: [BTL_IFS CTRL] send BTLIF_REGISTER_SUBSYS_RSP (CTRL) 2 pbytes (hdl 19)
D/BTL_IFC ( 1714): BTL_IFC_RegisterSubSystem: add new ctrl fd to active set
D/BTL_IFC ( 1714): BTL_IFC_CtrlSend: BTL_IFC_CtrlSend
I/BTL_IFC ( 1714): send_ctrl_msg: [BTL_IFC CTRL] send BTLIF_PBS_ENABLE (PBS) 0 pbytes (hdl 114)
I/BTL-IFS ( 2375): send_ctrl_msg: [BTL_IFS CTRL] send BTLIF_PBS_ENABLE_EVT (PBS) 0 pbytes (hdl 19)
I/BTL_IFC ( 1714): btl_ifc_ctrl_rx: [BTL_IFC CTRL] recv BTLIF_FTPS_ENABLE_EVT (FTPS) 2 pbytes (hdl 113)
I/BTL_IFC ( 1714): BTL_IFC_RegisterSubSystem: Register subsystem [DUN]
I/BTL_IFC ( 1714): btl_ifc_ctrl_connect: Connect control channel for subsystem [DUN]
D/BTL_IFC_WRP( 1714): wrp_sock_create: CTRL
D/BTL_IFC_WRP( 1714): wrp_alloc_new_sock: wrp_alloc_new_sock sub 1
D/BTL_IFC_WRP( 1714): wrp_sock_create: 115
D/BTL_IFC_WRP( 1714): wrp_sock_connect: wrp_sock_connect brcm.bt.dtun:9000 (115)
D/BTL_IFC_WRP( 1714): DTUN_MAKE_LOCAL_SERVER_NAME return name: brcm.bt.dtun.9000
I/BTL-IFS ( 2375): main_server_thread: [CTRL] Client connected (20)
I/BTL_IFC_WRP( 1714): wrp_sock_connect: Connected. (115)
I/BTL_IFC ( 1714): send_ctrl_msg: [BTL_IFC CTRL] send BTLIF_REGISTER_SUBSYS_REQ (DUN) 0 pbytes (hdl 115)
I/BTL-IFS ( 2375): attach_client: ######## Attached client subsystem DUN (20) ######## 
D/BTL-IFS ( 2375): send_registration_rsp: send_registration_rsp [0]
I/BTL-IFS ( 2375): send_ctrl_msg: [BTL_IFS CTRL] send BTLIF_REGISTER_SUBSYS_RSP (CTRL) 2 pbytes (hdl 20)
D/BTL_IFC ( 1714): BTL_IFC_RegisterSubSystem: add new ctrl fd to active set
I/service_dun_BluetoothDUNService.cpp( 1714): enableNative: BTL_IFC enable result = 0
D/BTL_IFC ( 1714): BTL_IFC_CtrlSend: BTL_IFC_CtrlSend
I/BTL_IFC ( 1714): send_ctrl_msg: [BTL_IFC CTRL] send BTLIF_DG_ENABLE (DUN) 0 pbytes (hdl 115)
I/BTL-IFS ( 2375): send_ctrl_msg: [BTL_IFS CTRL] send BTLIF_DG_LISTEN_EVT (DUN) 1 pbytes (hdl 20)
I/BTAPP_DG:( 2375): bta_dg_dun_ports_have_state(enable): port 0 hdl = 1
I/BTAPP_DG:( 2375): bta_dg_dun_ports_have_state() returning TRUE
I/BTL-IFS ( 2375): send_ctrl_msg: [BTL_IFS CTRL] send BTLIF_DG_ENABLE_EVT (DUN) 0 pbytes (hdl 20)
I/BTL_IFC ( 1714): btl_ifc_ctrl_rx: [BTL_IFC CTRL] recv BTLIF_PBS_ENABLE_EVT (PBS) 2 pbytes (hdl 114)
D/com_broadcom_bt_service_pbap_PBAPService.cpp( 1714): #### handlePbapEvent 0xad3f8656 ####
D/com_broadcom_bt_service_pbap_PBAPService.cpp( 1714): ### PBAP Enabled ####
I/BTL_IFC ( 1714): btl_ifc_ctrl_rx: [BTL_IFC CTRL] recv BTLIF_DG_LISTEN_EVT (DUN) 3 pbytes (hdl 115)
I/service_dun_BluetoothDUNService.cpp( 1714): btlif_rx_ctrl(handle: 115, id: 4524)
I/service_dun_BluetoothDUNService.cpp( 1714): [BTLIF_DUN_LISTEN_EVT]: app_id: 5
E/BluetoothDUNService( 1714): [BTUI] [DUN:5] : BtUiDunStateChanged = state(1)
I/BTL_IFC ( 1714): btl_ifc_ctrl_rx: [BTL_IFC CTRL] recv BTLIF_DG_ENABLE_EVT (DUN) 2 pbytes (hdl 115)
I/service_dun_BluetoothDUNService.cpp( 1714): btlif_rx_ctrl(handle: 115, id: 4522)
I/service_dun_BluetoothDUNService.cpp( 1714): [BTLIF_DG_ENABLE_EVT]
D/OppReceiver( 2255): Received action: broadcom.bt.intent.action.BT_SVC_STATE_CHANGE
D/OppReceiver( 2255): Received action: broadcom.bt.intent.action.BT_SVC_STATE_CHANGE
D/OppReceiver( 2255): Received action: broadcom.bt.intent.action.BT_SVC_STATE_CHANGE
I/BTL-BTAPP_OPS:( 2375): btapp_op_set_exchange_folder(/mnt/sdcard/bluetooth/)
I/BTL-BTAPP_OPS:( 2375): btapp_op_set_owner_vcard(/data/data/com.android.bluetooth/.tmp/op_default.vcf)
D/OppReceiver( 2255): Received action: broadcom.bt.intent.action.BT_SVC_STATE_CHANGE
D/OppReceiver( 2255): Received action: broadcom.bt.intent.action.BT_SVC_STATE_CHANGE
D/OPPServerService( 2255): [main/1] onStartCommand():intent,flags,startid=Intent { act=brcm.rst cmp=com.android.bluetooth/com.broadcom.bt.app.opp.server.OPPServerService },0,1
D/OPPBaseService( 2255): [main/1] onProxyAvailable(). Proxy object = com.broadcom.bt.service.opp.BluetoothOPP@45f54540
W/CachedBluetoothDevice( 1824): [BTUI] [Q] processCommand()... CONNECT
I/BT HSHFP( 1818): connectHeadset: device = 00:02:72:A0:EF:E6
D/BT HSHFP( 1818): Headset state 0 -> 1, result = 1
D/BT HSHFP( 1818): Saved priority 00:02:72:A0:EF:E6 = 1000
D/BT HSHFP( 1818): SDP UUID: TYPE_HEADSET
I/BLZ_WRAPPER( 1818): btl_if_socket: btl_if_socket :: fam 31, type 1, proto 3
D/BTL_IFC_WRP( 1818): wrp_sock_create: AG
D/BTL_IFC_WRP( 1818): wrp_alloc_new_sock: wrp_alloc_new_sock sub 3
D/BTL_IFC_WRP( 1818): wrp_sock_create: 47
D/BLZ_WRAPPER( 1818): btl_if_setsockopt: btl_if_setsockopt level:18, optname:3
I/BLZ_WRAPPER( 1818): btl_if_setsockopt: configure rfcomm lm mode
I/BT HSHFP CPP( 1818): Created RFCOMM socket fd 47.
D/BLZ_WRAPPER( 1818): btl_if_connect: [btl_if_connect] : (47:-1), disc_pending 0, asnc 0, selct 0, flags 802
I/BLZ_WRAPPER( 1818): btl_if_connect: btl_if_connect : rf_chan 8, bd[2:0]
D/BLZ_WRAPPER( 1818): btl_if_connect: set async pending, return EINPROGRESS and send connect req...
D/BTL_IFC ( 1818): BTL_IFC_CtrlSend: BTL_IFC_CtrlSend
I/BTL_IFC ( 1818): send_ctrl_msg: [BTL_IFC CTRL] send BTLIF_CONNECT_REQ (AG) 8 pbytes (hdl 34)
I/BT HSHFP CPP( 1818): async connect is in progress (Operation now in progress)
D/BLZ_WRAPPER( 1818): btl_if_select: [btl_if_select] : (47:-1), disc_pending 0, asnc 4102, selct 1, flags 802
D/BLZ_WRAPPER( 1818): btl_if_select: btl_if_select : async pending, sleep [0 s, 500000 us]
W/BTLD    ( 2375): btm_dev_timeout( timeout_reason: x1 )::devcb.state: 5
W/BTLD    ( 2375): info:x10
I/BTL-IFS ( 2375): send_ctrl_msg: [BTL_IFS CTRL] send BTLIF_DTUN_SIGNAL_EVT (CTRL) 10 pbytes (hdl 14)
I/BT HSHFP( 1818): ACL connected
I/BT HSHFP CPP( 1818): select() timeout = 500 returned 0
D/BLZ_WRAPPER( 1818): btl_if_select: [btl_if_select] : (47:-1), disc_pending 0, asnc 4102, selct 1, flags 802
D/BLZ_WRAPPER( 1818): btl_if_select: btl_if_select : async pending, sleep [0 s, 500000 us]
I/BT HSHFP CPP( 1818): select() timeout = 500 returned 0
D/BLZ_WRAPPER( 1818): btl_if_select: [btl_if_select] : (47:-1), disc_pending 0, asnc 4102, selct 1, flags 802
D/BLZ_WRAPPER( 1818): btl_if_select: btl_if_select : async pending, sleep [0 s, 500000 us]
W/BTLD    ( 2375): process_service_search_attr_rsp
I/BT HSHFP CPP( 1818): select() timeout = 500 returned 0
D/BLZ_WRAPPER( 1818): btl_if_select: [btl_if_select] : (47:-1), disc_pending 0, asnc 4102, selct 1, flags 802
D/BLZ_WRAPPER( 1818): btl_if_select: btl_if_select : async pending, sleep [0 s, 500000 us]
I/BT HSHFP CPP( 1818): select() timeout = 500 returned 0
D/BLZ_WRAPPER( 1818): btl_if_select: [btl_if_select] : (47:-1), disc_pending 0, asnc 4102, selct 1, flags 802
D/BLZ_WRAPPER( 1818): btl_if_select: btl_if_select : async pending, sleep [0 s, 500000 us]
I/BT HSHFP CPP( 1818): select() timeout = 500 returned 0
D/BLZ_WRAPPER( 1818): btl_if_select: [btl_if_select] : (47:-1), disc_pending 0, asnc 4102, selct 1, flags 802
D/BLZ_WRAPPER( 1818): btl_if_select: btl_if_select : async pending, sleep [0 s, 500000 us]
W/BTLD    ( 2375): process_service_search_attr_rsp
I/BT HSHFP CPP( 1818): select() timeout = 500 returned 0
D/BLZ_WRAPPER( 1818): btl_if_select: [btl_if_select] : (47:-1), disc_pending 0, asnc 4102, selct 1, flags 802
D/BLZ_WRAPPER( 1818): btl_if_select: btl_if_select : async pending, sleep [0 s, 500000 us]
I/BT HSHFP CPP( 1818): select() timeout = 500 returned 0
D/BLZ_WRAPPER( 1818): btl_if_select: [btl_if_select] : (47:-1), disc_pending 0, asnc 4102, selct 1, flags 802
D/BLZ_WRAPPER( 1818): btl_if_select: btl_if_select : async pending, sleep [0 s, 500000 us]
W/BTLD    ( 2375): process_service_search_attr_rsp
W/BTLD    ( 2375): new conn_srvc id:5, app_id:0
W/BTLD    ( 2375): bta_dm_pm_ssr conn_srvc id:5, app_id:0
W/BTLD    ( 2375): bta_dm_pm_ssr:2, lat:1200
I/BTL-IFS ( 2375): BTL_IF_SetupListener: [BTL_IFS] setup datachannel listener [AG], sub_port 8
I/BTL-IFS ( 2375): send_ctrl_msg: [BTL_IFS CTRL] send BTLIF_CONNECT_RSP (AG) 4 pbytes (hdl 15)
I/BTL_IFC ( 1818): btl_ifc_ctrl_rx: [BTL_IFC CTRL] recv BTLIF_CONNECT_RSP (AG) 6 pbytes (hdl 34)
D/BLZ_WRAPPER( 1818): blz_ctrl_msg: [blz ctrl] received message [BTLIF_CONNECT_RSP]
I/BT HSHFP CPP( 1818): select() timeout = 500 returned 0
D/BLZ_WRAPPER( 1818): btl_if_select: [btl_if_select] : (47:-1), disc_pending 0, asnc 4102, selct 0, flags 802
D/BLZ_WRAPPER( 1818): btl_if_select: btl_if_select : async connection completed, check result
D/BTL_IFC_WRP( 1818): wrp_sock_connect: wrp_sock_connect brcm.bt.dtun:9015 (47)
D/BTL_IFC_WRP( 1818): DTUN_MAKE_LOCAL_SERVER_NAME return name: brcm.bt.dtun.9015
I/BTL-IFS ( 2375): btl_if_notify_local_event: Notify local event BTLIF_DATA_CHAN_IND
I//system/bin/btld( 2374): btl_ifs: calling accept() listen socket:21
I//system/bin/btld( 2374): btl_ifs:wrp_data_accept() accept local socket:22
I/BTL_IFC_WRP( 1818): wrp_sock_connect: Connected. (47)
D/BLZ_WRAPPER( 1818): btl_if_select: btl_if_select : transparant mode, result 1
I/BT HSHFP CPP( 1818): select() timeout = 500 returned 1
I/BT HSHFP CPP( 1818): Successful RFCOMM socket connect.
D/BT HSHFP( 1818): RFCOMM connection attempt took 4028 ms
D/BT HSHFP( 1818): Rfcomm connected
D/BT HSHFP( 1818): Headset state 1 -> 2, result = 1
E/AudioService( 1714): [BTUI] onReceive() : [HEADSET] is connected => [put] mConnectedDevices
W/StatusBarPolicy( 1714): [BTUI] Profile State [AG:2 AV:false FTP:0 PBAP:0 DUN:0]
W/CachedBluetoothDevice( 1824): [BTUI] [Q] processCommand()... CONNECT
D/BT HSHFP( 1818): Saved priority 00:02:72:A0:EF:E6 = 1004
E/BTLD    ( 2375): reset flags
W/StatusBarPolicy( 1714): [BTUI] Profile State [AG:2 AV:false FTP:0 PBAP:0 DUN:0]
W/BTLD    ( 2375): process_service_search_attr_rsp
E/BTLD    ( 2375): bta_av_rc_create ACP handle exist for shdl:0
W/BTLD    ( 2375): bta_dm_rm_cback:0, status:0
W/BTLD    ( 2375): new conn_srvc id:18, app_id:0
W/BTLD    ( 2375): bta_dm_pm_ssr conn_srvc id:5, app_id:0
W/BTLD    ( 2375): bta_dm_pm_ssr conn_srvc id:18, app_id:0
W/BTLD    ( 2375): bta_dm_pm_ssr:2, lat:1200
W/BTLD    ( 2375): ### :: codec open ::
W/BTLD    ( 2375): ### mtu 512, 660 bytes/frame, bitrate 229, nbr ch 0, freq 240
W/BTLD    ( 2375): ### alloc : 3, blk len 240, chmode:15, bitpool 32:2, subbands 12
W/BTLD    ( 2375): ###
I/BTL-IFS ( 2375): send_ctrl_msg: [BTL_IFS CTRL] send BTLIF_DTUN_SIGNAL_EVT (CTRL) 44 pbytes (hdl 14)
E/BluetoothA2dpService( 1714): [BTUI] onSinkPropertyChanged() : oState(1) nState(2) priority(1000)
W/StatusBarPolicy( 1714): [BTUI] Profile State [AG:2 AV:true FTP:0 PBAP:0 DUN:0]
W/BTLD    ( 2375): process_service_search_attr_rsp
W/BTLD    ( 2375): dm_pm_timer expires
W/BTLD    ( 2375): dm_pm_timer expires 0
W/BTLD    ( 2375): proc dm_pm_timer expires
W/CachedBluetoothDevice( 1824): [BTUI] [Q] processCommand()... DISCONNECT
D/BT HSHFP( 1818): Saved priority 00:02:72:A0:EF:E6 = 100
I/BLZ_WRAPPER( 1818): btl_if_close: btl_if_close (47)
D/BLZ_WRAPPER( 1818): btl_if_close: [btl_if_close] : (47:-1), disc_pending 0, asnc 0, selct 0, flags 2
D/BTL_IFC ( 1818): BTL_IFC_CtrlSend: BTL_IFC_CtrlSend
I/BTL_IFC ( 1818): send_ctrl_msg: [BTL_IFC CTRL] send BTLIF_DISCONNECT_REQ (AG) 2 pbytes (hdl 34)
D/BTL_IFC_WRP( 1818): wrp_close_s_only: wrp_close_s_only [47] (47:-1) [brcm.bt.dtun]
D/BTL_IFC_WRP( 1818): wrp_close_s_only: data socket closed
D/BTL_IFC_WRP( 1818): wsactive_del: delete wsock 47 from active list [ad3b0250]
I/BTL-IFS ( 2375): btl_if_notify_local_event: Notify local event BTLIF_DATA_CH_DISC_IND
D/BTL_IFC_WRP( 1818): wrp_close_s_only: wsock fully closed, return to pool
D/BT HSHFP( 1818): Headset state 2 -> 0, result = 2
W/StatusBarPolicy( 1714): [BTUI] Profile State [AG:0 AV:true FTP:0 PBAP:0 DUN:0]
W/CachedBluetoothDevice( 1824): [BTUI] [Q] processCommand()... DISCONNECT
E/BTLD    ( 2375): bta_av_str_stopped:audio_open_cnt=1
W/BTLD    ( 2375): bta_dm_rm_cback:1, status:6
W/BTLD    ( 2375): new conn_srvc id:18, app_id:1
I/BTL-IFS ( 2375): send_ctrl_msg: [BTL_IFS CTRL] send BTLIF_DISCONNECT_RSP (AG) 2 pbytes (hdl 15)
I/BTL_IFC ( 1818): btl_ifc_ctrl_rx: [BTL_IFC CTRL] recv BTLIF_DISCONNECT_RSP (AG) 4 pbytes (hdl 34)
D/BLZ_WRAPPER( 1818): blz_ctrl_msg: [blz ctrl] received message [BTLIF_DISCONNECT_RSP]
W/BTLD    ( 2375): bta_dm_rm_cback:1, status:1
I/BTL-IFS ( 2375): send_ctrl_msg: [BTL_IFS CTRL] send BTLIF_DTUN_SIGNAL_EVT (CTRL) 44 pbytes (hdl 14)
E/BluetoothA2dpService( 1714): [BTUI] onSinkPropertyChanged() : oState(3) nState(0) priority(100)
I/BTL-IFS ( 2375): send_ctrl_msg: [BTL_IFS CTRL] send BTLIF_DTUN_SIGNAL_EVT (CTRL) 11 pbytes (hdl 14)
W/BTLD    ( 2375): btui_security_cback( LINK FAILURE:: hci status: 19) 8==link super to
W/BTLD    ( 2375): for bd_addr: x00:x02:x72:xa0:xef:xe6
I/BT HSHFP( 1818): ACL disconnected
W/BTLD    ( 2375): dm_pm_timer expires
W/BTLD    ( 2375): dm_pm_timer expires 0
W/BTLD    ( 2375): proc dm_pm_timer expires

Edit: too annoyed at this to continue it. hopefully someone with more patience than I could get it to work.

 

[gotenks]

I/BTL-IFS ( 7033): BTL_IF_RegisterSubSystem: Registered subsystem [FM]

You are much more lucky than me. I cannot pass this point on Samsung Galaxy Spica I5700. In my case registration of FM process fails. Unfortunately I do not get precise error message to figure out what causes this error. Seems to be dtun server error. It happens when FM service tries to enable FM radio hardware.

Where can I find P500 framework to analize it and to try to get some more info ?

 

[zefie]

You are much more lucky than me. I cannot pass this point on Samsung Galaxy Spica I5700. In my case registration of FM process fails. Unfortunately I do not get precise error message to figure out what causes this error. Seems to be dtun server error. It happens when FM service tries to enable FM radio hardware.

Where can I find P500 framework to analize it and to try to get some more info ?

Page 1 of this thread.

BTW I got to that stage by copying the binaries for btld and the required library files.

 

[gotenks]

I am sure in my case btld is the issue. I've even disassembled it and checked that handling FM is there for sure, but I am not able to analize why dtun part fails ( my limited skills ).

PS. Thanks for the point where I can find P500 related stuff.

 

[zdre]

If i remember right, and this may have changed, iheartradio streams at 64 kbps, which translates roughly into 3.75MB per minute, which translates roughly into 225MB per hour. Listening to RMG every weekday for a month over iheartradio would be around 18gb if my math is correct. I really wish we had a good data calculator on these things so it could be more exact :\

Even if it was 32kbps thats still 9gb, You can see why I want FM radio lol

Your math is incorrect. People seem to be confusing bits (b) with bytes (B). Divide your numbers by 8.

 

[gotenks]

BTW I got to that stage by copying the binaries for btld and the required library files.

Using your tip I am now able to reach the point you have reached previously.
Few basic operations on radio thru a service seem to finish with status 0 (OK).
But no sound so far ...

Many thanks for a tip. I am now trying to analize what logcats contain

 

[zefie]

i can't even get basic operations the stupid bluetooth_fm_receiver_service is not enabled

 

[gotenks]

Looks like my current sound problem with FM is related to hardware differencies between LG and Samsung. Probably I will not be able to solve it that way .
So the only way left to me is to find out what P500 does and Spica doesn't do, that FM subsystem registers succesfully in LG server even on my Samsung Spica .

Thanks again for a valuable info.

 

[zefie]

Glad someone was able to get help from it..

 

[KSmithInNY]

I found the link below to be a good read. Maybe you can find a little gem of knowledge...

http://forum.xda-developers.com/archive/index.php/t-707404.html

 

[zefie]

idk HTC uses a different approach to it than LG. I don't see why this is giving me such issue when the Optimus 1 is nearly the same.

It could be a kernel issue though. We are lacking the kernel source

 

[zefie]

only difference between Opt1 and OptS kernel configs, besides my tweaks, was SERIO, so trying that now.

 

[KSmithInNY]

It could be a kernel issue though.

That was kind of my point. I know its long, but about half way down you'll notice they do a 180 and all the attention heads towards the kernel. This is where I was suggesting before. Also gbhil (who any android hacker should look up to) was suggesting you check the kernel. Usual what he says is what I try first cause theres a good chance he dropped some knowledge in his own cryptic way lol.

I'm thinking it might be time to look at the drivers/kernel. I still have my money riding on that being the thing that closes the loop and gets that radio working.

Hopefully you/somebody can come up with some crazy idea because it's usually the craziest, last, least likely to work idea that ends up doing it.

 

[zefie]

That was kind of my point. I know its long, but about half way down you'll notice they do a 180 and all the attention heads towards the kernel. This is where I was suggesting before. Also gbhil (who any android hacker should look up to) was suggesting you check the kernel. Usual what he says is what I try first cause theres a good chance he dropped some knowledge in his own cryptic way lol.

I'm thinking it might be time to look at the drivers/kernel. I still have my money riding on that being the thing that closes the loop and gets that radio working.

Hopefully you/somebody can come up with some crazy idea because it's usually the craziest, last, least likely to work idea that ends up doing it.

Well like I said the only difference between the configs was SERIO, so either our driver doesn't have support or it is something else. They had the service running but no sound. We have no service.

But if it is the kernel we pretty much have nothing until the P500 sources come out.

 

[klobster]

But if it is the kernel we pretty much have nothing until the P500 sources come out.

www.lg.com/global/support/opensource/opensource.jsp

In 'Category' select 'Mobile Phones'. In 'Model Name' enter 'LGLS670'.

Edit:
Oops I meant 'LGKU3700'
Thats the codename for the korean optimus one, should be the same source.

 

[zefie]

I honestly think somehow I'm missing something somewhere within the android framework. There is a function called enableFmRadio() which is supposed to be called by a series of commands when the FmRadio.apk starts, however it never seems to happen.

FmRadio.apk doesn't log anything like no such command, or why it's not running it. Its just very annoying.

There are no errors in the dmesg or logcat anymore that can help.

That is why I posted this:
http://androidforums.com/lg-optimus-one-p500/226502-fm-radio.html#post1946929

Anyone else with an FM-Radio enabled Froyo (2.2) LG phone who would be willing to provide the requested logs would be appreciated

Unforunately Cupcake, Donut or Eclair wouldn't be much help, sorry.

Either I am missing something critical, or there is a config somewhere that is telling it "hey don't enable FM this is a Sprint phone and Sprint didn't pay us for FM"