HA Companion App BLE intermittent transmitter

ingrove · May 2, 2022, 2:52pm

I’m having an issue with the BLE beacon transmitter randomly stop/starting. The history not only shows the transmitter stopping and starting, but it disappears completely. The only sensor I have enabled in the Companion App is BLE transmitter. I don’t see anything weird in the logs either, but I’m not an expert with the Companion App. I’m trying to determine whether this is an issue with my phone (Pixel 5/Android 12) or with the HA Companion App.

I’ve tried every combination of settings in the Companion App BLE sensor section without any success. I’ve also given the App unrestricted battery usage and granted background permissions in my android phone settings.

Any words of wisdom would be greatly appreciated!

dshokouhi · May 2, 2022, 3:31pm

we need to look at Companion App logs and also mobile_app debug logs from HA core when this happens. At the moment it looks like the entity did not exist during those blank spaces. Seems to be more of a HA core issue than app.

ingrove · May 2, 2022, 5:55pm

Thanks. What is the best way to log only the mobile_app entries? I can specify “default: debug” for the logger in configuration.yaml but that will return a large dump to wade through. The companion log isn’t a problem as long as I can catch it in the act (it seems to only return about the last 45 minutes of data).

dshokouhi · May 2, 2022, 5:59pm

you can specify logging on a per component basis: Logger - Home Assistant

so you want to define homeassistant.components.mobile_app: debug as part of the logger

whats the behavior of the actual transmitter on the device when you see this? it should still be transmitting and if not you will see that in the companion app logs

ingrove · May 2, 2022, 6:07pm

Thanks again. This is what I had set in logger, but I wasn’t seeing any entries during the reboot:

logger:
  default: critical
  logs:
    homeassistant.components.mobile_app: debug

I haven’t been able to actually catch this as it’s happening to see the behavior of my Pixel 5 phone. What’s interesting though is that if I power off the phone, the HA history for the BLE transmitter indicates that it is still transmitting. I don’t know if that is the expected behavior or not.

dshokouhi · May 2, 2022, 6:12pm

there is no way for the app to send any data to your server when the phone is shut down

ingrove · May 2, 2022, 6:21pm

That’s what I thought too. I powered off my phone now at 2:15 pm yet the server indicates the phone is still transmitting:

dshokouhi · May 2, 2022, 6:25pm

the server depends on the app to relay the state without it getting any data you should not expect to see any changes

ingrove · May 2, 2022, 7:01pm

Here is the core log from the last drop-out:

2022-05-02 14:45:00 DEBUG (MainThread) [homeassistant.components.mobile_app.webhook] Received webhook payload from Pixel 5 for type update_sensor_states: [{'unique_id': 'ble_emitter', 'state': '', 'type': 'sensor', 'icon': 'mdi:bluetooth-off', 'attributes': {'Advertise mode': 'lowLatency', 'Measured power': -59, 'Transmitting power': 'low', 'id': '008fd71e-a43a-4ab4-8e02-f969133f8380-100-1'}}]

2022-05-02 14:45:00 DEBUG (MainThread) [homeassistant.components.mobile_app.webhook] Received webhook payload from Pixel 5 for type update_sensor_states: [{'unique_id': 'ble_emitter', 'state': '', 'type': 'sensor', 'icon': 'mdi:bluetooth-off', 'attributes': {'Advertise mode': 'lowLatency', 'Measured power': -59, 'Transmitting power': 'low', 'id': '008fd71e-a43a-4ab4-8e02-f969133f8380-100-1'}}]

2022-05-02 14:45:59 DEBUG (MainThread) [homeassistant.components.mobile_app.webhook] Received webhook payload from Pixel 5 for type update_sensor_states: [{'unique_id': 'ble_emitter', 'state': 'Transmitting', 'type': 'sensor', 'icon': 'mdi:bluetooth', 'attributes': {'Advertise mode': 'lowLatency', 'Measured power': -59, 'Transmitting power': 'low', 'id': '008fd71e-a43a-4ab4-8e02-f969133f8380-100-1'}}]

Here’s the Companion App log:

05-02 14:45:00.066 31838 31838 E mpanion.androi: Not starting debugger since process cannot load the jdwp agent.
05-02 14:45:00.068 31838 31838 D ProcessState: Binder ioctl to enable oneway spam detection failed: Invalid argument
05-02 14:45:00.081 31838 31838 D CompatibilityChangeReporter: Compat change id reported: 171979766; UID 10471; state: ENABLED
05-02 14:45:00.144 31838 31838 W mpanion.androi: Entry not found
05-02 14:45:00.146 31838 31838 W mpanion.androi: Entry not found
05-02 14:45:00.147 31838 31838 W mpanion.androi: Entry not found
05-02 14:45:00.153 31838 31838 W mpanion.androi: JIT profile information will not be recorded: profile file does not exist.
05-02 14:45:00.154 31838 31838 W mpanion.androi: JIT profile information will not be recorded: profile file does not exist.
05-02 14:45:00.154 31838 31838 W mpanion.androi: JIT profile information will not be recorded: profile file does not exist.
05-02 14:45:00.163 31838 31838 V GraphicsEnvironment: ANGLE Developer option for 'io.homeassistant.companion.android' set to: 'default'
05-02 14:45:00.163 31838 31838 V GraphicsEnvironment: ANGLE GameManagerService for io.homeassistant.companion.android: false
05-02 14:45:00.164 31838 31838 V GraphicsEnvironment: Updatable production driver is not supported on the device.
05-02 14:45:00.164 31838 31838 D NetworkSecurityConfig: Using Network Security Config from resource network_security_config debugBuild: false
05-02 14:45:00.167 31838 31838 D NetworkSecurityConfig: Using Network Security Config from resource network_security_config debugBuild: false
05-02 14:45:00.207 31838 31838 I FirebaseApp: Device unlocked: initializing all Firebase APIs for app [DEFAULT]
05-02 14:45:00.217 31838 31838 I FirebaseInitProvider: FirebaseApp initialization successful
05-02 14:45:00.220 31838 31838 D WM-WrkMgrInitializer: Initializing WorkManager with default configuration.
05-02 14:45:00.236 31838 31862 I DynamiteModule: Considering local module com.google.android.gms.measurement.dynamite:55 and remote module com.google.android.gms.measurement.dynamite:70
05-02 14:45:00.236 31838 31862 I DynamiteModule: Selected remote version of com.google.android.gms.measurement.dynamite, version >= 70
05-02 14:45:00.236 31838 31862 V DynamiteModule: Dynamite loader version >= 2, using loadModule2NoCrashUtils
05-02 14:45:00.236 31838 31838 D Sentry  : io.sentry.auto-init read: false
05-02 14:45:00.236 31838 31838 I Sentry  : Retrieving auto-init from AndroidManifest.xml
05-02 14:45:00.253 31838 31864 D CompatibilityChangeReporter: Compat change id reported: 160794467; UID 10471; state: ENABLED
05-02 14:45:00.374 31838 31871 I FA      : App measurement initialized, version: 61007
05-02 14:45:00.374 31838 31871 I FA      : To enable debug logging run: adb shell setprop log.tag.FA VERBOSE
05-02 14:45:00.375 31838 31871 I FA      : To enable faster debug mode event logging run:
05-02 14:45:00.375 31838 31871 I FA      :   adb shell setprop debug.firebase.analytics.app io.homeassistant.companion.android
05-02 14:45:00.381 31838 31876 I WebViewFactory: Loading com.google.android.webview version 100.0.4896.127 (code 489612734)
05-02 14:45:00.450 31838 31876 I cr_WVCFactoryProvider: Loaded version=100.0.4896.127 minSdkVersion=29 isBundle=true multiprocess=true packageId=2
05-02 14:45:00.465 31838 31876 I cr_LibraryLoader: Successfully loaded native library
05-02 14:45:00.466 31838 31876 I cr_CachingUmaRecorder: Flushed 8 samples from 8 histograms.
05-02 14:45:00.479 31838 31838 D SensorReceiver: Received intent: android.net.wifi.STATE_CHANGE
05-02 14:45:00.480 31838 31838 D SensorReceiver: Received intent: android.net.wifi.WIFI_STATE_CHANGED
05-02 14:45:00.480 31838 31838 D SensorReceiver: Sensor wifi_state corresponding to received event android.net.wifi.WIFI_STATE_CHANGED is disabled, skipping sensors update
05-02 14:45:00.480 31838 31838 D SensorReceiver: Received intent: android.media.RINGER_MODE_CHANGED
05-02 14:45:00.480 31838 31838 D SensorReceiver: Sensor audio_sensor corresponding to received event android.media.RINGER_MODE_CHANGED is disabled, skipping sensors update
05-02 14:45:00.494 31838 31868 D SensorWorker: Updating all Sensors.
05-02 14:45:00.496 31838 31874 I WM-Processor: Moving WorkSpec (2bb3752e-e1a4-414d-8958-2733643b09e7) to the foreground
05-02 14:45:00.507 31838 31838 I WM-SystemFgDispatcher: Started foreground service Intent { act=ACTION_START_FOREGROUND cmp=io.homeassistant.companion.android/androidx.work.impl.foreground.SystemForegroundService (has extras) }
05-02 14:45:00.508 31838 31867 D UrlRepository: localUrl is: false and usesInternalSsid is: false
05-02 14:45:00.512 31838 31874 I WM-WorkerWrapper: Worker result SUCCESS for Work [ id=aeb9bc6a-e17e-4b90-b173-e0a4ed1cb35c, tags={ io.homeassistant.companion.android.websocket.WebsocketManager } ]
05-02 14:45:00.534 31838 31869 D BeaconParser: Parsing beacon layout: m:2-3=0215,i:4-19,i:20-21,i:22-23,p:24-24
05-02 14:45:00.536 31838 31869 D BluetoothAdapter: isLeEnabled(): ON
05-02 14:45:00.537 31838 31869 D BluetoothAdapter: isLeEnabled(): ON
05-02 14:45:00.546 31838 31919 D BluetoothAdapter: isLeEnabled(): ON
05-02 14:45:00.546 31838 31838 E BeaconTransmitter: Advertisement start failed, code: 3
05-02 14:45:00.559 31838 31838 I BeaconTransmitter: Advertisement start succeeded.
05-02 14:45:00.612 31838 31869 D UrlRepository: localUrl is: false and usesInternalSsid is: false
05-02 14:45:00.612 31838 31919 D UrlRepository: localUrl is: false and usesInternalSsid is: false
05-02 14:45:00.726 31838 31864 I WM-WorkerWrapper: Worker result SUCCESS for Work [ id=2bb3752e-e1a4-414d-8958-2733643b09e7, tags={ io.homeassistant.companion.android.sensors.SensorWorker } ]
05-02 14:45:00.731 31838 31838 I WM-SystemFgDispatcher: Stopping foreground service
05-02 14:46:00.004 31838 31838 D SensorReceiver: Received intent: android.intent.action.TIME_TICK

dshokouhi · May 2, 2022, 7:06pm

interesting can you please open an issue on github with these logs and screenshots? it should not be sending a blank state like that so something we should look at and the companion app logs show it had a failure so we need to take a closer look

ingrove · May 2, 2022, 7:08pm

Will do. Thank you again for your help with this!

brookst · February 3, 2024, 6:41pm

I’m getting the same kind of problem with my Android phone. The github issue(#2499) has been marked solved, so I guess something else is causing it now. The state of the transmitter in HA is always “Transmitting”.

I have the companion app logs, but not the HA logs. I went into the settings for the BLE Transmitter sensor and turned the “Enable Transmitter” setting off and then on again. ESPresence wasn’t sending an MQTT topic for the phone until I did this. HA showed the phone state right away at 18:05:52. I can’t see anything different in the logs before hand but the logs don’t seem to go back very far. Could this be some power saving issue? The app has “Unrestricted” battery usage in the Android system settings.

Companion app logs

--------- beginning of main
02-03 18:05:19.521 22334 22406 D BluetoothAdapter: isLeEnabled(): ON
02-03 18:05:20.021 22334 22406 D BluetoothAdapter: isLeEnabled(): ON
02-03 18:05:20.027 22334  1318 D BluetoothLeScanner: onScannerRegistered() - status=0 scannerId=5 mScannerId=0
02-03 18:05:20.235 22334  1318 I OpenGLRenderer: Davey! duration=9220754886637ms; Flags=0, FrameTimelineVsyncId=50632075, IntendedVsync=2617150216471278, Vsync=2617150216471278, InputEventId=743601371, HandleInputStart=2617150217305184, AnimationStart=2617150218965653, PerformTraversalsStart=2617150221615236, DrawStart=2617150221987164, FrameDeadline=2617150240471277, FrameInterval=2617150217297111, FrameStartTime=8333333, SyncQueued=2617150222072111, SyncStart=2617150223020757, IssueDrawCommandsStart=2617150223999612, SwapBuffers=2617150228696695, FrameCompleted=9223372036854775807, DequeueBufferDuration=48750, QueueBufferDuration=1591875, GpuCompleted=9223372036854775807, SwapBuffersCompleted=2617150231403779, DisplayPresentTime=281479271743489, 
02-03 18:05:20.548 22334 22334 W Choreographer: Frame time is 0.042794 ms in the future!  Check that graphics HAL is generating vsync timestamps using the correct timebase.
02-03 18:05:20.906 22334 22334 W Choreographer: Frame time is 0.017615 ms in the future!  Check that graphics HAL is generating vsync timestamps using the correct timebase.
02-03 18:05:20.993 22334 22347 I OpenGLRenderer: Davey! duration=9220754885888ms; Flags=0, FrameTimelineVsyncId=50632248, IntendedVsync=2617150966227356, Vsync=2617150966227356, InputEventId=415660968, HandleInputStart=2617150966726300, AnimationStart=2617150968964946, PerformTraversalsStart=2617150977769010, DrawStart=2617150977974635, FrameDeadline=2617150990227355, FrameInterval=2617150966716873, FrameStartTime=8333333, SyncQueued=2617150980346093, SyncStart=2617150980715000, IssueDrawCommandsStart=2617150981510989, SwapBuffers=2617150986691042, FrameCompleted=9223372036854775807, DequeueBufferDuration=125521, QueueBufferDuration=1762344, GpuCompleted=9223372036854775807, SwapBuffersCompleted=2617150989511407, DisplayPresentTime=3096237628850177, 
02-03 18:05:21.093 22334 22347 I OpenGLRenderer: Davey! duration=9220754885779ms; Flags=0, FrameTimelineVsyncId=50632273, IntendedVsync=2617151074612443, Vsync=2617151074612443, InputEventId=50542333, HandleInputStart=2617151076368238, AnimationStart=2617151077077457, PerformTraversalsStart=2617151082478916, DrawStart=2617151082586572, FrameDeadline=2617151098612442, FrameInterval=2617151076361519, FrameStartTime=8333333, SyncQueued=2617151083330531, SyncStart=2617151083955426, IssueDrawCommandsStart=2617151084255374, SwapBuffers=2617151087099021, FrameCompleted=9223372036854775807, DequeueBufferDuration=29323, QueueBufferDuration=1684740, GpuCompleted=9223372036854775807, SwapBuffersCompleted=2617151089385167, DisplayPresentTime=0, 
02-03 18:05:27.526 22334 22406 D BluetoothAdapter: isLeEnabled(): ON
02-03 18:05:28.018 22334 22406 D BluetoothAdapter: isLeEnabled(): ON
02-03 18:05:28.024 22334 22347 D BluetoothLeScanner: onScannerRegistered() - status=0 scannerId=5 mScannerId=0
02-03 18:05:28.106 22334 22347 I OpenGLRenderer: Davey! duration=9220754878778ms; Flags=0, FrameTimelineVsyncId=50632376, IntendedVsync=2617158075892517, Vsync=2617158075892517, InputEventId=580433040, HandleInputStart=2617158080177894, AnimationStart=2617158080199248, PerformTraversalsStart=2617158080222998, DrawStart=2617158080818623, FrameDeadline=2617158099892516, FrameInterval=2617158080168936, FrameStartTime=8333333, SyncQueued=2617158085634561, SyncStart=2617158085759093, IssueDrawCommandsStart=2617158086557739, SwapBuffers=2617158091887010, FrameCompleted=9223372036854775807, DequeueBufferDuration=66562, QueueBufferDuration=3509428, GpuCompleted=9223372036854775807, SwapBuffersCompleted=2617158096273104, DisplayPresentTime=281479271743489, 
02-03 18:05:28.163 22334 22347 I OpenGLRenderer: Davey! duration=9220754878770ms; Flags=0, FrameTimelineVsyncId=50632377, IntendedVsync=2617158084226545, Vsync=2617158109226544, InputEventId=0, HandleInputStart=2617158112609252, AnimationStart=2617158112610866, PerformTraversalsStart=2617158116852533, DrawStart=2617158141048473, FrameDeadline=2617158108226544, FrameInterval=2617158112604252, FrameStartTime=8333333, SyncQueued=2617158143347432, SyncStart=2617158143834203, IssueDrawCommandsStart=2617158144511755, SwapBuffers=2617158154657589, FrameCompleted=9223372036854775807, DequeueBufferDuration=21197, QueueBufferDuration=827396, GpuCompleted=9223372036854775807, SwapBuffersCompleted=2617158156130714, DisplayPresentTime=281479271743490, 
02-03 18:05:28.182 22334 22334 D SensorReceiver: Received intent: io.homeassistant.companion.android.UPDATE_SENSORS
02-03 18:05:28.185 22334   952 D ServerConnectionInfo: localUrl is: false, usesInternalSsid is: false, usesWifi is: true
02-03 18:05:28.291 22334   952 D ServerConnectionInfo: localUrl is: false, usesInternalSsid is: false, usesWifi is: true
02-03 18:05:28.291 22334   952 D ServerConnectionInfo: localUrl is: false, usesInternalSsid is: false, usesWifi is: true
02-03 18:05:28.508 22334   952 D ServerConnectionInfo: localUrl is: false, usesInternalSsid is: false, usesWifi is: true
02-03 18:05:28.534 22334   952 I SensorReceiver: Sensor updates and sync completed
02-03 18:05:28.570 22334 22347 I OpenGLRenderer: Davey! duration=9220754878311ms; Flags=0, FrameTimelineVsyncId=50632465, IntendedVsync=2617158542867887, Vsync=2617158551201220, InputEventId=0, HandleInputStart=2617158551928775, AnimationStart=2617158551931639, PerformTraversalsStart=2617158556944036, DrawStart=2617158557030598, FrameDeadline=2617158558534553, FrameInterval=2617158551923306, FrameStartTime=8333333, SyncQueued=2617158558029088, SyncStart=2617158558143567, IssueDrawCommandsStart=2617158559144973, SwapBuffers=2617158562437057, FrameCompleted=9223372036854775807, DequeueBufferDuration=38334, QueueBufferDuration=1570730, GpuCompleted=9223372036854775807, SwapBuffersCompleted=2617158564664036, DisplayPresentTime=281479271743489, 
02-03 18:05:29.745 22334  1318 I OpenGLRenderer: Davey! duration=9220754877135ms; Flags=0, FrameTimelineVsyncId=50632475, IntendedVsync=2617159718365485, Vsync=2617159718365485, InputEventId=0, HandleInputStart=2617159720301599, AnimationStart=2617159720306912, PerformTraversalsStart=2617159720310610, DrawStart=2617159720463318, FrameDeadline=2617159742365484, FrameInterval=2617159720290662, FrameStartTime=8333333, SyncQueued=2617159721209464, SyncStart=2617159721765297, IssueDrawCommandsStart=2617159722423318, SwapBuffers=2617159727952850, FrameCompleted=9223372036854775807, DequeueBufferDuration=97656, QueueBufferDuration=2413386, GpuCompleted=9223372036854775807, SwapBuffersCompleted=2617159732273423, DisplayPresentTime=281479271743489, 
02-03 18:05:29.771 22334 22347 I OpenGLRenderer: Davey! duration=9220754877102ms; Flags=0, FrameTimelineVsyncId=50632479, IntendedVsync=2617159743377196, Vsync=2617159743377196, InputEventId=0, HandleInputStart=2617159746926133, AnimationStart=2617159746928842, PerformTraversalsStart=2617159746930925, DrawStart=2617159747079467, FrameDeadline=2617159767377195, FrameInterval=2617159746918633, FrameStartTime=8333333, SyncQueued=2617159747768269, SyncStart=2617159756952020, IssueDrawCommandsStart=2617159757737020, SwapBuffers=2617159762527645, FrameCompleted=9223372036854775807, DequeueBufferDuration=60989, QueueBufferDuration=1327916, GpuCompleted=9223372036854775807, SwapBuffersCompleted=2617159765360823, DisplayPresentTime=562954248454145, 
02-03 18:05:35.522 22334 22406 D BluetoothAdapter: isLeEnabled(): ON
02-03 18:05:36.022 22334 22406 D BluetoothAdapter: isLeEnabled(): ON
02-03 18:05:36.032 22334 22347 D BluetoothLeScanner: onScannerRegistered() - status=0 scannerId=5 mScannerId=0
02-03 18:05:42.132 22334 22347 I OpenGLRenderer: Davey! duration=9220754864742ms; Flags=0, FrameTimelineVsyncId=50632802, IntendedVsync=2617172111319327, Vsync=2617172111319327, InputEventId=444002489, HandleInputStart=2617172112257261, AnimationStart=2617172114026480, PerformTraversalsStart=2617172115842313, DrawStart=2617172115966897, FrameDeadline=2617172126985993, FrameInterval=2617172112248355, FrameStartTime=8333333, SyncQueued=2617172117142366, SyncStart=2617172117601532, IssueDrawCommandsStart=2617172118147105, SwapBuffers=2617172121532887, FrameCompleted=9223372036854775807, DequeueBufferDuration=146667, QueueBufferDuration=2099010, GpuCompleted=9223372036854775807, SwapBuffersCompleted=2617172124678720, DisplayPresentTime=281479271743489, 
02-03 18:05:43.526 22334 22406 D BluetoothAdapter: isLeEnabled(): ON
02-03 18:05:43.748 22334  1318 I OpenGLRenderer: Davey! duration=9220754863175ms; Flags=0, FrameTimelineVsyncId=50632938, IntendedVsync=2617173678908232, Vsync=2617173687241565, InputEventId=550266814, HandleInputStart=2617173690418251, AnimationStart=2617173690422053, PerformTraversalsStart=2617173735695548, DrawStart=2617173736689089, FrameDeadline=2617173702908231, FrameInterval=2617173690409918, FrameStartTime=8333333, SyncQueued=2617173738976173, SyncStart=2617173739730392, IssueDrawCommandsStart=2617173739921954, SwapBuffers=2617173741390340, FrameCompleted=9223372036854775807, DequeueBufferDuration=34167, QueueBufferDuration=890521, GpuCompleted=9223372036854775807, SwapBuffersCompleted=2617173742831538, DisplayPresentTime=281479271743489, 
02-03 18:05:43.808 22334 22347 I OpenGLRenderer: Davey! duration=9220754863141ms; Flags=0, FrameTimelineVsyncId=50632940, IntendedVsync=2617173712212800, Vsync=2617173778879464, InputEventId=0, HandleInputStart=2617173784293469, AnimationStart=2617173784295813, PerformTraversalsStart=2617173785281073, DrawStart=2617173799819148, FrameDeadline=2617173736212799, FrameInterval=2617173784289355, FrameStartTime=8333333, SyncQueued=2617173800030033, SyncStart=2617173801049565, IssueDrawCommandsStart=2617173801171127, SwapBuffers=2617173802109877, FrameCompleted=9223372036854775807, DequeueBufferDuration=19114, QueueBufferDuration=1605885, GpuCompleted=9223372036854775807, SwapBuffersCompleted=2617173804000398, DisplayPresentTime=0, 
02-03 18:05:44.018 22334 22406 D BluetoothAdapter: isLeEnabled(): ON
02-03 18:05:44.023 22334  1318 D BluetoothLeScanner: onScannerRegistered() - status=0 scannerId=5 mScannerId=0
02-03 18:05:45.190 22334 22334 D IntegrationRepository: isAppLocked(): false. (LockEnabled: false, appActive: true, expireMillis: 0, currentMillis: 1706983545190)
02-03 18:05:45.190 22334 22334 D IntegrationRepository: setAppActive(): true
02-03 18:05:48.267 22334 22347 I OpenGLRenderer: Davey! duration=9220754858607ms; Flags=0, FrameTimelineVsyncId=50633256, IntendedVsync=2617178246545564, Vsync=2617178246545564, InputEventId=380507748, HandleInputStart=2617178247150216, AnimationStart=2617178248816778, PerformTraversalsStart=2617178248819851, DrawStart=2617178249540528, FrameDeadline=2617178262212230, FrameInterval=2617178247140789, FrameStartTime=8333333, SyncQueued=2617178254359279, SyncStart=2617178254773914, IssueDrawCommandsStart=2617178255544540, SwapBuffers=2617178259080425, FrameCompleted=9223372036854775807, DequeueBufferDuration=69479, QueueBufferDuration=1334166, GpuCompleted=9223372036854775807, SwapBuffersCompleted=2617178261559019, DisplayPresentTime=0, 
02-03 18:05:48.934 22334  1318 I OpenGLRenderer: Davey! duration=9220754857941ms; Flags=2, FrameTimelineVsyncId=50633443, IntendedVsync=2617178913555796, Vsync=2617178913555796, InputEventId=0, HandleInputStart=2617178913555796, AnimationStart=2617178913555796, PerformTraversalsStart=2617178913555796, DrawStart=2617178913555796, FrameDeadline=2617178929222462, FrameInterval=0, FrameStartTime=8333333, SyncQueued=2617178915731272, SyncStart=2617178915732939, IssueDrawCommandsStart=2617178916190022, SwapBuffers=2617178919925595, FrameCompleted=9223372036854775807, DequeueBufferDuration=40156, QueueBufferDuration=1800105, GpuCompleted=9223372036854775807, SwapBuffersCompleted=2617178922284346, DisplayPresentTime=562954248454145, 
02-03 18:05:49.765 22334 22334 D IntegrationRepository: isAppLocked(): false. (LockEnabled: false, appActive: true, expireMillis: 0, currentMillis: 1706983549765)
02-03 18:05:49.766 22334 22334 D IntegrationRepository: setAppActive(): true
02-03 18:05:50.841 22334 22334 D SensorReceiver: Received intent: io.homeassistant.companion.android.UPDATE_SENSORS
02-03 18:05:50.843 22334   952 D ServerConnectionInfo: localUrl is: false, usesInternalSsid is: false, usesWifi is: true
02-03 18:05:50.847 22334 30669 I OpenGLRenderer: Davey! duration=9220754856057ms; Flags=0, FrameTimelineVsyncId=50633575, IntendedVsync=2617180797052995, Vsync=2617180822052994, InputEventId=489626211, HandleInputStart=2617180825709900, AnimationStart=2617180825733389, PerformTraversalsStart=2617180825734691, DrawStart=2617180825945264, FrameDeadline=2617180812719661, FrameInterval=2617180825704327, FrameStartTime=8333333, SyncQueued=2617180827849796, SyncStart=2617180827946931, IssueDrawCommandsStart=2617180828099796, SwapBuffers=2617180829600629, FrameCompleted=9223372036854775807, DequeueBufferDuration=41979, QueueBufferDuration=1264115, GpuCompleted=9223372036854775807, SwapBuffersCompleted=2617180831288702, DisplayPresentTime=-5476376642628689328, 
02-03 18:05:50.932 22334   952 D ServerConnectionInfo: localUrl is: false, usesInternalSsid is: false, usesWifi is: true
02-03 18:05:50.933 22334   952 D ServerConnectionInfo: localUrl is: false, usesInternalSsid is: false, usesWifi is: true
02-03 18:05:51.172 22334   952 D ServerConnectionInfo: localUrl is: false, usesInternalSsid is: false, usesWifi is: true
02-03 18:05:51.207 22334   952 I SensorReceiver: Sensor updates and sync completed
02-03 18:05:51.522 22334 22406 D BluetoothAdapter: isLeEnabled(): ON
02-03 18:05:52.018 22334 22406 D BluetoothAdapter: isLeEnabled(): ON
02-03 18:05:52.023 22334 30669 D BluetoothLeScanner: onScannerRegistered() - status=0 scannerId=5 mScannerId=0
02-03 18:05:52.069 22334 22334 D BluetoothAdapter: isLeEnabled(): ON
02-03 18:05:52.069 22334 22334 D BluetoothAdapter: isLeEnabled(): ON
02-03 18:05:52.115 22334 30669 I OpenGLRenderer: Davey! duration=9220754854790ms; Flags=0, FrameTimelineVsyncId=50633785, IntendedVsync=2617182064118832, Vsync=2617182089118831, InputEventId=498294030, HandleInputStart=2617182095042526, AnimationStart=2617182095043828, PerformTraversalsStart=2617182103865548, DrawStart=2617182103918569, FrameDeadline=2617182079785498, FrameInterval=2617182095038516, FrameStartTime=8333333, SyncQueued=2617182106336173, SyncStart=2617182106784663, IssueDrawCommandsStart=2617182106941069, SwapBuffers=2617182107915705, FrameCompleted=9223372036854775807, DequeueBufferDuration=27031, QueueBufferDuration=1460052, GpuCompleted=9223372036854775807, SwapBuffersCompleted=2617182109796330, DisplayPresentTime=281483566776321, 
02-03 18:05:52.119 22334 22334 D SensorReceiver: Received intent: io.homeassistant.companion.android.UPDATE_SENSORS
02-03 18:05:52.121 22334 14160 D ServerConnectionInfo: localUrl is: false, usesInternalSsid is: false, usesWifi is: true
02-03 18:05:52.122 22334 22334 I BeaconTransmitter: Advertisement start succeeded.
02-03 18:05:52.220 22334 14160 D ServerConnectionInfo: localUrl is: false, usesInternalSsid is: false, usesWifi is: true
02-03 18:05:52.221 22334 14160 D ServerConnectionInfo: localUrl is: false, usesInternalSsid is: false, usesWifi is: true
02-03 18:05:52.338 22334 14160 D ServerConnectionInfo: localUrl is: false, usesInternalSsid is: false, usesWifi is: true
02-03 18:05:52.380 22334 14160 I SensorReceiver: Sensor updates and sync completed
02-03 18:05:52.687 22334 22334 D LocBroadcastReceiver: Received location update.
02-03 18:05:52.692 22334 22334 W LocBroadcastReceiver: Location accuracy didn't meet requirements, disregarding: Location[fused *,* hAcc=2599.999 et=+34d20h50m5s930ms alt=139.4818115234375 vAcc=27.728811]
02-03 18:05:59.524 22334 22406 D BluetoothAdapter: isLeEnabled(): ON
02-03 18:06:00.020 22334 22406 D BluetoothAdapter: isLeEnabled(): ON
02-03 18:06:00.027 22334 30669 D BluetoothLeScanner: onScannerRegistered() - status=0 scannerId=5 mScannerId=0
02-03 18:06:03.136 22334 22334 D SensorReceiver: Received intent: android.net.wifi.STATE_CHANGE
02-03 18:06:03.162 22334 14161 D ServerConnectionInfo: localUrl is: false, usesInternalSsid is: false, usesWifi is: true
02-03 18:06:03.251 22334 14160 D ServerConnectionInfo: localUrl is: false, usesInternalSsid is: false, usesWifi is: true
02-03 18:06:03.252 22334 22361 I WM-WorkerWrapper: Worker result SUCCESS for Work [ id=c095c464-7184-4b46-85ed-0955abd2bcfd, tags={ io.homeassistant.companion.android.websocket.WebsocketManager } ]
02-03 18:06:03.308 22334 14161 D ServerConnectionInfo: localUrl is: false, usesInternalSsid is: false, usesWifi is: true
02-03 18:06:03.308 22334 14161 D ServerConnectionInfo: localUrl is: false, usesInternalSsid is: false, usesWifi is: true
02-03 18:06:03.451 22334 14161 D ServerConnectionInfo: localUrl is: false, usesInternalSsid is: false, usesWifi is: true
02-03 18:06:03.508 22334 14161 I SensorReceiver: Sensor updates and sync completed
02-03 18:06:07.526 22334 22406 D BluetoothAdapter: isLeEnabled(): ON
02-03 18:06:08.019 22334 22406 D BluetoothAdapter: isLeEnabled(): ON
02-03 18:06:08.030 22334  1318 D BluetoothLeScanner: onScannerRegistered() - status=0 scannerId=5 mScannerId=0
02-03 18:06:15.523 22334 22406 D BluetoothAdapter: isLeEnabled(): ON
02-03 18:06:16.022 22334 22406 D BluetoothAdapter: isLeEnabled(): ON
02-03 18:06:16.031 22334  1318 D BluetoothLeScanner: onScannerRegistered() - status=0 scannerId=5 mScannerId=0
02-03 18:06:23.521 22334 22406 D BluetoothAdapter: isLeEnabled(): ON
02-03 18:06:24.022 22334 22406 D BluetoothAdapter: isLeEnabled(): ON
02-03 18:06:24.032 22334 30669 D BluetoothLeScanner: onScannerRegistered() - status=0 scannerId=5 mScannerId=0
02-03 18:06:25.354 22334 22334 D SensorReceiver: Received intent: android.net.wifi.STATE_CHANGE
02-03 18:06:25.359 22334 14161 D ServerConnectionInfo: localUrl is: false, usesInternalSsid is: false, usesWifi is: true
02-03 18:06:25.407 22334 14160 D ServerConnectionInfo: localUrl is: false, usesInternalSsid is: false, usesWifi is: true
02-03 18:06:25.408 22334 22380 I WM-WorkerWrapper: Worker result SUCCESS for Work [ id=908819a1-4b8d-49fd-972f-1fb5e4f65e4f, tags={ io.homeassistant.companion.android.websocket.WebsocketManager } ]
02-03 18:06:25.453 22334 14161 D ServerConnectionInfo: localUrl is: false, usesInternalSsid is: false, usesWifi is: true
02-03 18:06:25.454 22334 14161 D ServerConnectionInfo: localUrl is: false, usesInternalSsid is: false, usesWifi is: true
02-03 18:06:30.797 22334 14161 D ServerConnectionInfo: localUrl is: false, usesInternalSsid is: false, usesWifi is: true
02-03 18:06:30.822 22334 14161 I SensorReceiver: Sensor updates and sync completed
02-03 18:06:31.522 22334 22406 D BluetoothAdapter: isLeEnabled(): ON
02-03 18:06:32.022 22334 22406 D BluetoothAdapter: isLeEnabled(): ON
02-03 18:06:32.032 22334  1318 D BluetoothLeScanner: onScannerRegistered() - status=0 scannerId=5 mScannerId=0
02-03 18:06:39.525 22334 22406 D BluetoothAdapter: isLeEnabled(): ON
02-03 18:06:40.021 22334 22406 D BluetoothAdapter: isLeEnabled(): ON
02-03 18:06:40.030 22334  1318 D BluetoothLeScanner: onScannerRegistered() - status=0 scannerId=5 mScannerId=0
02-03 18:06:47.522 22334 22406 D BluetoothAdapter: isLeEnabled(): ON
02-03 18:06:48.020 22334 22406 D BluetoothAdapter: isLeEnabled(): ON
02-03 18:06:48.029 22334  1318 D BluetoothLeScanner: onScannerRegistered() - status=0 scannerId=5 mScannerId=0
02-03 18:06:52.691 22334 22334 D LocBroadcastReceiver: Received location update.
02-03 18:06:52.698 22334 22334 W LocBroadcastReceiver: Location accuracy didn't meet requirements, disregarding: Location[fused *,* hAcc=2599.999 et=+34d20h51m5s931ms alt=139.4818115234375 vAcc=48.729164]
02-03 18:06:55.524 22334 22406 D BluetoothAdapter: isLeEnabled(): ON
02-03 18:06:56.019 22334 22406 D BluetoothAdapter: isLeEnabled(): ON
02-03 18:06:56.028 22334  1318 D BluetoothLeScanner: onScannerRegistered() - status=0 scannerId=5 mScannerId=0
02-03 18:07:03.522 22334 22406 D BluetoothAdapter: isLeEnabled(): ON
02-03 18:07:04.020 22334 22406 D BluetoothAdapter: isLeEnabled(): ON
02-03 18:07:04.028 22334  1318 D BluetoothLeScanner: onScannerRegistered() - status=0 scannerId=5 mScannerId=0
02-03 18:07:11.523 22334 22406 D BluetoothAdapter: isLeEnabled(): ON
02-03 18:07:12.022 22334 22406 D BluetoothAdapter: isLeEnabled(): ON
02-03 18:07:12.031 22334  1318 D BluetoothLeScanner: onScannerRegistered() - status=0 scannerId=5 mScannerId=0
02-03 18:07:19.526 22334 22406 D BluetoothAdapter: isLeEnabled(): ON
02-03 18:07:20.019 22334 22406 D BluetoothAdapter: isLeEnabled(): ON
02-03 18:07:20.027 22334  1318 D BluetoothLeScanner: onScannerRegistered() - status=0 scannerId=5 mScannerId=0
02-03 18:07:27.520 22334 22406 D BluetoothAdapter: isLeEnabled(): ON
02-03 18:07:28.019 22334 22406 D BluetoothAdapter: isLeEnabled(): ON
02-03 18:07:28.026 22334  1318 D BluetoothLeScanner: onScannerRegistered() - status=0 scannerId=5 mScannerId=0
02-03 18:07:33.669 22334  1318 I OpenGLRenderer: Davey! duration=9220754753203ms; Flags=0, FrameTimelineVsyncId=50634038, IntendedVsync=2617283650827332, Vsync=2617283650827332, InputEventId=768531254, HandleInputStart=2617283651311601, AnimationStart=2617283652835143, PerformTraversalsStart=2617283658023842, DrawStart=2617283658167019, FrameDeadline=2617283666493998, FrameInterval=2617283651303164, FrameStartTime=8333333, SyncQueued=2617283659369988, SyncStart=2617283659465404, IssueDrawCommandsStart=2617283659973998, SwapBuffers=2617283662441707, FrameCompleted=9223372036854775807, DequeueBufferDuration=48958, QueueBufferDuration=2030000, GpuCompleted=9223372036854775807, SwapBuffersCompleted=2617283667052540, DisplayPresentTime=562954248454145, 
02-03 18:07:33.680 22334  1318 I OpenGLRenderer: Davey! duration=9220754753194ms; Flags=0, FrameTimelineVsyncId=50634040, IntendedVsync=2617283659173615, Vsync=2617283659173615, InputEventId=661934409, HandleInputStart=2617283666242436, AnimationStart=2617283668067905, PerformTraversalsStart=2617283669002645, DrawStart=2617283669105457, FrameDeadline=2617283683173614, FrameInterval=2617283666233322, FrameStartTime=8333333, SyncQueued=2617283670070874, SyncStart=2617283671144833, IssueDrawCommandsStart=2617283671349780, SwapBuffers=2617283672380197, FrameCompleted=9223372036854775807, DequeueBufferDuration=21823, QueueBufferDuration=1795469, GpuCompleted=9223372036854775807, SwapBuffersCompleted=2617283674731031, DisplayPresentTime=281479271743489, 
02-03 18:07:35.521 22334 22406 D BluetoothAdapter: isLeEnabled(): ON
02-03 18:07:36.020 22334 22406 D BluetoothAdapter: isLeEnabled(): ON
02-03 18:07:36.028 22334 30669 D BluetoothLeScanner: onScannerRegistered() - status=0 scannerId=5 mScannerId=0
02-03 18:07:43.521 22334 22406 D BluetoothAdapter: isLeEnabled(): ON
02-03 18:07:44.021 22334 22406 D BluetoothAdapter: isLeEnabled(): ON
02-03 18:07:44.029 22334 30669 D BluetoothLeScanner: onScannerRegistered() - status=0 scannerId=5 mScannerId=0
02-03 18:07:51.524 22334 22406 D BluetoothAdapter: isLeEnabled(): ON
02-03 18:07:52.021 22334 22406 D BluetoothAdapter: isLeEnabled(): ON
02-03 18:07:52.031 22334 30669 D BluetoothLeScanner: onScannerRegistered() - status=0 scannerId=5 mScannerId=0
02-03 18:07:52.684 22334 22334 D LocBroadcastReceiver: Received location update.
02-03 18:07:52.688 22334 22334 W LocBroadcastReceiver: Location accuracy didn't meet requirements, disregarding: Location[fused *,* hAcc=2599.999 et=+34d20h52m5s931ms alt=139.4818115234375 vAcc=69.729164]
02-03 18:07:59.524 22334 22406 D BluetoothAdapter: isLeEnabled(): ON
02-03 18:08:00.019 22334 22406 D BluetoothAdapter: isLeEnabled(): ON
02-03 18:08:00.026 22334 30669 D BluetoothLeScanner: onScannerRegistered() - status=0 scannerId=5 mScannerId=0
02-03 18:08:07.525 22334 22406 D BluetoothAdapter: isLeEnabled(): ON
02-03 18:08:08.019 22334 22406 D BluetoothAdapter: isLeEnabled(): ON
02-03 18:08:08.029 22334 30669 D BluetoothLeScanner: onScannerRegistered() - status=0 scannerId=5 mScannerId=0
02-03 18:08:15.523 22334 22406 D BluetoothAdapter: isLeEnabled(): ON
02-03 18:08:16.021 22334 22406 D BluetoothAdapter: isLeEnabled(): ON
02-03 18:08:16.029 22334 30669 D BluetoothLeScanner: onScannerRegistered() - status=0 scannerId=5 mScannerId=0
02-03 18:08:23.526 22334 22406 D BluetoothAdapter: isLeEnabled(): ON
02-03 18:08:24.023 22334 22406 D BluetoothAdapter: isLeEnabled(): ON
02-03 18:08:24.033 22334 30669 D BluetoothLeScanner: onScannerRegistered() - status=0 scannerId=5 mScannerId=0
02-03 18:08:31.522 22334 22406 D BluetoothAdapter: isLeEnabled(): ON
02-03 18:08:32.021 22334 22406 D BluetoothAdapter: isLeEnabled(): ON
02-03 18:08:32.028 22334 30669 D BluetoothLeScanner: onScannerRegistered() - status=0 scannerId=5 mScannerId=0
02-03 18:08:39.526 22334 22406 D BluetoothAdapter: isLeEnabled(): ON
02-03 18:08:40.022 22334 22406 D BluetoothAdapter: isLeEnabled(): ON
02-03 18:08:40.030 22334 30669 D BluetoothLeScanner: onScannerRegistered() - status=0 scannerId=5 mScannerId=0

dshokouhi · February 4, 2024, 6:46pm

depending on the device there may be more than 1 setting, Samsung for example has multiple

brookst · February 8, 2024, 6:11pm

Thanks for the suggestion, I missed the response email but checked now as the problem reoccurred. My phone is a Motorola. The only additional setting I see is “Adaptive Battery”. Unfortunately that cannot be disabled per app so I’ll try leaving it off and see if the problem recurs.

It seems odd that this problem continues even after opening the app. It is only cycling the transmit option that gets it to work again. Is there no way the app could detect that it’s actually failing to transmit?

iebooker · February 10, 2024, 6:31pm

I’m also experiencing some issues with BLE transmitter and the HA companion app.

I have a Pixel 7 Pro. Battery optimization is set to “Unrestricted”, companion app is allowed background access, BLE transmitter settings are screenshot below:

I read in a GitHub thread, to trying changing the transmitter power or speed to get it working again, but this no longer seems to be working.

I’ve tried restarting my phone, dismissing the app, and the above measures of changing BLE transmitter settings, but none of these efforts are working.

My wife has a OnePlus 7T with a pretty aggressive battery management system and it’s working more reliably than my phone. So my suspicion is that it has something to do with more recent versions of Android and permissions.

I also have a Chipolo enabled device that is reliably detected so my configuration for ESPresence is valid.