tedsalmon / BlueBus

A Bluetooth module for vehicles equipped with I-Bus
Other
172 stars 40 forks source link

Not able to autoplay (BC127 BlueBus not able to connect) #203

Open HDC67 opened 5 months ago

HDC67 commented 5 months ago

Ok, firstly this is DIY built BlueBus v1. It has generally worked just fine but seems with the current phone I have, the Autoplay doesn't work. I don't drive the car that much, but I'd probably say it's never worked with this phone. It did work with an older phone. Mostly it works fine but the noticeable failure is the auto play doesn't work. It's not a huge issue hence I only today got around to getting a log.

It seems what happens is:

1) Ignition on 2) It tries to connect to phone 3) Phone seems to think it's connected according to Bluetooth menu 4) BC127 returns "OPEN_ERROR A2DP " including the trailing space. 5) This happens a few times - sometimes the BMBT play/pause indicator toggles whilst this is happening 6) BlueBus gives up 7) I press the play/pause on the BMBT or in the app and it starts to play properly with no extra effort

Other points of note: 1) If I turn off ignition, it disconnects and the player (PlexAmp) stops. If I say skip the track but leave device paused whilst the car is off, when ignition comes back on it connects properly and autoplay works. Wierd right? 2) I tried both unparing and the BlueBus RESTORE command as well as deleting the device from the phone itself. When I enabled pairing again, the phone popped up immediately saying the car was trying to connect and did I want to allow it (because I'd unpaired on the phone). So clearly the BC127 hadn't completely forgotten about my phone. 3) ERROR: 0x0019 you see when it seems to give up means "Name not found" whatever that means.

Phone is a Oppo Find X5 Pro. It seems it might be a phone issue or at least a phone/BC127 issue. I have 7.3 of the BC127 firmware and I'm using 1.3.4 of BlueBus although I had been using 1.1.18 I think and tried to upgrade hoping it might magically fix it :)

I appreciate that it's not a purchased module but would be interested any ideas, or maybe things to try?

Maybe if someone else has an Oppo Find X5 Pro or other Oppo, does it work properly?

Log looks like this from just before the ignition on, through the trying to connect phase and ultimately the actual playing by pressing play/pause (I think on the BMBT, not the app). This is before I tried to unpair etc.

[141850] DEBUG: IBus: RX[8]: 68 06 3B A5 62 01 00 93 [SELF]
[141862] DEBUG: IBus: RX[10]: 68 08 3B A5 62 01 02 7C 7C 9F [SELF]
[141876] DEBUG: IBus: RX[8]: 68 06 3B A5 62 01 00 93 [SELF]
[141888] DEBUG: IBus: RX[17]: 68 0F 3B 21 62 01 40 42 6C 75 65 74 6F 6F 74 68 08 [SELF]
[141903] DEBUG: IBus: RX[8]: 68 06 3B A5 62 01 00 93 [SELF]
[141916] DEBUG: IBus: RX[10]: 68 08 3B A5 62 01 02 7C 7C 9F [SELF]
[141929] DEBUG: IBus: RX[8]: 68 06 3B A5 62 01 00 93 [SELF]
[141941] DEBUG: IBus: RX[17]: 68 0F 3B A5 62 01 06 4E 6F 20 44 65 76 69 63 65 A5 [SELF]
[141957] DEBUG: IBus: RX[10]: 68 08 3B A5 62 01 02 7C 7C 9F [SELF]
[141970] DEBUG: IBus: RX[8]: 68 06 3B A5 62 01 00 93 [SELF]
[141983] DEBUG: IBus: RX[16]: 18 0E 68 39 00 82 00 01 00 01 01 00 01 01 01 C5 [SELF]
[141998] DEBUG: IBus: RX[6]: 3B 04 68 45 00 12 [SELF]
[142009] DEBUG: IBus: RX[8]: A4 06 BF 70 00 00 0A 67 
[142034] DEBUG: IBus: RX[8]: A4 06 BF 70 02 00 0A 65 
[151350] DEBUG: IBus: RX[7]: 44 05 BF 74 04 00 8E 
[159603] DEBUG: IBus: RX[6]: 80 04 BF 11 01 2B 
[159614] DEBUG: Handler: Ignition On
[159623] DEBUG: BT: W: 'BT_STATE ON OFF'
[159633] DEBUG: BT: W: 'LIST'
[159641] DEBUG: BT: W: 'TONE V 0 N C6 L 4'
[159652] DEBUG: BT: W: 'STATUS'
[159661] DEBUG: Handler: Request LCM Redundant Data
[159671] DEBUG: BT: R: 'OK'
[159680] DEBUG: BT: R: 'LIST 74EF4BD03D44 A2DP AVRCP'
[159690] DEBUG: BT: Paired Device 74EF4BD03D44
[159700] DEBUG: BT: W: 'NAME 74EF4BD03D44'
[159711] DEBUG: BT: R: 'LIST 947BE7A7E465 A2DP AVRCP'
[159722] DEBUG: BT: Paired Device 947BE7A7E465
[159731] DEBUG: BT: W: 'NAME 947BE7A7E465'
[159742] DEBUG: BT: R: 'OK'
[159750] DEBUG: BT: R: 'OK'
[159759] DEBUG: BT: R: 'STATE CONNECTED[0] CONNECTABLE[ON] DISCOVERABLE[OFF] BLE[OFF]'
[159772] DEBUG: BT: Got Status CONNECTABLE[ON] DISCOVERABLE[OFF]
[159784] DEBUG: BT: R: 'OK'
[159792] DEBUG: IBus: RX[7]: ED 05 F0 4F 12 11 54 
[159803] DEBUG: BT: R: 'PENDING'
[159811] DEBUG: BT: R: 'PENDING'
[159820] DEBUG: IBus: RX[5]: ED 03 80 14 7A 
[159830] DEBUG: IBus: RX[7]: 00 05 BF 7A 51 61 F0 
[159841] INFO: Handler: Central Locks unlocked
[159851] DEBUG: IBus: RX[6]: 00 04 BF 76 00 CD 
[159862] DEBUG: BT: R: 'NAME 74EF4BD03D44 "Find X5 Pro"'
[159873] DEBUG: Process Name
[159881] DEBUG: Handler: No Device -- Attempt connection
[159892] DEBUG: Connecting to preferred device
[159902] DEBUG: BT: W: 'OPEN 74EF4BD03D44 A2DP'
[159914] DEBUG: BT: New Pairing Profile
[159923] DEBUG: IBus: RX[7]: 00 05 BF 7D 00 10 D7 
[159934] DEBUG: BT: R: 'PENDING'
[159943] DEBUG: BT: R: 'OPEN_ERROR A2DP '
[159952] DEBUG: IBus: RX[6]: 00 04 BF 72 02 CB 
[159963] DEBUG: IBus: RX[10]: D0 08 BF 5B 00 00 00 00 00 3C 
[159976] DEBUG: OFF > Unrelated activity!
[159985] DEBUG: IBus: RX[12]: 80 0A BF 13 00 00 00 00 00 00 0C 2A 
[159999] DEBUG: IBus: RX[9]: 80 07 BF 15 F1 00 00 00 DC 
[160011] DEBUG: IBus: RX[6]: 3B 04 BF 02 40 C2 
[160022] DEBUG: IBus: RX[6]: 80 04 BF 11 01 2B 
[160033] DEBUG: IBus: RX[6]: F5 04 BF 02 01 4D 
[160043] DEBUG: IBus: RX[6]: 80 04 BF 11 01 2B 
[160054] DEBUG: IBus: RX[6]: 3B 04 68 45 10 02 
[160065] DEBUG: IBus: RX[5]: 3B 03 46 01 7F 
[160076] DEBUG: IBus: RX[7]: 3B 05 80 41 02 01 FC 
[160087] DEBUG: IBus: RX[8]: E8 06 00 58 FF 7F 00 36 
[160098] DEBUG: IBus: RX[7]: E8 05 D0 59 11 02 77 
[160109] DEBUG: IBus: RX[8]: A4 06 BF 70 03 00 0A 64 
[160121] DEBUG: IBus: RX[17]: 80 0F FF 24 02 00 31 33 2E 30 36 2E 32 30 32 34 56 
[160136] DEBUG: IBus: RX[7]: 44 05 BF 74 05 00 8F 
[160147] DEBUG: IBus: RX[9]: D0 07 BF 5C FF 3F 00 00 F4 
[160159] DEBUG: IBus: RX[8]: 7F 06 C8 A9 03 30 30 1B 
[160170] DEBUG: IBus: RX[8]: 7F 06 C8 A9 0A 30 30 12 
[160185] DEBUG: IBus: RX[6]: 50 04 FF 3B 00 90 
[160197] DEBUG: IBus: RX[5]: E8 03 00 75 9E 
[160208] DEBUG: IBus: RX[6]: 00 04 E8 77 00 9B 
[160245] DEBUG: IBus: RX[6]: 18 04 FF 02 01 E0 [SELF]
[160256] DEBUG: IBus: RX[5]: 80 03 D0 53 00 [SELF]
[160267] DEBUG: IBus: RX[6]: 80 04 BF 11 01 2B 
[160278] DEBUG: IBus: RX[5]: 3F 03 D0 0B E7 [SELF]
[160290] DEBUG: IBus: RX[18]: D0 10 80 54 50 50 86 35 50 04 13 00 05 08 05 01 37 DE 
[160323] DEBUG: IBus: RX[37]: D0 23 3F A0 00 40 FE FE 00 00 00 00 08 B1 00 00 00 00 00 2B 00 00 02 00 00 00 00 00 00 00 00 00 00 00 00 00 BC 
[160343] DEBUG: IBus: RX[6]: 68 04 BF 02 01 D0 
[160354] DEBUG: IBus: RX[6]: 80 04 BF 11 01 2B 
[160365] DEBUG: IBus: RX[6]: 3B 04 68 45 10 02 
[160376] DEBUG: IBus: RX[7]: 3B 05 68 4E 00 00 18 
[160387] DEBUG: Handler: Attempting to resolve pairing error
[160398] DEBUG: BT: W: 'OPEN 74EF4BD03D44 A2DP'
[160410] DEBUG: IBus: RX[7]: 68 05 76 38 00 00 23 
[160421] DEBUG: IBus: RX[6]: C8 04 BF 02 31 40 
[160432] DEBUG: BT: R: 'PENDING'
[160440] DEBUG: BT: R: 'OPEN_ERROR A2DP '
[160450] DEBUG: IBus: RX[6]: 80 04 BF 11 01 2B 
[160461] DEBUG: IBus: RX[6]: 68 04 3B 46 08 19 
[160472] DEBUG: IBus: RX[6]: 68 04 3B 46 04 15 
[160483] DEBUG: IBus: RX[6]: 68 04 F0 4A FF 29 
[160493] DEBUG: IBus: RX[5]: C8 03 B0 01 7A 
[160504] DEBUG: IBus: RX[13]: F0 0B 68 39 00 02 00 01 00 01 03 00 AB 
[160518] DEBUG: IBus: RX[8]: E8 06 00 58 FF 7F 00 36 
[160529] DEBUG: IBus: RX[7]: E8 05 D0 59 11 02 77 
[160610] DEBUG: IBus: RX[8]: A4 06 BF 70 03 00 1A 74 
[160684] DEBUG: IBus: RX[5]: C8 03 B0 01 7A 
[160710] DEBUG: IBus: RX[8]: A4 06 BF 70 01 00 1A 76 
[160985] DEBUG: IBus: RX[5]: C8 03 B0 01 7A 
[161285] DEBUG: IBus: RX[5]: C8 03 B0 01 7A 
[161586] DEBUG: IBus: RX[5]: C8 03 B0 01 7A 
[161599] DEBUG: IBus: RX[5]: 44 03 80 16 D1 
[161618] DEBUG: IBus: RX[12]: 80 0A BF 17 B9 97 01 01 E9 72 CC 5B 
[161631] DEBUG: IBus: RX[5]: 50 03 B0 01 E2 
[161642] DEBUG: IBus: RX[5]: 3B 03 F0 01 C9 
[161653] DEBUG: IBus: RX[6]: F0 04 3B 02 70 BD 
[161714] DEBUG: IBus: RX[14]: 80 0C FF 24 01 00 31 37 3A 31 30 20 20 6B 
[161886] DEBUG: IBus: RX[5]: C8 03 B0 01 7A 
[161995] DEBUG: Handler: Send CDC status preemptively
[162023] DEBUG: IBus: RX[16]: 18 0E 68 39 00 82 00 01 00 01 01 00 01 01 01 C5 [SELF]
[162187] DEBUG: IBus: RX[5]: C8 03 7F 01 B5 
[162198] DEBUG: IBus: RX[6]: 7F 04 C8 02 40 F1 
[162250] DEBUG: IBus: RX[7]: B0 05 7F AA 00 40 20 
[162262] DEBUG: IBus: RX[6]: 7F 04 B0 AF 04 60 
[162424] DEBUG: IBus: RX[8]: 68 06 F0 38 00 00 00 A6 
[162438] DEBUG: IBus: RX[7]: 68 05 76 38 00 00 23 
[162456] DEBUG: IBus: RX[13]: F0 0B 68 39 00 02 00 01 00 01 03 00 AB 
[162470] DEBUG: IBus: RX[7]: 68 05 18 38 00 00 4D 
[162482] DEBUG: IBus: RX[8]: 68 06 F0 38 01 00 00 A7 
[162511] DEBUG: IBus: RX[16]: 18 0E 68 39 00 82 00 01 00 01 01 00 01 01 01 C5 [SELF]
[162559] DEBUG: IBus: RX[6]: ED 04 F0 4A 90 C3 
[162595] DEBUG: IBus: RX[9]: D0 07 BF 5C FF 3F FF 00 0B 
[162612] DEBUG: IBus: RX[5]: 3F 03 D0 0B E7 [SELF]
[162660] DEBUG: IBus: RX[37]: D0 23 3F A0 00 40 FE FE 00 00 00 00 08 B1 00 00 00 00 00 2B 00 00 02 00 00 00 00 00 00 00 00 00 00 00 00 00 BC 
[162681] DEBUG: IBus: RX[13]: F0 0B 68 39 00 02 00 01 00 01 03 00 AB 
[162697] DEBUG: IBus: RX[6]: 68 04 F0 4A FF 29 
[162709] DEBUG: IBus: RX[8]: 68 06 F0 38 01 00 00 A7 
[162807] DEBUG: IBus: RX[13]: F0 0B 68 39 00 02 00 01 00 01 03 00 AB 
[162825] DEBUG: IBus: RX[13]: F0 0B 68 39 00 02 00 01 00 01 03 00 AB 
[162909] DEBUG: Handler: Attempting to resolve pairing error
[162920] DEBUG: BT: W: 'OPEN 74EF4BD03D44 A2DP'
[162932] DEBUG: IBus: RX[5]: 68 03 6A 01 00 
[162942] DEBUG: IBus: RX[7]: 68 05 18 38 02 00 4F 
[162953] DEBUG: BT: R: 'PENDING'
[162962] DEBUG: BT: R: 'OPEN_ERROR A2DP '
[162971] DEBUG: IBus: RX[7]: 68 05 18 38 00 00 4D 
[162983] DEBUG: IBus: RX[13]: 68 0B 3B A5 62 01 41 20 20 20 20 20 FF 
[162997] DEBUG: IBus: RX[13]: 68 0B 3B A5 62 01 42 20 20 20 20 20 FC 
[163021] DEBUG: IBus: RX[13]: 68 0B 3B A5 62 01 43 20 20 20 20 20 FD 
[163047] DEBUG: IBus: RX[13]: 68 0B 3B A5 62 01 44 20 20 20 20 20 FA 
[163077] DEBUG: IBus: RX[15]: 68 0D 3B A5 62 01 45 20 20 20 20 20 20 20 FD 
[163121] DEBUG: IBus: RX[28]: 68 1A 3B A5 62 01 46 20 20 20 20 20 20 20 20 20 20 20 20 20 20 20 20 20 20 20 20 C9 
[163166] DEBUG: IBus: RX[28]: 68 1A 3B A5 62 01 07 20 20 20 20 20 20 20 20 20 20 20 20 20 20 20 20 20 20 20 20 88 
[163193] DEBUG: IBus: RX[20]: 68 12 3B 23 62 10 43 44 43 20 31 2D 30 31 20 20 20 20 20 49 
[163208] DEBUG: IBus: RX[6]: 68 04 3B 46 0C 1D 
[163219] DEBUG: IBus: RX[7]: 3B 05 68 22 00 06 72 
[163265] DEBUG: IBus: RX[16]: 18 0E 68 39 01 8C 00 01 00 01 01 00 01 01 01 CA [SELF]
[163280] DEBUG: IBus: RX[16]: 18 0E 68 39 01 8C 00 01 00 01 01 00 01 01 01 CA [SELF]
[163296] DEBUG: IBus: RX[7]: 68 05 18 38 03 00 4E 
[163307] WARNING: BT: Unable to PLAY - AVRCP link unopened
[163341] DEBUG: IBus: RX[16]: 18 0E 68 39 02 89 00 01 00 01 01 00 01 01 01 CC [SELF]
[163356] DEBUG: IBus: RX[6]: 3B 04 68 45 02 10 [SELF]
[163382] DEBUG: IBus: RX[6]: 68 04 3B 46 0C 1D 
[163408] DEBUG: IBus: RX[13]: 68 0B 3B A5 62 01 41 20 20 20 20 20 FF 
[163434] DEBUG: IBus: RX[13]: 68 0B 3B A5 62 01 42 20 20 20 20 20 FC 
[163460] DEBUG: IBus: RX[13]: 68 0B 3B A5 62 01 43 20 20 20 20 20 FD 
[163486] DEBUG: IBus: RX[13]: 68 0B 3B A5 62 01 44 20 20 20 20 20 FA 
[163514] DEBUG: IBus: RX[15]: 68 0D 3B A5 62 01 45 20 20 20 20 20 20 20 FD 
[163559] DEBUG: IBus: RX[28]: 68 1A 3B A5 62 01 46 20 20 20 20 20 20 20 20 20 20 20 20 20 20 20 20 20 20 20 20 C9 
[163604] DEBUG: IBus: RX[28]: 68 1A 3B A5 62 01 07 20 20 20 20 20 20 20 20 20 20 20 20 20 20 20 20 20 20 20 20 88 
[163631] DEBUG: IBus: RX[20]: 68 12 3B 23 62 10 43 44 43 20 31 2D 30 31 20 20 20 20 20 49 
[163646] DEBUG: IBus: RX[7]: 3B 05 68 22 00 06 72 
[163716] DEBUG: IBus: RX[17]: 68 0F 3B 21 62 01 40 42 6C 75 65 74 6F 6F 74 68 08 [SELF]
[163731] DEBUG: IBus: RX[8]: 68 06 3B A5 62 01 00 93 [SELF]
[163744] DEBUG: IBus: RX[17]: 68 0F 3B 21 62 01 40 42 6C 75 65 74 6F 6F 74 68 08 [SELF]
[163759] DEBUG: IBus: RX[8]: 68 06 3B A5 62 01 00 93 [SELF]
[163771] DEBUG: IBus: RX[8]: A4 06 BF 70 00 00 1A 77 
[163783] DEBUG: IBus: RX[7]: 3B 05 68 22 00 00 74 
[163794] DEBUG: IBus: RX[7]: 3B 05 68 22 00 00 74 
[163820] DEBUG: IBus: RX[8]: 68 06 3B A5 61 01 00 90 [SELF]
[163833] DEBUG: IBus: RX[8]: 68 06 3B A5 61 01 00 90 [SELF]
[163907] DEBUG: IBus: RX[7]: 3B 05 68 22 00 00 74 
[163918] DEBUG: IBus: RX[7]: 3B 05 68 22 00 00 74 
[164119] DEBUG: IBus: RX[17]: 68 0F 3B 21 62 01 40 42 6C 75 65 74 6F 6F 74 68 08 [SELF]
[164134] DEBUG: IBus: RX[8]: 68 06 3B A5 62 01 00 93 [SELF]
[164147] DEBUG: IBus: RX[17]: 68 0F 3B A5 62 01 06 4E 6F 20 44 65 76 69 63 65 A5 [SELF]
[164162] DEBUG: IBus: RX[10]: 68 08 3B A5 62 01 02 7C 7C 9F [SELF]
[164176] DEBUG: IBus: RX[12]: 68 0A 3B A5 62 01 01 20 20 20 20 9E [SELF]
[164190] DEBUG: IBus: RX[8]: 68 06 3B A5 62 01 00 93 [SELF]
[164203] DEBUG: IBus: RX[23]: 68 15 3B 21 60 00 00 2D 20 4E 6F 74 20 50 6C 61 79 69 6E 67 20 2D 36 [SELF]
[164220] DEBUG: IBus: RX[9]: 68 07 3B 21 60 00 01 20 34 [SELF]
[164232] DEBUG: IBus: RX[16]: 68 0E 3B 21 60 00 02 20 06 06 06 06 06 06 06 38 [SELF]
[164248] DEBUG: IBus: RX[23]: 68 15 3B 21 60 00 04 54 65 6D 70 B0 43 3A 20 41 3A 2B 32 30 2E 30 8A [SELF]
[164265] DEBUG: IBus: RX[8]: 68 06 3B A5 60 01 00 91 [SELF]
[164277] DEBUG: IBus: RX[7]: 3B 05 68 22 00 00 74 
[164288] DEBUG: IBus: RX[7]: 3B 05 68 22 00 00 74 
[164299] DEBUG: IBus: RX[7]: 3B 05 68 22 00 00 74 
[164310] DEBUG: IBus: RX[7]: 3B 05 68 22 00 00 74 
[164322] DEBUG: IBus: RX[7]: 3B 05 68 22 00 00 74 
[164333] DEBUG: IBus: RX[7]: 3B 05 68 22 0F FF 84 
[164344] DEBUG: IBus: RX[7]: 3B 05 68 22 00 FF 8B 
[164355] DEBUG: IBus: RX[7]: 3B 05 68 22 00 FF 8B 
[164366] DEBUG: IBus: RX[7]: 3B 05 68 22 00 FF 8B 
[164377] DEBUG: IBus: RX[7]: 3B 05 68 22 00 00 74 
[164404] DEBUG: IBus: RX[8]: 68 06 3B A5 60 01 00 91 [SELF]
[164417] DEBUG: IBus: RX[8]: 68 06 3B A5 60 01 00 91 [SELF]
[164440] DEBUG: IBus: RX[7]: 3B 05 68 22 00 00 74 
[164476] DEBUG: IBus: RX[7]: 3B 05 68 22 00 00 74 
[164995] DEBUG: BT: R: 'ERROR 0x0019'
[165432] DEBUG: Handler: Attempting to resolve pairing error
[165443] DEBUG: BT: W: 'OPEN 74EF4BD03D44 A2DP'
[165468] DEBUG: BT: R: 'PENDING'
[166601] DEBUG: BT: R: 'OPEN_OK 10 A2DP 74EF4BD03D44'
[166612] DEBUG: BT: Open A2DP for ID 10
[166623] DEBUG: BT: W: 'VOLUME 10 UP'
[166632] WARNING: BT: Unable to PLAY - AVRCP link unopened
[166671] DEBUG: BT: R: 'OK'
[166680] DEBUG: IBus: RX[19]: 68 11 3B A5 62 01 06 46 69 6E 64 20 58 35 20 50 72 6F 87 [SELF]
[166696] DEBUG: IBus: RX[8]: 68 06 3B A5 62 01 00 93 [SELF]
[166717] DEBUG: IBus: RX[8]: 68 06 3B A5 60 01 00 91 [SELF]
[166743] DEBUG: IBus: RX[7]: 3B 05 68 22 00 00 74 
[166754] DEBUG: IBus: RX[7]: 3B 05 68 22 00 00 74 
[166765] DEBUG: BT: R: 'ROLE_OK 74EF4BD03D44 S'
[166775] DEBUG: IBus: RX[7]: 3B 05 68 22 00 00 74 
[166790] DEBUG: IBus: RX[7]: A4 05 80 41 02 01 63 
[166825] DEBUG: IBus: RX[17]: 80 0F FF 24 02 00 31 33 2E 30 36 2E 32 30 32 34 56 
[168673] DEBUG: BT: R: 'OPEN_OK 11 AVRCP 74EF4BD03D44'
[168683] DEBUG: BT: Open AVRCP for ID 11
[168694] DEBUG: BT: W: 'MUSIC 11 PLAY'
[168721] DEBUG: BT: R: 'OK'
[168729] DEBUG: BT: R: 'AVRCP_PLAY 11'
[168738] DEBUG: BT: Playing
[168796] DEBUG: BT: R: 'AVRCP_PAUSE 11'
[168805] DEBUG: BT: Paused
[168814] DEBUG: IBus: RX[17]: 68 0F 3B 21 62 01 40 42 6C 75 65 74 6F 6F 74 68 08 [SELF]
[168829] DEBUG: IBus: RX[8]: 68 06 3B A5 62 01 00 93 [SELF]
[168842] DEBUG: IBus: RX[10]: 68 08 3B A5 62 01 02 3E 20 81 [SELF]
[168855] DEBUG: IBus: RX[8]: 68 06 3B A5 62 01 00 93 [SELF]
[168867] DEBUG: IBus: RX[7]: 3B 05 68 22 00 00 74 
[168878] DEBUG: IBus: RX[7]: 3B 05 68 22 00 00 74 
[168890] DEBUG: IBus: RX[7]: 3B 05 68 22 00 00 74 
[168973] DEBUG: IBus: RX[17]: 68 0F 3B 21 62 01 40 42 6C 75 65 74 6F 6F 74 68 08 [SELF]
[168988] DEBUG: IBus: RX[8]: 68 06 3B A5 62 01 00 93 [SELF]
[169000] DEBUG: IBus: RX[10]: 68 08 3B A5 62 01 02 7C 7C 9F [SELF]
[169014] DEBUG: IBus: RX[8]: 68 06 3B A5 62 01 00 93 [SELF]
[169026] DEBUG: IBus: RX[8]: 68 06 3B A5 60 01 00 91 [SELF]
[169038] DEBUG: IBus: RX[8]: 68 06 3B A5 60 01 00 91 [SELF]
[169050] DEBUG: IBus: RX[7]: 3B 05 68 22 00 00 74 
[169061] DEBUG: IBus: RX[7]: 3B 05 68 22 00 00 74 
[169072] DEBUG: IBus: RX[7]: 3B 05 68 22 00 00 74 
[169083] DEBUG: IBus: RX[7]: 3B 05 68 22 00 00 74 
[169095] DEBUG: IBus: RX[7]: 3B 05 68 22 00 00 74 
[169121] DEBUG: IBus: RX[8]: 68 06 3B A5 60 01 00 91 [SELF]
[169134] DEBUG: IBus: RX[8]: 68 06 3B A5 60 01 00 91 [SELF]
[169172] DEBUG: IBus: RX[7]: 3B 05 68 22 00 00 74 
[169183] DEBUG: IBus: RX[7]: 3B 05 68 22 00 00 74 
[169298] DEBUG: IBus: RX[5]: 50 03 B0 01 E2 
[169312] DEBUG: IBus: RX[6]: B0 04 50 02 30 D6 
[169636] DEBUG: IBus: RX[7]: E8 05 D0 59 11 02 77 
[169752] DEBUG: IBus: RX[5]: F0 03 68 01 9A 
[169764] DEBUG: IBus: RX[6]: 68 04 BF 02 00 D1 
[169950] DEBUG: IBus: RX[5]: 68 03 80 14 FF 
[169966] DEBUG: IBus: RX[9]: 80 07 BF 15 F1 00 00 00 DC 
[169990] DEBUG: IBus: RX[8]: 7F 06 C8 A9 03 30 30 1B 
[170080] DEBUG: IBus: RX[8]: 7F 06 C8 A9 0A 30 30 12 
[170091] DEBUG: IBus: RX[7]: 3B 05 80 41 02 01 FC 
[170126] DEBUG: IBus: RX[17]: 80 0F FF 24 02 00 31 33 2E 30 36 2E 32 30 32 34 56 
[170245] DEBUG: BT: W: 'STATUS AVRCP'
[170276] DEBUG: BT: R: 'LINK 11 CONNECTED AVRCP 74EF4BD03D44 PAUSED'
[170338] DEBUG: BT: R: 'OK'
[170347] DEBUG: IBus: RX[17]: 68 0F 3B 21 62 01 40 42 6C 75 65 74 6F 6F 74 68 08 [SELF]
[170362] DEBUG: IBus: RX[8]: 68 06 3B A5 62 01 00 93 [SELF]
[170374] DEBUG: IBus: RX[10]: 68 08 3B A5 62 01 02 7C 7C 9F [SELF]
[170388] DEBUG: IBus: RX[8]: 68 06 3B A5 62 01 00 93 [SELF]
[170403] DEBUG: IBus: RX[7]: 3B 05 68 22 00 00 74 
[170414] DEBUG: IBus: RX[7]: 3B 05 68 22 00 00 74 
[170425] DEBUG: IBus: RX[7]: 3B 05 68 22 00 00 74 
[170452] DEBUG: IBus: RX[8]: 68 06 3B A5 60 01 00 91 [SELF]
[170465] DEBUG: IBus: RX[8]: 68 06 3B A5 60 01 00 91 [SELF]
[170507] DEBUG: IBus: RX[7]: 3B 05 68 22 00 00 74 
[170518] DEBUG: IBus: RX[7]: 3B 05 68 22 00 00 74 
[171627] DEBUG: IBus: RX[5]: 3B 03 80 01 B9 
[171639] DEBUG: IBus: RX[6]: 80 04 3B 02 00 BD 
[175254] DEBUG: BT: W: 'STATUS AVRCP'
[175285] DEBUG: BT: R: 'LINK 11 CONNECTED AVRCP 74EF4BD03D44 PAUSED'
[175347] DEBUG: BT: R: 'OK'
[175356] DEBUG: IBus: RX[17]: 68 0F 3B 21 62 01 40 42 6C 75 65 74 6F 6F 74 68 08 [SELF]
[175371] DEBUG: IBus: RX[8]: 68 06 3B A5 62 01 00 93 [SELF]
[175383] DEBUG: IBus: RX[10]: 68 08 3B A5 62 01 02 7C 7C 9F [SELF]
[175397] DEBUG: IBus: RX[8]: 68 06 3B A5 62 01 00 93 [SELF]
[175415] DEBUG: IBus: RX[7]: 3B 05 68 22 00 00 74 
[175426] DEBUG: IBus: RX[7]: 3B 05 68 22 00 00 74 
[175437] DEBUG: IBus: RX[7]: 3B 05 68 22 00 00 74 
[175464] DEBUG: IBus: RX[8]: 68 06 3B A5 60 01 00 91 [SELF]
[175477] DEBUG: IBus: RX[8]: 68 06 3B A5 60 01 00 91 [SELF]
[175528] DEBUG: IBus: RX[7]: 3B 05 68 22 00 00 74 
[175540] DEBUG: IBus: RX[7]: 3B 05 68 22 00 00 74 
[177600] DEBUG: IBus: RX[9]: D0 07 BF 5C FF 3F FF 00 0B 
[179673] DEBUG: IBus: RX[7]: E8 05 D0 59 11 02 77 
[179851] DEBUG: IBus: RX[5]: F0 03 68 01 9A 
[179863] DEBUG: IBus: RX[6]: 68 04 BF 02 00 D1 
[180257] DEBUG: IBus: RX[22]: 7F 14 C8 A2 00 00 27 23 40 31 01 53 03 13 20 00 00 00 00 00 00 16 
[180278] DEBUG: BT: W: 'STATUS AVRCP'
[180302] DEBUG: IBus: RX[37]: 7F 23 C8 A4 00 01 42 52 49 53 42 41 4E 45 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 33 
[180323] DEBUG: BT: R: 'LINK 11 CONNECTED AVRCP 74EF4BD03D44 PAUSED'
[180335] DEBUG: BT: R: 'OK'
[180347] DEBUG: IBus: RX[37]: 7F 23 C8 A4 00 02 45 4E 4E 4F 52 20 53 54 52 45 45 54 3B 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 70 
[180367] DEBUG: IBus: RX[6]: 7F 04 68 A7 10 A4 
[180378] DEBUG: IBus: RX[6]: 7F 04 68 A7 30 84 
[180440] DEBUG: IBus: RX[17]: 68 0F 3B 21 62 01 40 42 6C 75 65 74 6F 6F 74 68 08 [SELF]
[180455] DEBUG: IBus: RX[8]: 68 06 3B A5 62 01 00 93 [SELF]
[180467] DEBUG: IBus: RX[10]: 68 08 3B A5 62 01 02 7C 7C 9F [SELF]
[180481] DEBUG: IBus: RX[8]: 68 06 3B A5 62 01 00 93 [SELF]
[180493] DEBUG: IBus: RX[5]: 68 03 6A 01 00 
[180508] DEBUG: IBus: RX[7]: 3B 05 68 22 00 00 74 
[180520] DEBUG: IBus: RX[7]: 3B 05 68 22 00 00 74 
[180531] DEBUG: IBus: RX[7]: 3B 05 68 22 00 00 74 
[180557] DEBUG: IBus: RX[8]: 68 06 3B A5 60 01 00 91 [SELF]
[180570] DEBUG: IBus: RX[8]: 68 06 3B A5 60 01 00 91 [SELF]
[180606] DEBUG: IBus: RX[7]: 3B 05 68 22 00 00 74 
[180618] DEBUG: IBus: RX[7]: 3B 05 68 22 00 00 74 
[181339] DEBUG: IBus: RX[6]: F0 04 68 48 14 C0 
[181351] DEBUG: BT: W: 'MUSIC 11 PLAY'
[181371] DEBUG: IBus: RX[5]: 51 03 80 10 C2 
[181382] DEBUG: BT: R: 'OK'
[181390] DEBUG: IBus: RX[6]: 80 04 BF 11 01 2B 
[181524] DEBUG: IBus: RX[6]: F0 04 68 48 94 40 
[181582] DEBUG: BT: R: 'A2DP_STREAM_START 10'
[181607] DEBUG: BT: R: 'AVRCP_PLAY 11'
[181616] DEBUG: BT: Playing
[181675] DEBUG: IBus: RX[17]: 68 0F 3B 21 62 01 40 42 6C 75 65 74 6F 6F 74 68 08 [SELF]
[181690] DEBUG: IBus: RX[8]: 68 06 3B A5 62 01 00 93 [SELF]
[181702] DEBUG: IBus: RX[10]: 68 08 3B A5 62 01 02 3E 20 81 [SELF]
[181716] DEBUG: BT: R: 'AVRCP_MEDIA 11 TITLE: Usual Places'
[181727] DEBUG: BT: R: 'AVRCP_MEDIA 11 ARTIST: Ash'
[181738] DEBUG: BT: R: 'AVRCP_MEDIA 11 ALBUM: Race the Night'
[181749] DEBUG: BT: title=Usual Places,artist=Ash,album=Race the Night
[181762] DEBUG: IBus: RX[8]: 68 06 3B A5 62 01 00 93 [SELF]
[181774] DEBUG: IBus: RX[5]: 3B 03 6A 01 53 
[181784] DEBUG: IBus: RX[7]: 3B 05 68 22 00 00 74 
[181796] DEBUG: IBus: RX[7]: 3B 05 68 22 00 00 74 
[181807] DEBUG: IBus: RX[7]: 3B 05 68 22 00 00 74 
[181979] DEBUG: IBus: RX[8]: 68 06 3B A5 60 01 00 91 [SELF]
[181992] DEBUG: IBus: RX[17]: 68 0F 3B 21 62 01 40 55 73 75 61 6C 20 50 6C 61 7D [SELF]
[182007] DEBUG: IBus: RX[8]: 68 06 3B A5 62 01 00 93 [SELF]
[182020] DEBUG: IBus: RX[20]: 68 12 3B 21 60 00 00 55 73 75 61 6C 20 50 6C 61 63 65 73 56 [SELF]
[182036] DEBUG: IBus: RX[11]: 68 09 3B 21 60 00 01 41 73 68 40 [SELF]
[182051] DEBUG: IBus: RX[29]: 68 1B 3B 21 60 00 02 52 61 63 65 20 74 68 65 20 4E 69 67 68 74 06 06 06 06 06 06 06 1D [SELF]
[182070] DEBUG: IBus: RX[23]: 68 15 3B 21 60 00 04 54 65 6D 70 B0 43 3A 20 41 3A 2B 32 30 2E 30 8A [SELF]
[182087] DEBUG: IBus: RX[8]: 68 06 3B A5 60 01 00 91 [SELF]
[182099] DEBUG: IBus: RX[8]: 68 06 3B A5 60 01 00 91 [SELF]
[182111] DEBUG: IBus: RX[7]: 3B 05 68 22 00 00 74 
tedsalmon commented 5 months ago

HI @HDC67,

I have never even heard of this phone! At a glance, I am seeing that the phone is immediately pausing after the AVRCP play command MUSIC 11 PLAY is sent to the handset:

[168694] DEBUG: BT: W: 'MUSIC 11 PLAY'
[168721] DEBUG: BT: R: 'OK'
[168729] DEBUG: BT: R: 'AVRCP_PLAY 11'
[168738] DEBUG: BT: Playing
[168796] DEBUG: BT: R: 'AVRCP_PAUSE 11'
[168805] DEBUG: BT: Paused

I can't do much about this, sadly. What Music app are you using, and have you tried different ones?

-Ted

HDC67 commented 5 months ago

It's Oppo's flagship phone so whilst it's no Samsung or iPhone it's not what I'd call particularly esoteric :)

Anyway the real problem seems to be before the lines you picked out.

[159603] DEBUG: IBus: RX[6]: 80 04 BF 11 01 2B 
[159614] DEBUG: Handler: Ignition On
[159623] DEBUG: BT: W: 'BT_STATE ON OFF'
[159633] DEBUG: BT: W: 'LIST'
[159641] DEBUG: BT: W: 'TONE V 0 N C6 L 4'
[159652] DEBUG: BT: W: 'STATUS'
[159661] DEBUG: Handler: Request LCM Redundant Data
[159671] DEBUG: BT: R: 'OK'
[159680] DEBUG: BT: R: 'LIST 74EF4BD03D44 A2DP AVRCP'
[159690] DEBUG: BT: Paired Device 74EF4BD03D44
[159700] DEBUG: BT: W: 'NAME 74EF4BD03D44'
[159711] DEBUG: BT: R: 'LIST 947BE7A7E465 A2DP AVRCP'
[159722] DEBUG: BT: Paired Device 947BE7A7E465
[159731] DEBUG: BT: W: 'NAME 947BE7A7E465'
[159742] DEBUG: BT: R: 'OK'
[159750] DEBUG: BT: R: 'OK'
[159759] DEBUG: BT: R: 'STATE CONNECTED[0] CONNECTABLE[ON] DISCOVERABLE[OFF] BLE[OFF]'
[159772] DEBUG: BT: Got Status CONNECTABLE[ON] DISCOVERABLE[OFF]
[159784] DEBUG: BT: R: 'OK'
[159803] DEBUG: BT: R: 'PENDING'
[159811] DEBUG: BT: R: 'PENDING'
[159862] DEBUG: BT: R: 'NAME 74EF4BD03D44 "Find X5 Pro"'
[159873] DEBUG: Process Name
[159881] DEBUG: Handler: No Device -- Attempt connection
[159892] DEBUG: Connecting to preferred device
[159902] DEBUG: BT: W: 'OPEN 74EF4BD03D44 A2DP'
[159914] DEBUG: BT: New Pairing Profile
[159923] DEBUG: IBus: RX[7]: 00 05 BF 7D 00 10 D7 
[159934] DEBUG: BT: R: 'PENDING'
[159943] DEBUG: BT: R: 'OPEN_ERROR A2DP '
...
[160387] DEBUG: Handler: Attempting to resolve pairing error
[160398] DEBUG: BT: W: 'OPEN 74EF4BD03D44 A2DP'
[160432] DEBUG: BT: R: 'PENDING'
[160440] DEBUG: BT: R: 'OPEN_ERROR A2DP '
...
[162909] DEBUG: Handler: Attempting to resolve pairing error
[162920] DEBUG: BT: W: 'OPEN 74EF4BD03D44 A2DP'
[162953] DEBUG: BT: R: 'PENDING'
[162962] DEBUG: BT: R: 'OPEN_ERROR A2DP '
[163307] WARNING: BT: Unable to PLAY - AVRCP link unopened
...
[164995] DEBUG: BT: R: 'ERROR 0x0019'
[165432] DEBUG: Handler: Attempting to resolve pairing error
[165443] DEBUG: BT: W: 'OPEN 74EF4BD03D44 A2DP'
[165468] DEBUG: BT: R: 'PENDING'
[166601] DEBUG: BT: R: 'OPEN_OK 10 A2DP 74EF4BD03D44'
[166612] DEBUG: BT: Open A2DP for ID 10
[166623] DEBUG: BT: W: 'VOLUME 10 UP'
[166632] WARNING: BT: Unable to PLAY - AVRCP link unopened

I'm using Plexamp usually. I can try something else but I doubt it will be different based on the logs.

Same phone works OK on an F31 with BMW's standard bluetooth implementation.

I'll try an old phone and see if it's something peculiar with the the module. The fact it will not completely forget the pairing seems problematic too. I'll delete the pairing and yet it still pops on my phone and tries to connect to the phone after deleting the pairing.