Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

2.8.2.10-dev Omnipod Dash - lost BT connection on 2 pods #935

Closed
tswire opened this issue Nov 15, 2021 · 23 comments
Closed

2.8.2.10-dev Omnipod Dash - lost BT connection on 2 pods #935

tswire opened this issue Nov 15, 2021 · 23 comments

Comments

@tswire
Copy link

tswire commented Nov 15, 2021

Reporting bugs

  • At or around 1pm EST, AAPS lost connection to Dash Pod. It could read what was previously stored in the Pod (maybe on last connect) but could not communicate with the Pod no matter what I did. I tried force closing AAPS, exit (3 dots), switch Pump to Virtual and back to Dash, turn BT off then on. Restart phone. Nothing worked, was forced to deactivate Pod (manually)
  • Build version: 54b8d06-2021.11.12-18:44 of 2.8.2.10-dev
    AndroidAPS_LOG_1636919800184.log.zip

This is the second of around 6 Pods that this happened to. Running Pixel Pro 6 on Android 12

@avereha
Copy link
Contributor

avereha commented Nov 15, 2021

After reading the logs it looks like the pod services are somehow cached on the phone:

14:56:16.737 [RxCachedThreadScheduler-54] D/PUMPBTCOMM: [ServiceDiscoverer.discoverServices():28]: Discovering services
14:56:16.926 [Binder:31845_4] D/PUMPBTCOMM: [BleCommCallbacks.onServicesDiscovered():50]: OnServicesDiscovered with status: 0
....
ightscout.androidaps.plugins.pump.omnipod.dash.driver.comm.exceptions.ConnectException: Service not found: 1a7e-4024-e3ed-4464-8b7e-751e03d0dc5f
....

Not sure how to reset the Bluetooth cache on the phone and if this is possible.
My suggestion: if you get this multiple times mark the phone model as 'yellow'

@Alamo04
Copy link

Alamo04 commented Nov 15, 2021

@tswire
To clear Bluetooth Cache (Pixel 5)

  • go to Settings
  • go to Storage
  • choose Apps
  • over the 3 dots above choose "Show System Apps"
  • search for Bluetooth
  • choose Bluetooth and clear the cache.

@tswire
Copy link
Author

tswire commented Nov 15, 2021

@tswire To clear Bluetooth Cache (Pixel 5)

  • go to Settings
  • go to Storage
  • choose Apps
  • over the 3 dots above choose "Show System Apps"
  • search for Bluetooth
  • choose Bluetooth and clear the cache.

well darn, this was what I was looking for when scrambling to have it come back to life. I will make note and if happens again, I will try this. If successful, might be a good solution for others no matter the phone. As long as the Pod is not screaming, it SHOULD be available to communicate with. Kinda like Eros pods with Rileylink (or similar) when sometimes you had to restart the Rileylink and exit (3 dots) the AAPS app. Then it would reconnect fine. I know we are dealing with BT here.....but maybe this will help restore the connection!

@Alamo04
Copy link

Alamo04 commented Nov 15, 2021

Didn't have this Issue on Pixel 5.
I'm testing Dash, but only on my 3. one.

@tswire
Copy link
Author

tswire commented Dec 3, 2021

Adding additional log from today with what looks like same issue at 16:38
AndroidAPS.2021-12-02_00-00-05.3.zip

@Lastof
Copy link

Lastof commented Dec 3, 2021

Looking at the logs from my Pixel 6 Pro which hasn't lost any pods I do have two instances of similar logs, one from the 28th with

00:19:26.484 [RxCachedThreadScheduler-66] D/PUMPBTCOMM: [ServiceDiscoverer.discoverServices():28]: Discovering services
< three sameTimeZone messsages >
00:19:26.486 [Binder:17244_2] D/PUMPBTCOMM: [BleCommCallbacks.onServicesDiscovered():50]: OnServicesDiscovered with status: 0
< another sameTimeZone messsage >
00:19:26.486 [RxCachedThreadScheduler-66] D/PUMPBTCOMM: [ServiceDiscoverer.discoverServices():44]: Services discovered
00:19:26.487 [RxCachedThreadScheduler-66] D/PUMPBTCOMM: [Connection.disconnect():119]: Disconnecting closeGatt=false
00:19:26.487 [RxCachedThreadScheduler-66] D/PUMPBTCOMM: [BleCommCallbacks.resetConnection():213]: Reset connection
00:19:26.488 [Binder:17244_2] D/PUMPBTCOMM: [BleCommCallbacks.onConnectionStateChange():38]: OnConnectionStateChange with status/state: 0/0

and one from last night

02:12:45.383 [RxCachedThreadScheduler-88] D/PUMPBTCOMM: [ServiceDiscoverer.discoverServices():28]: Discovering services
02:12:45.385 [Thread-15239] D/PUMPBTCOMM: [BleCommCallbacks.resetConnection():213]: Reset connection
02:12:45.387 [Thread-15239] D/PUMPQUEUE: [Command.cancel():53]: Result cancel
02:12:45.388 [Thread-15239] D/PUMPQUEUE: [QueueThread.run():81]: no connection possible
02:12:45.388 [Thread-15239] I/PUMP: [OmnipodDashPumpPlugin.disconnect():266]: disconnect reason=Queue empty
02:12:45.389 [Thread-15239] D/PUMPBTCOMM: [Connection.disconnect():119]: Disconnecting closeGatt=false
02:12:45.389 [Thread-15239] D/PUMPBTCOMM: [BleCommCallbacks.resetConnection():213]: Reset connection
02:12:45.390 [RxCachedThreadScheduler-88] D/PUMPBTCOMM: [ServiceDiscoverer.discoverServices():44]: Services discovered
02:12:45.391 [Thread-15239] D/PUMPQUEUE: [QueueThread.run():150]: thread end
02:12:45.394 [RxCachedThreadScheduler-88] D/PUMPBTCOMM: [Connection.disconnect():119]: Disconnecting closeGatt=false
02:12:45.396 [RxCachedThreadScheduler-88] D/PUMPBTCOMM: [BleCommCallbacks.resetConnection():213]: Reset connection
02:12:45.397 [RxCachedThreadScheduler-88] W/PUMPBTCOMM: [OmnipodDashManagerImpl.observeConnectToPodWithStop$lambda-5$lambda-4():98]: observeConnectToPodWithStop error=info.nightscout.androidaps.plugins.pump.omnipod.dash.driver.comm.exceptions.ConnectException: Service not found: 1a7e-4024-e3ed-4464-8b7e-751e03d0dc5f`

but both times it seems to have sucessfully connected the next time it tried? Can provide the full logs if useful for comparison.

@avereha
Copy link
Contributor

avereha commented Dec 7, 2021

Added more logging in #1043, please share the logs when this happens again.

@tswire
Copy link
Author

tswire commented Dec 17, 2021

Adding another log based on 3rd random "disconnect" See log, seems to have started at 15:58:06 and I do see a lot more logging of COMM issues.
AndroidAPS.2021-12-16_00-00-05.3.zip

@tswire
Copy link
Author

tswire commented Dec 17, 2021

adding another log where at 18:22:50 it timed out (greater than 120 seconds) on a command....never had that where it would reconnect again (and did so by my refreshing status on Dash screen in AAPS
AndroidAPS_LOG_1639783444586.log.zip
.

@tswire
Copy link
Author

tswire commented Dec 20, 2021

Another random disconnect on Pixel Pro 6. Log attached, started at 22:42:37. Was sleeping and woke up to 60 min alarm for pump unreachable....same symptoms...not reconnect possible. This pod was from new box (new order from Omnipod) but seems to have same firmware/bluetooth as previous pod
AndroidAPS.2021-12-19_00-00-12.txt
s

@static1333
Copy link

static1333 commented Dec 27, 2021

I have had issues similar to what is described by tswire over the last few days. I am using beta 11 and lost two pods which I couldn't regain connection. Every time I tried to refresh then the connection would time out after ~120secs. I discarded both pods as I couldn't regain connection after restarting the bluetooth/aaps/phone and clearing the bluetooth cache. I'm on samsung S8 using android v9.

Attached are my logs for the latest instance where the connection failure starts at 14:55.
AndroidAPS_Dec26 Pod Connection Fail.log
.

FYI, I looked through the log and this seems to be the first error message relevant to my problem. It looks like it could be different from what tswire is experiencing.

14:55:21.561 [RxCachedThreadScheduler-36] E/PUMP: [OmnipodDashPumpPlugin.executeProgrammingCommand$lambda-47():1327]: Error executing command
info.nightscout.androidaps.plugins.pump.omnipod.dash.driver.comm.exceptions.MessageIOException: Could not read response: CommandReceiveError(msg=Could not read response)
at info.nightscout.androidaps.plugins.pump.omnipod.dash.driver.comm.OmnipodDashBleManagerImpl.sendCommand$lambda-0(OmnipodDashBleManagerImpl.kt:79) ~[na:0.0]

@tswire
Copy link
Author

tswire commented Dec 31, 2021

another pod disconnect at 1 day 21 hours for absolutely no reason. Logs attached approx 15:57:55 forward you can see the errors. PLEASE, what is going on here
AndroidAPS.log
?

@avereha
Copy link
Contributor

avereha commented Jan 1, 2022

Here is what I found in the logs, maybe we can see a pattern

  1. AndroidAPS.2021-12-16_00-00-05.3.zip : this is expected. Dash does not maintain a connection, it creates one for each command. Not a bug.
  2. AndroidAPS.2021-12-19_00-00-12.txt -> connection timeouts can happen and this is expected. Something interesting is that around that time I noticed
18:21:36.815 [main] D/CORE: [NetworkChangeReceiver.grabNetworkStatus():51]: NETCHANGE: Mobile connected. Roaming: false Metered: true
18:21:36.816 [main] D/CORE: [NetworkChangeReceiver.grabNetworkStatus():56]: EventNetworkChange[metered=true,mobileConnected=true,roaming=false,ssid=,vpnConnected=false,wifiC
onnected=true]

it looks like at exactly the same time the phone lost connection to the WiFi too.
3 . AndroidAPS.2021-12-19_00-00-12.txt -> this is the first real issue. The error is not as before. It timed out in the middle of the connection. Something interesting is that this timeout happened when Dexcom connected:

22:42:57.725 [RxCachedThreadScheduler-31] D/AUTOMATION: [AutomationPlugin.onStart$lambda-9():140]: Grabbed new BT event: EventBTChange[deviceAddress=DC:CA:B3:A3:1F:D1,device
Name=Dexcom4W,state=CONNECT]

After that, all connections failed with

OnConnectionStateChange with status/state: 133/0

This is interesting!
4. AndroidAPS_Dec26 Pod Connection Fail.log ->
The connections seems to fail with the same status:

15:49:22.733 [Binder:28796_2] D/PUMPBTCOMM: [BleCommCallbacks.onConnectionStateChange():38]: OnConnectionStateChange with status/state: 133/0
15:49:22.735 [Binder:28796_2] I/PUMPBTCOMM: [Connection.onConnectionLost():190]: Lost connection with status: 133

5.AndroidAPS.log -> Connection fails with 'service not found'. This is the original failure.

@tswire
Copy link
Author

tswire commented Jan 2, 2022

adding another. 1 day 18 hours in to new pod this time. seems to have disconnected around 10:08:41
AndroidAPS.log

@tswire
Copy link
Author

tswire commented Jan 12, 2022

Another one today but I am having a hard time finding the exact issue in the logs. It was between 12:10 and 12:20 when it lost connection (last connection was 12:10:38 then after that it was not able to connect. 2 logs attached.
AndroidAPS.2022-01-12_00-00-00.3.log
AndroidAPS.log

@tmarini1
Copy link

This is @Supertones. Pixel 6. Attaching the log file from my second pod loss (out of twenty something) due to unable to connect. Last connection time was around 10:37. Talking on Bluetooth headset so thinking that is related.
AndroidAPS.log

@madebyjonas
Copy link

Hi, I am not sure if this is the same issue. But I have had more than one time that my pump (Omnipod Dash) became unreachable for no reason. (+/- 1 time / day, differte time in day). What I did notice is that when I restart my phone, the connection is perfectly fine again. This keeps a nasty bug for when it occures at night and I don't wake up from the alarm I got that the connection has failed.

My Phone: OnePlus 9 with Android 12 (OxygenOS)
My AndroidAPS: 3.0-rc1 (waiting to update to RC - 3 in a day, when my pump needs to be replaced) but I had the issue in the latest beta as well.

I added the logfile from this night. Cut to the part where the connection error occures (multiple times). This keeps going like that until I restart my phone.
log Jonas Peeters.txt

If more information is needed, or this isn't the correct issue to post in, please let me know. I will be happy to help.

@tswire
Copy link
Author

tswire commented Jan 21, 2022

Hi, I am not sure if this is the same issue. But I have had more than one time that my pump (Omnipod Dash) became unreachable for no reason. (+/- 1 time / day, differte time in day). What I did notice is that when I restart my phone, the connection is perfectly fine again. This keeps a nasty bug for when it occures at night and I don't wake up from the alarm I got that the connection has failed.

My Phone: OnePlus 9 with Android 12 (OxygenOS) My AndroidAPS: 3.0-rc1 (waiting to update to RC - 3 in a day, when my pump needs to be replaced) but I had the issue in the latest beta as well.

I added the logfile from this night. Cut to the part where the connection error occures (multiple times). This keeps going like that until I restart my phone. log Jonas Peeters.txt

If more information is needed, or this isn't the correct issue to post in, please let me know. I will be happy to help.

Definitely seems like similar "bug" and now that it's on a different phone (we now have 3....Pixel 6, Samsung S? and OnePlus 9) I think there is something going on here. @avereha I know you were away but anything connecting these ?

@static1333
Copy link

I'm just going to leave an early indicator on a new phone that I am using and have tested for ~24 hours. I replaced my Samsung S8 (which was giving ~70-80% connection quality and a couple failed pods in the past) with the new S21 FE. The signal quality has shot up to 100% for the ~24 hrs that I have been using the new phone. No problems connecting now but it's still early (note that I also upgraded to rc3 at the same time). Assuming the underlying cause of some of the problems are tied to connection quality then my new phone is providing a significantly better experience. I'll comment again if anything changes.

@tswire
Copy link
Author

tswire commented Jan 24, 2022

I'm just going to leave an early indicator on a new phone that I am using and have tested for ~24 hours. I replaced my Samsung S8 (which was giving ~70-80% connection quality and a couple failed pods in the past) with the new S21 FE. The signal quality has shot up to 100% for the ~24 hrs that I have been using the new phone. No problems connecting now but it's still early (note that I also upgraded to rc3 at the same time). Assuming the underlying cause of some of the problems are tied to connection quality then my new phone is providing a significantly better experience. I'll comment again if anything changes.

any update? Thinking about the S21 FE or even S21+ or even S20 FE to have more confidence in my connection vs Pixel 6 Pro

@static1333
Copy link

static1333 commented Jan 24, 2022

Still nearly 100% connection after ~4 days (only 3 missed connections out of ~350 with one or two of those when I walked 20+ feet away). It's a massive improvement. I still have to run some distance tests but at this point it's a huge positive for me.

BTW I grabbed the S21 FE due to the battery size and cheaper price compared the other S21 offerings. Early indications are a smaller battery on the S22 which will be formally announced in a few weeks (so I decided not to wait).

@tswire
Copy link
Author

tswire commented Feb 1, 2022

As per Discord....at approx 19:03 (in the logs, this seems to be when it started) pod could not connect. Approx 10 minutes before this, I had just paired and connected Buds Live headphones for a conference call. This CANNOT be a coincidence as so many with pod failures are seeing them when using another peripheral. For me prior to this, it SEEMED to be coming from a program called Gadgetbridge which is connecting my Fossil Hybrid HR watch. I stopped using that 2 weeks ago, on Galaxy Watch 4 now and for over those 2 weeks, been perfect. The second I pair a new BT device, it causes a Pod failure. @avereha hopefully you can see something in this new log that points to more consistent and comparable issues to others!
AndroidAPS._2022-01-31_17-41.txt
!

@MilosKozak
Copy link
Contributor

Closed for inactivity. Create new issue is if you still see the problem on latest branch

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Projects
None yet
Development

No branches or pull requests

8 participants