search

shermp / Pico-ASHA

ASHA on a Pico
BSD 3-Clause "New" or "Revised" License
28 stars 0 forks source link

Connected but no sound? #12

Open MadSpindel opened 2 hours ago

MadSpindel commented 2 hours ago

Tried the latest pico-asha.uf2 from GitHub Actions on a newly bought Pico W with my Oticon More 1 hearing aids.

The Pico W is connected to Windows 11 and Pico-ASHA adapter is selected as the sound output. The LED light is stable, so I guess it means that the hearing aids are connected to the Pico?

Unfortunately, no sound in hearing aids.

shermp commented 2 hours ago

Hi, check your volume & mute in Windows. Also, can you check that your hearing aids are not currently connected to another device like a phone.

A solid light does indeed mean that the devices have at least paired. We might need to see logs via serial to determine what is happening. Do you know anything about opening a serial connection on Windows?

We should be able to get it working though. I have this running successfully on my More 2's

MadSpindel commented 2 hours ago

We might need to see logs via serial to determine what is happening. Do you know anything about opening a serial connection on Windows?

I've used PuTTy before, but I've not used a serial connection with Raspberry Pi Pico before. Is it possible to do it over USB or do I need something else?

shermp commented 2 hours ago

PuTTy is fine. Pico-ASHA creates a USB serial device, you just need to find out what COM port it shows as through device manager. Mine is COM4. I don't think PuTTy is smart enough to list available COM ports unfortunately.

shermp commented 2 hours ago

The following PuTTy configuration should work: image

(Change the COM port to suit)

You may need to press enter after connecting if it doesn't show a log.

shermp commented 2 hours ago

And here is how to find the COM port number in Device Manager:

image

MadSpindel commented 2 hours ago

It's spamming this:

[ERROR : 55649] Left: L2CAP CoC failed with status code: 0x65
[INFO  : 55649] Left: Connecting to L2CAP
[ERROR : 55689] Left: L2CAP CoC failed with status code: 0x65
[INFO  : 55689] Left: Connecting to L2CAP
[ERROR : 55729] Left: L2CAP CoC failed with status code: 0x65
shermp commented 1 hour ago

That indicates that your More's might be connected to another ASHA device. Phone or Pipewire/Linux maybe?

MadSpindel commented 1 hour ago

That indicates that your More's might be connected to another ASHA device. Phone or Pipewire/Linux maybe?

Not any active connections. I turned off my iPhone completely, still spamming the same error. I've paired my More's with Pipewire/Linux but no active connection at the moment.

shermp commented 1 hour ago

Can you try unplugging and plugging back in if you haven't already done so?

MadSpindel commented 1 hour ago

I've done that several times, still the same error. Rebooted Windows now, and this error persists.

shermp commented 1 hour ago

Nah, not a Windows issue. For some reason your hearing aids are refusing to fully connect.

The error code in question is L2CAP_CONNECTION_RESPONSE_RESULT_REFUSED_PSM.

MadSpindel commented 1 hour ago

I disconnected the hearing aids (turned them off) and then turned them on again, not sure if this info could be helpful:

[INFO : 169020] Received disconnection event. [ERROR : 169020] Disconnected with reason: 8 [INFO : 169021] Left: Disconnected with 0 available credits. [INFO : 198260] Identity resolving succeeded [INFO : 198260] Connecting to address 7B:1A:63:EA:A3:85 [INFO : 198380] Device connected. Attempt pairing [INFO : 198381] Reencryption started [INFO : 198587] Reencryption complete [INFO : 198648] DL set to: RX Octets: 251, RX Time: 2112 us, TX Octets: 251, TX Time: 2120 us [INFO : 198648] Device paired. Discovering ASHA service [INFO : 198787] GATT service found [INFO : 198787] GAP service found [INFO : 198887] ASHA service found [INFO : 198987] Discovering characteristics for found services [INFO : 199047] Got Service Changed Characteristic [INFO : 199047] Characteristic discovery complete [INFO : 199047] Continue discovering more characteristics [INFO : 199087] Got Device Name Characteristic [INFO : 199087] Characteristic discovery complete [INFO : 199087] Continue discovering more characteristics [INFO : 199128] Got ROP Characteristic [INFO : 199128] Got ACP Characteristic [INFO : 199128] Got AUS Characteristic [INFO : 199128] Got VOL Characteristic [INFO : 199128] Got PSM Characteristic [INFO : 199128] Characteristic discovery complete [INFO : 199128] Characteristic discovery complete [INFO : 199227] Subscribed to GATT Service changed indication [INFO : 199307] Getting Device Name value [INFO : 199307] Name length: 7 [INFO : 199307] Completed value read of Device Name [INFO : 199367] Completed value read of ReadOnlyProperties [INFO : 199407] Completed value read of PSM [INFO : 199407] ROP - Side: R, Mode: M, M. ID: 0000, Delay: 18087, 16KHz: Y, 24KHz: Y [ERROR : 199507] Enabling ASP notifications failed with error code: 0x05 [INFO : 199508] Right: Subscribed to ASP Notification [INFO : 199508] Right: Connecting to L2CAP [ERROR : 199550] Right: L2CAP CoC failed with status code: 0x65 [INFO : 199550] Right: Connecting to L2CAP [ERROR : 199550] Right: Failure creating l2cap channel with error code: 0x56 [INFO : 199550] Right: Connecting to L2CAP [ERROR : 199550] Right: Failure creating l2cap channel with error code: 0x56 [INFO : 199550] Right: Connecting to L2CAP [ERROR : 199550] Right: Failure creating l2cap channel with error code: 0x56 [INFO : 199550] Right: Connecting to L2CAP [ERROR : 199551] Right: Failure creating l2cap channel with error code: 0x56 [INFO : 199551] Right: Connecting to L2CAP [ERROR : 199551] Right: Failure creating l2cap channel with error code: 0x56 [ERROR : 199588] Right: L2CAP CoC failed with status code: 0x6a [INFO : 199589] Right: Connecting to L2CAP [INFO : 199590] Received disconnection event. [ERROR : 199590] Disconnected with reason: 22 [INFO : 199590] Right: Disconnected with 0 available credits. [INFO : 199701] Identity resolving succeeded [INFO : 199701] Connecting to address 7B:1A:63:EA:A3:85 [INFO : 199750] Device connected. Attempt pairing [INFO : 199751] Reencryption started [INFO : 199944] Reencryption complete [INFO : 200064] DL set to: RX Octets: 251, RX Time: 2112 us, TX Octets: 167, TX Time: 1064 us [INFO : 200065] Device paired. Discovering ASHA service [INFO : 200204] GATT service found [INFO : 200205] GAP service found [INFO : 200284] ASHA service found [INFO : 200364] Discovering characteristics for found services [INFO : 200404] Got Service Changed Characteristic [INFO : 200405] Characteristic discovery complete [INFO : 200405] Continue discovering more characteristics [INFO : 200464] Got Device Name Characteristic [INFO : 200465] Characteristic discovery complete [INFO : 200465] Continue discovering more characteristics [INFO : 200505] Got ROP Characteristic [INFO : 200506] Got ACP Characteristic [INFO : 200506] Got AUS Characteristic [INFO : 200506] Got VOL Characteristic [INFO : 200506] Got PSM Characteristic [INFO : 200506] Characteristic discovery complete [INFO : 200506] Characteristic discovery complete [INFO : 200624] Subscribed to GATT Service changed indication [INFO : 200664] Getting Device Name value [INFO : 200664] Name length: 7 [INFO : 200664] Completed value read of Device Name [INFO : 200704] Completed value read of ReadOnlyProperties [INFO : 200764] Completed value read of PSM [INFO : 200764] ROP - Side: R, Mode: M, M. ID: 0000, Delay: 18087, 16KHz: Y, 24KHz: Y [ERROR : 200865] Enabling ASP notifications failed with error code: 0x05 [INFO : 200866] Right: Subscribed to ASP Notification [INFO : 200867] Right: Connecting to L2CAP [ERROR : 200907] Right: L2CAP CoC failed with status code: 0x65 [INFO : 200908] Right: Connecting to L2CAP [ERROR : 200908] Right: Failure creating l2cap channel with error code: 0x56 [INFO : 200908] Right: Connecting to L2CAP [ERROR : 200908] Right: Failure creating l2cap channel with error code: 0x56 [INFO : 200908] Right: Connecting to L2CAP [ERROR : 200908] Right: Failure creating l2cap channel with error code: 0x56 [INFO : 200908] Right: Connecting to L2CAP [ERROR : 200908] Right: Failure creating l2cap channel with error code: 0x56 [INFO : 200908] Right: Connecting to L2CAP [ERROR : 200908] Right: Failure creating l2cap channel with error code: 0x56 [ERROR : 200966] Right: L2CAP CoC failed with status code: 0x6a [INFO : 200966] Right: Connecting to L2CAP [INFO : 200967] Received disconnection event. [ERROR : 200967] Disconnected with reason: 22 [INFO : 200968] Right: Disconnected with 0 available credits. [INFO : 201000] Identity resolving succeeded [INFO : 201000] Connecting to address 7B:1A:63:EA:A3:85 [INFO : 201026] Device connected. Attempt pairing [INFO : 201027] Reencryption started [INFO : 201237] Reencryption complete [INFO : 201337] DL set to: RX Octets: 251, RX Time: 2112 us, TX Octets: 167, TX Time: 1064 us [INFO : 201337] Device paired. Discovering ASHA service [INFO : 201437] GATT service found [INFO : 201437] GAP service found [INFO : 201537] ASHA service found [INFO : 201617] Discovering characteristics for found services [INFO : 201677] Got Service Changed Characteristic [INFO : 201677] Characteristic discovery complete [INFO : 201677] Continue discovering more characteristics [INFO : 201717] Got Device Name Characteristic [INFO : 201717] Characteristic discovery complete [INFO : 201717] Continue discovering more characteristics [INFO : 201778] Got ROP Characteristic [INFO : 201778] Got ACP Characteristic [INFO : 201778] Got AUS Characteristic [INFO : 201778] Got VOL Characteristic [INFO : 201778] Got PSM Characteristic [INFO : 201778] Characteristic discovery complete [INFO : 201779] Characteristic discovery complete [INFO : 201876] Subscribed to GATT Service changed indication [INFO : 201957] Getting Device Name value [INFO : 201957] Name length: 7 [INFO : 201957] Completed value read of Device Name [INFO : 201997] Completed value read of ReadOnlyProperties [INFO : 202077] Completed value read of PSM [INFO : 202077] ROP - Side: R, Mode: M, M. ID: 2000, Delay: 51165, 16KHz: Y, 24KHz: Y [ERROR : 202198] Enabling ASP notifications failed with error code: 0x05 [INFO : 202199] Right: Subscribed to ASP Notification [INFO : 202200] Right: Connecting to L2CAP [ERROR : 202238] Right: L2CAP CoC failed with status code: 0x65 [INFO : 202239] Right: Connecting to L2CAP [ERROR : 202239] Right: Failure creating l2cap channel with error code: 0x56 [INFO : 202239] Right: Connecting to L2CAP [ERROR : 202239] Right: Failure creating l2cap channel with error code: 0x56 [INFO : 202239] Right: Connecting to L2CAP [ERROR : 202239] Right: Failure creating l2cap channel with error code: 0x56 [INFO : 202239] Right: Connecting to L2CAP [ERROR : 202239] Right: Failure creating l2cap channel with error code: 0x56 [INFO : 202239] Right: Connecting to L2CAP [ERROR : 202239] Right: Failure creating l2cap channel with error code: 0x56 [ERROR : 202259] Right: L2CAP CoC failed with status code: 0x6a [INFO : 202259] Right: Connecting to L2CAP [INFO : 202260] Received disconnection event. [ERROR : 202260] Disconnected with reason: 22 [INFO : 202260] Right: Disconnected with 0 available credits. [INFO : 202291] Identity resolving succeeded [INFO : 202291] Connecting to address 7B:1A:63:EA:A3:85 [INFO : 202319] Device connected. Attempt pairing [INFO : 202320] Reencryption started [INFO : 202534] Reencryption complete [INFO : 202574] DL set to: RX Octets: 251, RX Time: 2112 us, TX Octets: 251, TX Time: 2120 us [INFO : 202574] Device paired. Discovering ASHA service [INFO : 202734] GATT service found [INFO : 202735] GAP service found [INFO : 202834] ASHA service found [INFO : 202954] Discovering characteristics for found services [INFO : 202994] Got Service Changed Characteristic [INFO : 202995] Characteristic discovery complete [INFO : 202995] Continue discovering more characteristics [INFO : 203054] Got Device Name Characteristic [INFO : 203055] Characteristic discovery complete [INFO : 203055] Continue discovering more characteristics [INFO : 203095] Got ROP Characteristic [INFO : 203095] Got ACP Characteristic [INFO : 203096] Got AUS Characteristic [INFO : 203096] Got VOL Characteristic [INFO : 203096] Got PSM Characteristic [INFO : 203096] Characteristic discovery complete [INFO : 203096] Characteristic discovery complete [INFO : 203214] Subscribed to GATT Service changed indication [INFO : 203254] Getting Device Name value [INFO : 203254] Name length: 7 [INFO : 203254] Completed value read of Device Name [INFO : 203314] Completed value read of ReadOnlyProperties [INFO : 203354] Completed value read of PSM [INFO : 203354] ROP - Side: R, Mode: M, M. ID: 2000, Delay: 18087, 16KHz: Y, 24KHz: Y [ERROR : 203475] Enabling ASP notifications failed with error code: 0x05 [INFO : 203476] Right: Subscribed to ASP Notification [INFO : 203477] Right: Connecting to L2CAP [ERROR : 203516] Right: L2CAP CoC failed with status code: 0x65 [INFO : 203516] Right: Connecting to L2CAP [ERROR : 203516] Right: Failure creating l2cap channel with error code: 0x56 [INFO : 203516] Right: Connecting to L2CAP [ERROR : 203516] Right: Failure creating l2cap channel with error code: 0x56 [INFO : 203516] Right: Connecting to L2CAP [ERROR : 203516] Right: Failure creating l2cap channel with error code: 0x56 [INFO : 203516] Right: Connecting to L2CAP [ERROR : 203516] Right: Failure creating l2cap channel with error code: 0x56 [INFO : 203516] Right: Connecting to L2CAP [ERROR : 203517] Right: Failure creating l2cap channel with error code: 0x56 [ERROR : 203538] Right: L2CAP CoC failed with status code: 0x6a [INFO : 203538] Right: Connecting to L2CAP [INFO : 203539] Received disconnection event. [ERROR : 203539] Disconnected with reason: 22 [INFO : 203539] Right: Disconnected with 0 available credits. [INFO : 203620] Identity resolving succeeded [INFO : 203620] Connecting to address 7B:1A:63:EA:A3:85 [INFO : 203704] Device connected. Attempt pairing [INFO : 203705] Reencryption started [INFO : 203925] Reencryption complete [INFO : 204006] DL set to: RX Octets: 251, RX Time: 2112 us, TX Octets: 251, TX Time: 2120 us [INFO : 204006] Device paired. Discovering ASHA service [INFO : 204126] GATT service found [INFO : 204126] GAP service found [INFO : 204245] ASHA service found [INFO : 204385] Discovering characteristics for found services [INFO : 204426] Got Service Changed Characteristic [INFO : 204426] Characteristic discovery complete [INFO : 204426] Continue discovering more characteristics [INFO : 204466] Got Device Name Characteristic [INFO : 204466] Characteristic discovery complete [INFO : 204466] Continue discovering more characteristics [INFO : 204527] Got ROP Characteristic [INFO : 204527] Got ACP Characteristic [INFO : 204527] Got AUS Characteristic [INFO : 204527] Got VOL Characteristic [INFO : 204527] Got PSM Characteristic [INFO : 204527] Characteristic discovery complete [INFO : 204527] Characteristic discovery complete [INFO : 204605] Subscribed to GATT Service changed indication [INFO : 204645] Getting Device Name value [INFO : 204645] Name length: 7 [INFO : 204645] Completed value read of Device Name [INFO : 204685] Completed value read of ReadOnlyProperties [INFO : 204765] Completed value read of PSM [INFO : 204765] ROP - Side: R, Mode: M, M. ID: 2000, Delay: 5304, 16KHz: N, 24KHz: Y [ERROR : 204867] Enabling ASP notifications failed with error code: 0x05 [INFO : 204869] Right: Subscribed to ASP Notification [INFO : 204869] Right: Connecting to L2CAP [ERROR : 204946] Right: L2CAP CoC failed with status code: 0x65 [INFO : 204946] Right: Connecting to L2CAP [ERROR : 204946] Right: Failure creating l2cap channel with error code: 0x56 [INFO : 204946] Right: Connecting to L2CAP [ERROR : 204946] Right: Failure creating l2cap channel with error code: 0x56 [INFO : 204946] Right: Connecting to L2CAP [ERROR : 204946] Right: Failure creating l2cap channel with error code: 0x56 [INFO : 204946] Right: Connecting to L2CAP [ERROR : 204946] Right: Failure creating l2cap channel with error code: 0x56 [INFO : 204946] Right: Connecting to L2CAP [ERROR : 204946] Right: Failure creating l2cap channel with error code: 0x56 [ERROR : 204967] Right: L2CAP CoC failed with status code: 0x6a [INFO : 204967] Right: Connecting to L2CAP [INFO : 204969] Received disconnection event. [ERROR : 204969] Disconnected with reason: 22 [INFO : 204969] Right: Disconnected with 0 available credits. [INFO : 205039] Identity resolving succeeded [INFO : 205039] Connecting to address 7B:1A:63:EA:A3:85 [INFO : 205085] Device connected. Attempt pairing [INFO : 205086] Reencryption started [INFO : 205254] Reencryption complete [INFO : 205354] DL set to: RX Octets: 251, RX Time: 2112 us, TX Octets: 167, TX Time: 1064 us [INFO : 205355] Device paired. Discovering ASHA service [INFO : 205475] GATT service found [INFO : 205475] GAP service found [INFO : 205574] ASHA service found [INFO : 205654] Discovering characteristics for found services [INFO : 205734] Got Service Changed Characteristic [INFO : 205735] Characteristic discovery complete [INFO : 205735] Continue discovering more characteristics [INFO : 205774] Got Device Name Characteristic [INFO : 205775] Characteristic discovery complete [INFO : 205775] Continue discovering more characteristics [INFO : 205835] Got ROP Characteristic [INFO : 205835] Got ACP Characteristic [INFO : 205836] Got AUS Characteristic [INFO : 205836] Got VOL Characteristic [INFO : 205836] Got PSM Characteristic [INFO : 205836] Characteristic discovery complete [INFO : 205836] Characteristic discovery complete [INFO : 205954] Subscribed to GATT Service changed indication [INFO : 206014] Getting Device Name value [INFO : 206014] Name length: 7 [INFO : 206014] Completed value read of Device Name [INFO : 206054] Completed value read of ReadOnlyProperties [INFO : 206114] Completed value read of PSM [INFO : 206114] ROP - Side: R, Mode: M, M. ID: 2000, Delay: 18087, 16KHz: Y, 24KHz: Y [ERROR : 206194] Enabling ASP notifications failed with error code: 0x05 [INFO : 206196] Right: Subscribed to ASP Notification [INFO : 206196] Right: Connecting to L2CAP [ERROR : 206236] Right: L2CAP CoC failed with status code: 0x65 [INFO : 206236] Right: Connecting to L2CAP [ERROR : 206236] Right: Failure creating l2cap channel with error code: 0x56 [INFO : 206236] Right: Connecting to L2CAP [ERROR : 206236] Right: Failure creating l2cap channel with error code: 0x56 [INFO : 206236] Right: Connecting to L2CAP [ERROR : 206236] Right: Failure creating l2cap channel with error code: 0x56 [INFO : 206236] Right: Connecting to L2CAP [ERROR : 206236] Right: Failure creating l2cap channel with error code: 0x56 [INFO : 206236] Right: Connecting to L2CAP [ERROR : 206237] Right: Failure creating l2cap channel with error code: 0x56 [ERROR : 206255] Right: L2CAP CoC failed with status code: 0x6a [INFO : 206255] Right: Connecting to L2CAP [INFO : 206256] Received disconnection event. [ERROR : 206257] Disconnected with reason: 22 [INFO : 206257] Right: Disconnected with 0 available credits. [INFO : 206300] Identity resolving succeeded [INFO : 206301] Connecting to address 7B:1A:63:EA:A3:85 [INFO : 206378] Device connected. Attempt pairing [INFO : 206379] Reencryption started [INFO : 206606] Reencryption complete [INFO : 206686] DL set to: RX Octets: 251, RX Time: 2112 us, TX Octets: 251, TX Time: 2120 us [INFO : 206686] Device paired. Discovering ASHA service [INFO : 206806] GATT service found [INFO : 206806] GAP service found [INFO : 206905] ASHA service found [INFO : 207005] Discovering characteristics for found services [INFO : 207066] Got Service Changed Characteristic [INFO : 207066] Characteristic discovery complete [INFO : 207066] Continue discovering more characteristics [INFO : 207126] Got Device Name Characteristic [INFO : 207126] Characteristic discovery complete [INFO : 207126] Continue discovering more characteristics [INFO : 207167] Got ROP Characteristic [INFO : 207167] Got ACP Characteristic [INFO : 207167] Got AUS Characteristic [INFO : 207167] Got VOL Characteristic [INFO : 207167] Got PSM Characteristic [INFO : 207167] Characteristic discovery complete [INFO : 207167] Characteristic discovery complete [INFO : 207305] Subscribed to GATT Service changed indication [INFO : 207345] Getting Device Name value [INFO : 207345] Name length: 7 [INFO : 207345] Completed value read of Device Name [INFO : 207385] Completed value read of ReadOnlyProperties [INFO : 207445] Completed value read of PSM [INFO : 207445] ROP - Side: R, Mode: M, M. ID: 2000, Delay: 18087, 16KHz: Y, 24KHz: Y [ERROR : 207525] Enabling ASP notifications failed with error code: 0x05 [INFO : 207527] Right: Subscribed to ASP Notification [INFO : 207527] Right: Connecting to L2CAP [ERROR : 207567] Right: L2CAP CoC failed with status code: 0x65 [INFO : 207567] Right: Connecting to L2CAP [ERROR : 207567] Right: Failure creating l2cap channel with error code: 0x56 [INFO : 207567] Right: Connecting to L2CAP [ERROR : 207567] Right: Failure creating l2cap channel with error code: 0x56 [INFO : 207568] Right: Connecting to L2CAP [ERROR : 207568] Right: Failure creating l2cap channel with error code: 0x56 [INFO : 207568] Right: Connecting to L2CAP [ERROR : 207568] Right: Failure creating l2cap channel with error code: 0x56 [INFO : 207568] Right: Connecting to L2CAP [ERROR : 207568] Right: Failure creating l2cap channel with error code: 0x56 [ERROR : 207586] Right: L2CAP CoC failed with status code: 0x6a [INFO : 207587] Right: Connecting to L2CAP [INFO : 207588] Received disconnection event. [ERROR : 207588] Disconnected with reason: 22 [INFO : 207588] Right: Disconnected with 0 available credits. [INFO : 207620] Identity resolving succeeded [INFO : 207620] Connecting to address 7B:1A:63:EA:A3:85 [INFO : 207681] Device connected. Attempt pairing [INFO : 207682] Reencryption started [INFO : 207888] Reencryption complete [INFO : 207948] DL set to: RX Octets: 251, RX Time: 2112 us, TX Octets: 251, TX Time: 2120 us [INFO : 207948] Device paired. Discovering ASHA service [INFO : 208048] GATT service found [INFO : 208048] GAP service found [INFO : 208148] ASHA service found [INFO : 208228] Discovering characteristics for found services [INFO : 208268] Got Service Changed Characteristic [INFO : 208268] Characteristic discovery complete [INFO : 208268] Continue discovering more characteristics [INFO : 208328] Got Device Name Characteristic [INFO : 208328] Characteristic discovery complete [INFO : 208328] Continue discovering more characteristics [INFO : 208369] Got ROP Characteristic [INFO : 208369] Got ACP Characteristic [INFO : 208369] Got AUS Characteristic [INFO : 208369] Got VOL Characteristic [INFO : 208369] Got PSM Characteristic [INFO : 208370] Characteristic discovery complete [INFO : 208370] Characteristic discovery complete [INFO : 208468] Subscribed to GATT Service changed indication [INFO : 208528] Getting Device Name value [INFO : 208528] Name length: 7 [INFO : 208528] Completed value read of Device Name [INFO : 208568] Completed value read of ReadOnlyProperties [INFO : 208608] Completed value read of PSM [INFO : 208608] ROP - Side: R, Mode: M, M. ID: 2000, Delay: 18087, 16KHz: Y, 24KHz: Y [ERROR : 208708] Enabling ASP notifications failed with error code: 0x05 [INFO : 208709] Right: Subscribed to ASP Notification [INFO : 208709] Right: Connecting to L2CAP [ERROR : 208769] Right: L2CAP CoC failed with status code: 0x65 [INFO : 208770] Right: Connecting to L2CAP [ERROR : 208770] Right: Failure creating l2cap channel with error code: 0x56 [INFO : 208770] Right: Connecting to L2CAP [ERROR : 208770] Right: Failure creating l2cap channel with error code: 0x56 [INFO : 208770] Right: Connecting to L2CAP [ERROR : 208770] Right: Failure creating l2cap channel with error code: 0x56 [INFO : 208770] Right: Connecting to L2CAP [ERROR : 208770] Right: Failure creating l2cap channel with error code: 0x56 [INFO : 208770] Right: Connecting to L2CAP [ERROR : 208770] Right: Failure creating l2cap channel with error code: 0x56 [ERROR : 208789] Right: L2CAP CoC failed with status code: 0x6a [INFO : 208789] Right: Connecting to L2CAP [INFO : 208790] Received disconnection event. [ERROR : 208790] Disconnected with reason: 22 [INFO : 208790] Right: Disconnected with 0 available credits. [INFO : 208928] Identity resolving succeeded [INFO : 208928] Connecting to address 7B:1A:63:EA:A3:85 [INFO : 209005] Device connected. Attempt pairing [INFO : 209006] Reencryption started [INFO : 209143] Received disconnection event. [ERROR : 209143] Disconnected with reason: 62 [INFO : 209182] Identity resolving succeeded [INFO : 209183] Connecting to address 7B:1A:63:EA:A3:85 [INFO : 209230] Device connected. Attempt pairing [INFO : 209231] Reencryption started [INFO : 209420] Reencryption complete [INFO : 209500] DL set to: RX Octets: 251, RX Time: 2112 us, TX Octets: 167, TX Time: 1064 us [INFO : 209501] Device paired. Discovering ASHA service [INFO : 209621] GATT service found [INFO : 209621] GAP service found [INFO : 209700] ASHA service found [INFO : 209800] Discovering characteristics for found services [INFO : 209861] Got Service Changed Characteristic [INFO : 209861] Characteristic discovery complete [INFO : 209861] Continue discovering more characteristics [INFO : 209901] Got Device Name Characteristic [INFO : 209901] Characteristic discovery complete [INFO : 209901] Continue discovering more characteristics [INFO : 209941] Got ROP Characteristic [INFO : 209942] Got ACP Characteristic [INFO : 209942] Got AUS Characteristic [INFO : 209942] Got VOL Characteristic [INFO : 209942] Got PSM Characteristic [INFO : 209942] Characteristic discovery complete [INFO : 209942] Characteristic discovery complete [INFO : 210040] Subscribed to GATT Service changed indication [INFO : 210080] Getting Device Name value [INFO : 210080] Name length: 7 [INFO : 210080] Completed value read of Device Name [INFO : 210140] Completed value read of ReadOnlyProperties [INFO : 210200] Completed value read of PSM [INFO : 210200] ROP - Side: R, Mode: M, M. ID: 2000, Delay: 18087, 16KHz: Y, 24KHz: Y [ERROR : 210301] Enabling ASP notifications failed with error code: 0x05 [INFO : 210302] Right: Subscribed to ASP Notification [INFO : 210302] Right: Connecting to L2CAP [ERROR : 210342] Right: L2CAP CoC failed with status code: 0x65 [INFO : 210342] Right: Connecting to L2CAP [ERROR : 210401] Right: L2CAP CoC failed with status code: 0x65 [INFO : 210402] Right: Connecting to L2CAP [ERROR : 210440] Right: L2CAP CoC failed with status code: 0x65 [INFO : 210441] Right: Connecting to L2CAP [ERROR : 210481] Right: L2CAP CoC failed with status code: 0x65

shermp commented 1 hour ago

Something doesn't look right with that.

Can you try the attached build please, it shouldn't keep trying multiple connections, which can get me a log from startup:

pico-asha.zip

shermp commented 1 hour ago

I think I need to better handle reconnects. Something goes all screwy after repeated connection attempts.

MadSpindel commented 1 hour ago

All right, loaded the build that you provided. Turned my hearing aids on & off again:

[INFO : 257] BT ASHA starting. [INFO : 257] L2CAP Init. [INFO : 257] SM Init. [INFO : 257] GATT Client Init. [INFO : 257] HCI power on. [INFO : 1165] BTstack up and running on 28:CD:C1:11:EB:F6 [INFO : 1165] Start scanning. [INFO : 1854] Identity resolving succeeded [INFO : 1855] Connecting to address 7B:1A:63:EA:A3:85 [INFO : 2007] Device connected. Attempt pairing [INFO : 2008] Reencryption started [INFO : 2187] Reencryption complete [INFO : 2227] DL set to: RX Octets: 251, RX Time: 2112 us, TX Octets: 251, TX Time: 2120 us [INFO : 2227] Device paired. Discovering ASHA service [INFO : 2367] GATT service found [INFO : 2367] GAP service found [INFO : 2467] ASHA service found [INFO : 2567] Discovering characteristics for found services [INFO : 2607] Got Service Changed Characteristic [INFO : 2607] Characteristic discovery complete [INFO : 2608] Continue discovering more characteristics [INFO : 2667] Got Device Name Characteristic [INFO : 2667] Characteristic discovery complete [INFO : 2668] Continue discovering more characteristics [INFO : 2708] Got ROP Characteristic [INFO : 2708] Got ACP Characteristic [INFO : 2708] Got AUS Characteristic [INFO : 2709] Got VOL Characteristic [INFO : 2709] Got PSM Characteristic [INFO : 2709] Characteristic discovery complete [INFO : 2709] Characteristic discovery complete [INFO : 2787] Subscribed to GATT Service changed indication [INFO : 2847] Getting Device Name value [INFO : 2847] Name length: 7 [INFO : 2847] Completed value read of Device Name [INFO : 2927] Completed value read of ReadOnlyProperties [INFO : 2967] Completed value read of PSM [INFO : 2967] ROP - Side: L, Mode: M, M. ID: 0000, Delay: 0, 16KHz: N, 24KHz: N [INFO : 2968] Connected to all aid(s) in set. [ERROR : 3067] Enabling ASP notifications failed with error code: 0x05 [INFO : 3068] Left: Subscribed to ASP Notification [INFO : 3068] Left: Connecting to L2CAP [ERROR : 3108] Left: L2CAP CoC failed with status code: 0x65 [INFO : 47274] Received disconnection event. [ERROR : 47275] Disconnected with reason: 8 [INFO : 47275] Left: Disconnected with 0 available credits. [INFO : 75146] Identity resolving succeeded [INFO : 75146] Connecting to address 44:41:28:A0:E4:9E [INFO : 75189] Device connected. Attempt pairing [INFO : 75190] Reencryption started [INFO : 75421] Reencryption complete [INFO : 75482] DL set to: RX Octets: 251, RX Time: 2112 us, TX Octets: 251, TX Time: 2120 us [INFO : 75482] Device paired. Discovering ASHA service [INFO : 75602] GATT service found [INFO : 75602] GAP service found [INFO : 75701] ASHA service found [INFO : 75781] Discovering characteristics for found services [INFO : 75842] Got Service Changed Characteristic [INFO : 75842] Characteristic discovery complete [INFO : 75842] Continue discovering more characteristics [INFO : 75882] Got Device Name Characteristic [INFO : 75882] Characteristic discovery complete [INFO : 75882] Continue discovering more characteristics [INFO : 75923] Got ROP Characteristic [INFO : 75923] Got ACP Characteristic [INFO : 75923] Got AUS Characteristic [INFO : 75923] Got VOL Characteristic [INFO : 75923] Got PSM Characteristic [INFO : 75923] Characteristic discovery complete [INFO : 75923] Characteristic discovery complete [INFO : 76021] Subscribed to GATT Service changed indication [INFO : 76081] Getting Device Name value [INFO : 76081] Name length: 7 [INFO : 76081] Completed value read of Device Name [INFO : 76121] Completed value read of ReadOnlyProperties [INFO : 76161] Completed value read of PSM [INFO : 76161] ROP - Side: R, Mode: M, M. ID: 0000, Delay: 18083, 16KHz: Y, 24KHz: Y [ERROR : 76281] Enabling ASP notifications failed with error code: 0x05 [INFO : 76283] Right: Subscribed to ASP Notification [INFO : 76283] Right: Connecting to L2CAP [ERROR : 76325] Right: L2CAP CoC failed with status code: 0x65

shermp commented 1 hour ago

Ok, that explains the error, but not why. Your HA's are supplying some very weird values.

For comparison, here's my log:

[INFO  : 257] BT ASHA starting.
[INFO  : 257] L2CAP Init.
[INFO  : 257] SM Init.
[INFO  : 257] GATT Client Init.
[INFO  : 257] HCI power on.
[INFO  : 1169] BTstack up and running on D8:3A:DD:5E:3D:2E
[INFO  : 1169] Start scanning.
[INFO  : 1471] Identity resolving succeeded
[INFO  : 1471] Connecting to address 7C:BB:EA:1B:7E:AF
[INFO  : 1523] Device connected. Attempt pairing
[INFO  : 1525] Reencryption started
[INFO  : 1688] Reencryption complete
[INFO  : 1768] DL set to: RX Octets: 251, RX Time: 2112 us, TX Octets: 167, TX Time: 1064 us
[INFO  : 1769] Device paired. Discovering ASHA service
[INFO  : 1868] GATT service found
[INFO  : 1869] GAP service found
[INFO  : 1968] ASHA service found
[INFO  : 2068] Discovering characteristics for found services
[INFO  : 2128] Got Service Changed Characteristic
[INFO  : 2129] Characteristic discovery complete
[INFO  : 2129] Continue discovering more characteristics
[INFO  : 2168] Got Device Name Characteristic
[INFO  : 2169] Characteristic discovery complete
[INFO  : 2169] Continue discovering more characteristics
[INFO  : 2209] Got ROP Characteristic
[INFO  : 2210] Got ACP Characteristic
[INFO  : 2210] Got AUS Characteristic
[INFO  : 2210] Got VOL Characteristic
[INFO  : 2210] Got PSM Characteristic
[INFO  : 2210] Characteristic discovery complete
[INFO  : 2210] Characteristic discovery complete
[INFO  : 2308] Subscribed to GATT Service changed indication
[INFO  : 2348] Getting Device Name value
[INFO  : 2348] Name length: 7
[INFO  : 2348] Completed value read of Device Name
[INFO  : 2388] Getting ReadOnlyProperties value
[INFO  : 2388] Completed value read of ReadOnlyProperties
[INFO  : 2428] Getting PSM value
[INFO  : 2428] PSM: 128
[INFO  : 2428] Completed value read of PSM
[INFO  : 2428] ROP - Side: L, Mode: B, M. ID: 0107, Delay: 89, 16KHz: Y, 24KHz: N
[INFO  : 2508] Left: Subscribed to ASP Notification
[INFO  : 2508] Left: Connecting to L2CAP
[INFO  : 2548] Left: L2CAP CoC channel created
[INFO  : 2589] Left: ASP Start Ok.
[INFO  : 2603] Identity resolving succeeded
[INFO  : 2604] Connecting to address 61:0F:14:0B:8D:FA
[INFO  : 2610] Left: Volume changed to -62
[INFO  : 2762] Device connected. Attempt pairing
[INFO  : 2765] Reencryption started
[INFO  : 2941] Reencryption complete
[INFO  : 2981] DL set to: RX Octets: 251, RX Time: 2112 us, TX Octets: 251, TX Time: 2120 us
[INFO  : 2981] Device paired. Discovering ASHA service
[INFO  : 3081] GATT service found
[INFO  : 3081] GAP service found
[INFO  : 3161] ASHA service found
[INFO  : 3261] Discovering characteristics for found services
[INFO  : 3301] Got Service Changed Characteristic
[INFO  : 3301] Characteristic discovery complete
[INFO  : 3302] Continue discovering more characteristics
[INFO  : 3341] Got Device Name Characteristic
[INFO  : 3341] Characteristic discovery complete
[INFO  : 3342] Continue discovering more characteristics
[INFO  : 3382] Got ROP Characteristic
[INFO  : 3382] Got ACP Characteristic
[INFO  : 3382] Got AUS Characteristic
[INFO  : 3383] Got VOL Characteristic
[INFO  : 3383] Got PSM Characteristic
[INFO  : 3383] Characteristic discovery complete
[INFO  : 3383] Characteristic discovery complete
[INFO  : 3461] Subscribed to GATT Service changed indication
[INFO  : 3501] Getting Device Name value
[INFO  : 3501] Name length: 7
[INFO  : 3501] Completed value read of Device Name
[INFO  : 3541] Getting ReadOnlyProperties value
[INFO  : 3541] Completed value read of ReadOnlyProperties
[INFO  : 3581] Getting PSM value
[INFO  : 3581] PSM: 128
[INFO  : 3581] Completed value read of PSM
[INFO  : 3581] ROP - Side: R, Mode: B, M. ID: 0107, Delay: 89, 16KHz: Y, 24KHz: N
[INFO  : 3583] Connected to all aid(s) in set.
[INFO  : 3661] Right: Subscribed to ASP Notification
[INFO  : 3661] Right: Connecting to L2CAP
[INFO  : 3701] Right: L2CAP CoC channel created
[INFO  : 3742] Right: ASP Start Ok.
[INFO  : 3744] Right: Volume changed to -62

Specifically, note the line [INFO : 3581] PSM: 128. I don't see that in your devices

shermp commented 1 hour ago

Actually, also the line [INFO : 2967] ROP - Side: L, Mode: M, M. ID: 0000, Delay: 0, 16KHz: N, 24KHz: N

What is going on?

MadSpindel commented 1 hour ago

I've the latest Oticon More firmware (1.4.4), do you have that as well?

shermp commented 1 hour ago

I've the latest Oticon More firmware (1.4.4), do you have that as well?

Yes, that's what I'm running.

It seems I need to add some more error checking...

shermp commented 1 hour ago

Can you try this build. I added some error checking:

pico-asha-more-errors.zip

This is all I can do this evening, I need to go to bed soon.

MadSpindel commented 1 hour ago

This is all I can do this evening, I need to go to bed soon.

Sure, no worries. I'll try it soon and report back. If we can get this to work, it will be a game changer.

shermp commented 1 hour ago

It should work, my own More's are proof of that. I don't think there's any difference between the 1 & 2 as far as BT/ASHA is concerned.

MadSpindel commented 50 minutes ago

Now the LED light is flashing

[INFO  : 256] BT ASHA starting.
[INFO  : 256] L2CAP Init.
[INFO  : 256] SM Init.
[INFO  : 256] GATT Client Init.
[INFO  : 256] HCI power on.
[INFO  : 1162] BTstack up and running on 28:CD:C1:11:EB:F6
[INFO  : 1162] Start scanning.
[INFO  : 1456] Identity resolving succeeded
[INFO  : 1457] Connecting to address 74:E2:22:B3:1D:62
[INFO  : 1530] Device connected. Attempt pairing
[INFO  : 1531] Reencryption started
[INFO  : 1722] Reencryption complete
[INFO  : 1822] DL set to: RX Octets: 251, RX Time: 2112 us, TX Octets: 167, TX Time: 1064 us
[INFO  : 1822] Device paired. Discovering ASHA service
[INFO  : 1962] GATT service found
[INFO  : 1962] GAP service found
[INFO  : 2062] ASHA service found
[INFO  : 2182] Discovering characteristics for found services
[INFO  : 2242] Got Service Changed Characteristic
[INFO  : 2242] Characteristic discovery complete
[INFO  : 2243] Continue discovering more characteristics
[INFO  : 2282] Got Device Name Characteristic
[INFO  : 2282] Characteristic discovery complete
[INFO  : 2283] Continue discovering more characteristics
[INFO  : 2323] Got ROP Characteristic
[INFO  : 2323] Got ACP Characteristic
[INFO  : 2323] Got AUS Characteristic
[INFO  : 2323] Got VOL Characteristic
[INFO  : 2323] Got PSM Characteristic
[INFO  : 2324] Characteristic discovery complete
[INFO  : 2324] Characteristic discovery complete
[INFO  : 2422] Subscribed to GATT Service changed indication
[INFO  : 2462] Getting Device Name value
[INFO  : 2462] Name length: 7
[INFO  : 2462] Completed value read of Device Name
[ERROR : 2502] Error reading ROP: 0x05