Actionable notification works intermittently

guilly · September 5, 2021, 12:00pm

Hi,

I’ve set up an actionable notification when I detect motion which includes a snapshot from BI. It works great, however I noticed that after some time I stop receiving the picture in the notification. If I open the mobile app I start receiving the picture again but after a few hours it stops.

Has anyone seen this behavior before ? I’ve confirmed the snapshot is stored every time so it’s definitely the mobile app not retrieving it…

dshokouhi · September 5, 2021, 2:36pm

Look at the app configuration logs it will show the notification details to see what happened.

guilly · September 6, 2021, 12:04pm

Do you mean the standard logs in HA under configuration ? If so, there’s no log entries when I get a notification without the picture.

dshokouhi · September 6, 2021, 4:07pm

no in the app, go to app configuration and there is a logs section there…make sure to reproduce the issue before you look at the device logs.

guilly · September 8, 2021, 11:42am

Thanks, I took a look but I don’t see any errors thrown. It’s clear that the notification isn’t the same though. 07:52:00 is the initial trigger that did not work. 07:55:17 is the one that worked.

I’m seeing a lot of certificate chaining issues (I use an external cert) Is it possible my android device isn’t trusting the certificate ? Not sure if this could account for the different behavior when the app is opened or not ?

09-07 07:52:00.125 32179 12802 D UrlRepository: localUrl is: false and usesInternalSsid is: false
09-07 07:52:00.195 32179 12785 I WM-WorkerWrapper: Worker result SUCCESS for Work [ id=201c0359-ce9c-45b2-88bd-8670a7ad617c, tags={ io.homeassistant.companion.android.sensors.SensorWorker } ]
09-07 07:52:00.197 32179 32179 I WM-SystemFgDispatcher: Stopping foreground service
09-07 07:52:00.203 32179 32179 D ConnectivityManager: unregisterNetworkCallback; CallingUid : 10343, CallingPid : 32179
09-07 07:52:00.327 32179 32179 I SurfaceView: onWindowVisibilityChanged(8) false android.view.SurfaceView{2ffc3bf G.E...... ......I. 0,0-0,0} of ViewRootImpl@eeda07e[WebViewActivity]
09-07 07:52:00.333 32179 32179 I ViewRootImpl@eeda07e[WebViewActivity]: Relayout returned: old=(0,0,1080,2220) new=(0,0,1080,2220) req=(1080,2220)8 dur=4 res=0x5 s={false 0} ch=true
09-07 07:52:00.338 32179 32179 I ViewRootImpl@eeda07e[WebViewActivity]: stopped(true) old=false
09-07 07:52:00.338 32179 32179 I SurfaceView: windowStopped(true) false android.view.SurfaceView{2ffc3bf G.E...... ......I. 0,0-0,0} of ViewRootImpl@eeda07e[WebViewActivity]
09-07 07:52:00.353 32179 32179 I ViewRootImpl@eeda07e[WebViewActivity]: Relayout returned: old=(0,0,1080,2220) new=(0,0,1080,2220) req=(1080,2220)8 dur=4 res=0x5 s={false 0} ch=false
09-07 07:52:00.673 32179 32179 I ViewRootImpl@f3cf573[SettingsActivity]: ViewPostIme pointer 0
09-07 07:52:00.840 32179 32179 I ViewRootImpl@f3cf573[SettingsActivity]: ViewPostIme pointer 1
09-07 07:52:01.342 32179 32179 I ViewRootImpl@f3cf573[SettingsActivity]: ViewPostIme pointer 0
09-07 07:52:01.468 32179 32179 I ViewRootImpl@f3cf573[SettingsActivity]: ViewPostIme pointer 1
09-07 07:52:05.149 32179 32179 I ViewRootImpl@f3cf573[SettingsActivity]: ViewPostIme pointer 0
09-07 07:52:05.280 32179 32179 I ViewRootImpl@f3cf573[SettingsActivity]: ViewPostIme pointer 1
09-07 07:52:10.937 32179 32179 I ViewRootImpl@f3cf573[SettingsActivity]: ViewPostIme pointer 0
09-07 07:52:11.078 32179 32179 I ViewRootImpl@f3cf573[SettingsActivity]: ViewPostIme pointer 1
09-07 07:52:12.914 32179 32179 I ViewRootImpl@f3cf573[SettingsActivity]: ViewPostIme pointer 0
09-07 07:52:13.017 32179 32179 I ViewRootImpl@f3cf573[SettingsActivity]: ViewPostIme pointer 1
09-07 07:52:13.044 32179 32179 D ScrollView: initGoToTop
09-07 07:52:13.078 32179 32179 I ViewRootImpl@f3cf573[SettingsActivity]: Relayout returned: old=(0,0,1080,2220) new=(0,0,1080,2220) req=(1080,2220)0 dur=7 res=0x1 s={true 532384907264} ch=false
09-07 07:52:13.083 32179 13013 D LogcatReader: Read logcat for pid 32179
09-07 07:52:13.197 32179 13013 D LogcatReader: Done reading logcat for pid 32179
09-07 07:52:13.320 32179 32179 D ScrollView:  onsize change changed 
09-07 07:52:13.405 32179 32179 D InputMethodManager: prepareNavigationBarInfo() DecorView@57a94a5[SettingsActivity]
09-07 07:52:13.405 32179 32179 D InputMethodManager: getNavigationBarColor() -15658735
09-07 07:52:13.405 32179 32179 V InputMethodManager: Starting input: tba=io.homeassistant.companion.android ic=null mNaviBarColor -15658735 mIsGetNaviBarColorSuccess true , NavVisible : true , NavTrans : false
09-07 07:52:13.405 32179 32179 D InputMethodManager: startInputInner - Id : 0
09-07 07:52:13.405 32179 32179 I InputMethodManager: startInputInner - mService.startInputOrWindowGainedFocus
09-07 07:52:13.407 32179 32179 D InputTransport: Input channel destroyed: 'ClientS', fd=305
09-07 07:52:16.543 32179 32179 I ViewRootImpl@f3cf573[SettingsActivity]: ViewPostIme pointer 0
09-07 07:52:16.948 32179 32179 I ViewRootImpl@f3cf573[SettingsActivity]: ViewPostIme pointer 1
09-07 07:52:20.502 32179 32179 I ViewRootImpl@f3cf573[SettingsActivity]: ViewPostIme pointer 0
09-07 07:52:21.130 32179 32179 I ViewRootImpl@f3cf573[SettingsActivity]: ViewPostIme pointer 1
09-07 07:52:24.800 32179 32179 I ViewRootImpl@f3cf573[SettingsActivity]: ViewPostIme pointer 0
09-07 07:52:24.994 32179 32179 I ViewRootImpl@f3cf573[SettingsActivity]: ViewPostIme pointer 1
09-07 07:52:25.960 32179 32179 I ViewRootImpl@f3cf573[SettingsActivity]: ViewPostIme pointer 0
09-07 07:52:26.122 32179 32179 I ViewRootImpl@f3cf573[SettingsActivity]: ViewPostIme pointer 1
09-07 07:52:27.058 32179 32179 I ViewRootImpl@f3cf573[SettingsActivity]: ViewPostIme pointer 0
09-07 07:52:27.190 32179 32179 I ViewRootImpl@f3cf573[SettingsActivity]: ViewPostIme pointer 1
09-07 07:52:29.918 32179 32179 I ViewRootImpl@f3cf573[SettingsActivity]: ViewPostIme pointer 0
09-07 07:52:30.085 32179 32179 I ViewRootImpl@f3cf573[SettingsActivity]: ViewPostIme pointer 1
09-07 07:52:30.616 32179 32179 I ViewRootImpl@f3cf573[SettingsActivity]: ViewPostIme pointer 0
09-07 07:52:30.779 32179 32179 I ViewRootImpl@f3cf573[SettingsActivity]: ViewPostIme pointer 1
09-07 07:52:32.224 32179 32179 I ViewRootImpl@f3cf573[SettingsActivity]: ViewPostIme pointer 0
09-07 07:52:32.382 32179 32179 I ViewRootImpl@f3cf573[SettingsActivity]: ViewPostIme pointer 1
09-07 07:52:32.857 32179 32179 I ViewRootImpl@f3cf573[SettingsActivity]: ViewPostIme pointer 0
09-07 07:52:33.006 32179 32179 I ViewRootImpl@f3cf573[SettingsActivity]: ViewPostIme pointer 1
09-07 07:52:34.279 32179 32179 I ViewRootImpl@f3cf573[SettingsActivity]: ViewPostIme pointer 0
09-07 07:52:34.418 32179 32179 I ViewRootImpl@f3cf573[SettingsActivity]: ViewPostIme pointer 1
09-07 07:52:34.855 32179 32179 I ViewRootImpl@f3cf573[SettingsActivity]: ViewPostIme pointer 0
09-07 07:52:35.003 32179 32179 I ViewRootImpl@f3cf573[SettingsActivity]: ViewPostIme pointer 1
09-07 07:52:40.150 32179 32179 I ViewRootImpl@f3cf573[SettingsActivity]: ViewPostIme pointer 0
09-07 07:52:40.175 32179 32179 I ViewRootImpl@f3cf573[SettingsActivity]: ViewPostIme pointer 1
09-07 07:52:40.185 32179 32179 D LogFragment: Click on share logs without WRITE_EXTERNAL_STORAGE permission

09-07 07:55:17.034 32179 13013 D UrlRepository: localUrl is: false and usesInternalSsid is: false
09-07 07:55:17.127 32179 13013 D LocBroadcastReceiver: Location update sent successfully
09-07 07:55:38.361 32179 13013 D NextAlarm: No alarm is scheduled, sending unavailable
09-07 07:55:38.431 32179 13013 D UrlRepository: localUrl is: false and usesInternalSsid is: false
09-07 07:56:32.554 32179 16039 D MessagingService: From: 331041709873
09-07 07:56:32.555 32179 16039 D MessagingService: Message data payload: {image=https://bulldog.iwearbibs.com:8123/local/snapshot_doorbell.jpg, title=ALERT, message=Front Door Alert, action_1_title=Open Video, action_1_key=URI, action_1_uri=/lovelace/video}
09-07 07:56:32.571 32179 32179 D MessagingService: Creating notification with following data: {image=https://bulldog.iwearbibs.com:8123/local/snapshot_doorbell.jpg, title=ALERT, message=Front Door Alert, action_1_title=Open Video, action_1_key=URI, action_1_uri=/lovelace/video}
09-07 07:56:32.617 32179 32179 D UrlRepository: localUrl is: false and usesInternalSsid is: false
09-07 07:56:32.617 32179 32179 D UrlRepository: Using external URL
09-07 07:56:32.624 32179 13013 I System.out: (HTTPLog)-Static: isSBSettingEnabled false
09-07 07:56:32.624 32179 13013 I System.out: (HTTPLog)-Static: isSBSettingEnabled false
09-07 07:56:32.764 32179 32179 D MessagingService: Show notification with tag "null" and id "-1071779904"
09-07 07:56:48.408 32179 16039 D MessagingService: From: 331041709873
09-07 07:56:48.408 32179 16039 D MessagingService: Message data payload: {image=https://bulldog.iwearbibs.com:8123/local/snapshot_doorbell.jpg, title=ALERT, message=Front Door Alert, action_1_title=Open Video, action_1_key=URI, action_1_uri=/lovelace/video}
09-07 07:56:48.411 32179 32179 D MessagingService: Creating notification with following data: {image=https://bulldog.iwearbibs.com:8123/local/snapshot_doorbell.jpg, title=ALERT, message=Front Door Alert, action_1_title=Open Video, action_1_key=URI, action_1_uri=/lovelace/video}
09-07 07:56:48.430 32179 32179 D UrlRepository: localUrl is: false and usesInternalSsid is: false
09-07 07:56:48.430 32179 32179 D UrlRepository: Using external URL
09-07 07:56:48.432 32179 13013 I System.out: (HTTPLog)-Static: isSBSettingEnabled false
09-07 07:56:48.432 32179 13013 I System.out: (HTTPLog)-Static: isSBSettingEnabled false
09-07 07:56:48.479 32179 32179 D MessagingService: Show notification with tag "null" and id "-1071764069"
09-07 07:56:57.751 32179 32179 D SensorReceiver: Sensor is_interactive corresponding to received event android.intent.action.SCREEN_ON is disabled, skipping sensors update

dshokouhi · September 8, 2021, 3:28pm

according to the logs there was no notification received, I only see 2 notifications one at 7:56:32 and 7:56:48

if it was a cert issue then you wouldnt be able to access HA using the app.

If you had encountered an error I would expect to see an error similar to this line

github.com

home-assistant/android/blob/1f50d296ab450b01094c35a45a1b3bada91e3888/app/src/full/java/io/homeassistant/companion/android/notifications/MessagingService.kt#L973


      
                      return@withContext null
          
                  var image: Bitmap? = null
                  try {
                      val uc = url.openConnection()
                      if (requiresAuth) {
                          uc.setRequestProperty("Authorization", authenticationUseCase.buildBearerToken())
                      }
                      image = BitmapFactory.decodeStream(uc.getInputStream())
                  } catch (e: Exception) {
                      Log.e(TAG, "Couldn't download image for notification", e)
                  }
                  return@withContext image
              }
          
          private fun handleActions(
              builder: NotificationCompat.Builder,
              tag: String?,
              messageId: Int,
              data: Map<String, String>
          ) {

With that said I have seen some users on certain android devices who found an issue showing an image alongside actionable notification, I believe it was a MIUI device and the issue was resolved via a system update on that device.

guilly · September 10, 2021, 11:07am

Thanks,

I ran another test and it’s really strange I get a notification just not the image. But like you said in the logs I see zero entry that indicates a message was sent. I suspect I should be seeing something

I do see a bunch of untrusted chain errors whenever I get an unsuccessful message. My cert is about to roll over so I think I’m going to fix those first. Is it possible the app or android o/s does not have the proper trusted CA cert for my certification signing authority ? Should I issue a new cert from a different 3rd party CA ?

09-09 09:12:07.878 10297  8892 E CONSCRYPT: ------------------Untrusted chain: ----------------------
09-09 09:12:07.878 10297  8892 E CONSCRYPT: == Chain0 == 
09-09 09:12:07.878 10297  8892 E CONSCRYPT:  Version:   3
09-09 09:12:07.878 10297  8892 E CONSCRYPT:  AuthorityKeyIdentifier:   418301680148d8c5ec454ad8ae177e99bf99b05e1b8018d61e1
09-09 09:12:07.878 10297  8892 E CONSCRYPT:  SubjectKeyIdentifier:   4160414298a031662faf7da4c1bce2c5361cdc64fd2be3c
09-09 09:12:07.878 10297  8892 E CONSCRYPT:  Serial Number:   ec58074d80cbd6ff15e0fd608eda81cb
09-09 09:12:07.880 10297  8892 E CONSCRYPT:  SubjectDN:   CN=bulldog.iwearbibs.com
09-09 09:12:07.880 10297  8892 E CONSCRYPT:  IssuerDN:   CN=Sectigo RSA Domain Validation Secure Server CA, O=Sectigo Limited, L=Salford, ST=Greater Manchester, C=GB
09-09 09:12:07.896 10297  8892 E CONSCRYPT:  Get not before:   Sun Oct 04 20:00:00 EDT 2020
09-09 09:12:07.896 10297  8892 E CONSCRYPT:  Get not after:   Tue Oct 05 19:59:59 EDT 2021
09-09 09:12:07.896 10297  8892 E CONSCRYPT:  Sig ALG name:   SHA256withRSA

dshokouhi · September 10, 2021, 4:31pm

yea android can be picky about that, try a more popular known service maybe?

guilly · December 19, 2021, 12:21pm

Ended up generating a cert using Let’s Encrypt. Seems to have done the trick.