WorkManager: 2.4.0
Device: Google Pixel 3a (Android 11), Google Pixel 1 (Android 10)

Don’t think the Android version matters here. The device might — we’ve observed that Google Pixel 4 (Android 10) does not suffer from this as often.

The sample application is attached. Steps to reproduce using it:

Run from Android Studio.
Observe system notification.
Results:
- Actual: sometimes notification is not hidden and remains shown forever. Multiple reruns might be required. Happens once per 3—5 attempts for me. Almost never happens on clean install.
- Expected: notification is hidden immediately.

Logs captured using custom configuration (level is set to DEBUG):

Fine behavior (notification is hidden eventually):

2020-09-15 08:37:48.462 7255-7255/com.sample D/WM-PackageManagerHelper: androidx.work.impl.background.systemjob.SystemJobService enabled
2020-09-15 08:37:48.462 7255-7255/com.sample D/WM-Schedulers: Created SystemJobScheduler and enabled SystemJobService
2020-09-15 08:37:48.475 7255-7292/com.sample D/WM-ForceStopRunnable: Performing cleanup operations.
2020-09-15 08:37:48.583 7255-7292/com.sample D/WM-ForceStopRunnable: Application was force-stopped, rescheduling.
2020-09-15 08:37:48.897 7255-7303/com.sample D/WM-PackageManagerHelper: androidx.work.impl.background.systemalarm.RescheduleReceiver enabled
2020-09-15 08:37:48.909 7255-7303/com.sample D/WM-SystemJobScheduler: Scheduling work ID 7aaf9d71-bd63-4ee9-9cef-54e6b8f7a556 Job ID 0
2020-09-15 08:37:48.989 7255-7303/com.sample D/WM-GreedyScheduler: Starting work for 7aaf9d71-bd63-4ee9-9cef-54e6b8f7a556
2020-09-15 08:37:48.996 7255-7306/com.sample D/WM-Processor: Processor: processing 7aaf9d71-bd63-4ee9-9cef-54e6b8f7a556
2020-09-15 08:37:49.005 7255-7255/com.sample D/WM-WorkerWrapper: Starting work for com.sample.WorkApplication$ForegroundWorker
2020-09-15 08:37:49.029 7255-7305/com.sample I/WM-Processor: Moving WorkSpec (7aaf9d71-bd63-4ee9-9cef-54e6b8f7a556) to the foreground
2020-09-15 08:37:49.044 7255-7255/com.sample I/WM-SystemFgDispatcher: Started foreground service Intent { act=ACTION_START_FOREGROUND cmp=com.sample/androidx.work.impl.foreground.SystemForegroundService (has extras) }
2020-09-15 08:37:49.045 7255-7309/com.sample D/WM-Foreground-Worker: I AM DONE!
2020-09-15 08:37:49.046 7255-7255/com.sample D/WM-SystemFgDispatcher: Notifying with (id: 42, workSpecId: 7aaf9d71-bd63-4ee9-9cef-54e6b8f7a556, notificationType: 0)
2020-09-15 08:37:49.046 7255-7306/com.sample D/WM-WorkerWrapper: com.sample.WorkApplication$ForegroundWorker returned a Success {mOutputData=Data {}} result.
2020-09-15 08:37:49.049 7255-7255/com.sample D/WM-SystemFgDispatcher: Notifying with (id: 42, workSpecId: 7aaf9d71-bd63-4ee9-9cef-54e6b8f7a556, notificationType: 0)
2020-09-15 08:37:49.050 7255-7306/com.sample I/WM-WorkerWrapper: Worker result SUCCESS for Work [ id=7aaf9d71-bd63-4ee9-9cef-54e6b8f7a556, tags={ com.sample.WorkApplication$ForegroundWorker } ]
2020-09-15 08:37:49.060 7255-7306/com.sample D/WM-PackageManagerHelper: androidx.work.impl.background.systemalarm.RescheduleReceiver disabled
2020-09-15 08:37:49.060 7255-7306/com.sample D/WM-Processor: No more foreground work. Stopping SystemForegroundService
2020-09-15 08:37:49.060 7255-7255/com.sample I/WM-SystemFgDispatcher: Stopping foreground service
2020-09-15 08:37:49.060 7255-7255/com.sample D/WM-SystemFgService: All commands completed.
2020-09-15 08:37:49.063 7255-7255/com.sample D/WM-Processor: Processor 7aaf9d71-bd63-4ee9-9cef-54e6b8f7a556 executed; reschedule = false
2020-09-15 08:37:49.063 7255-7255/com.sample D/WM-SystemJobService: 7aaf9d71-bd63-4ee9-9cef-54e6b8f7a556 executed on JobScheduler
2020-09-15 08:37:49.077 7255-7306/com.sample D/WM-GreedyScheduler: Cancelling work ID 7aaf9d71-bd63-4ee9-9cef-54e6b8f7a556
2020-09-15 08:37:49.089 7255-7306/com.sample D/WM-Processor: Processor stopping background work 7aaf9d71-bd63-4ee9-9cef-54e6b8f7a556
2020-09-15 08:37:49.089 7255-7306/com.sample D/WM-Processor: WorkerWrapper could not be found for 7aaf9d71-bd63-4ee9-9cef-54e6b8f7a556
2020-09-15 08:37:49.090 7255-7306/com.sample D/WM-StopWorkRunnable: StopWorkRunnable for 7aaf9d71-bd63-4ee9-9cef-54e6b8f7a556; Processor.stopWork = false

Diagnostics:

2020-09-15 08:38:37.896 7255-7255/com.sample D/WM-DiagnosticsRcvr: Requesting diagnostics
2020-09-15 08:38:37.905 7255-7305/com.sample D/WM-PackageManagerHelper: androidx.work.impl.background.systemalarm.RescheduleReceiver enabled
2020-09-15 08:38:37.917 7255-7305/com.sample D/WM-SystemJobScheduler: Scheduling work ID 2daaf311-db13-4f1d-ba96-f7e5af3070a4 Job ID 1
2020-09-15 08:38:37.924 7255-7305/com.sample D/WM-GreedyScheduler: Starting work for 2daaf311-db13-4f1d-ba96-f7e5af3070a4
2020-09-15 08:38:37.928 7255-7305/com.sample D/WM-Processor: Processor: processing 2daaf311-db13-4f1d-ba96-f7e5af3070a4
2020-09-15 08:38:37.931 7255-7255/com.sample D/WM-SystemJobService: onStartJob for 2daaf311-db13-4f1d-ba96-f7e5af3070a4
2020-09-15 08:38:37.940 7255-7305/com.sample D/WM-Processor: Work 2daaf311-db13-4f1d-ba96-f7e5af3070a4 is already enqueued for processing
2020-09-15 08:38:37.941 7255-7255/com.sample D/WM-WorkerWrapper: Starting work for androidx.work.impl.workers.DiagnosticsWorker
2020-09-15 08:38:37.946 7255-7338/com.sample I/WM-DiagnosticsWrkr: Recently completed work:
2020-09-15 08:38:37.949 7255-7338/com.sample I/WM-DiagnosticsWrkr:  Id 	 Class Name	 Job Id	 State	 Unique Name	 Tags	
    7aaf9d71-bd63-4ee9-9cef-54e6b8f7a556	 com.sample.WorkApplication$ForegroundWorker	 null	 SUCCEEDED	 foreground-work	 com.sample.WorkApplication$ForegroundWorker	
2020-09-15 08:38:37.949 7255-7338/com.sample I/WM-DiagnosticsWrkr: Running work:
2020-09-15 08:38:37.951 7255-7338/com.sample I/WM-DiagnosticsWrkr:  Id 	 Class Name	 Job Id	 State	 Unique Name	 Tags	
    2daaf311-db13-4f1d-ba96-f7e5af3070a4	 androidx.work.impl.workers.DiagnosticsWorker	 1	 RUNNING	 	 androidx.work.impl.workers.DiagnosticsWorker	
2020-09-15 08:38:37.952 7255-7306/com.sample D/WM-WorkerWrapper: androidx.work.impl.workers.DiagnosticsWorker returned a Success {mOutputData=Data {}} result.
2020-09-15 08:38:37.953 7255-7306/com.sample I/WM-WorkerWrapper: Worker result SUCCESS for Work [ id=2daaf311-db13-4f1d-ba96-f7e5af3070a4, tags={ androidx.work.impl.workers.DiagnosticsWorker } ]
2020-09-15 08:38:37.956 7255-7306/com.sample D/WM-PackageManagerHelper: androidx.work.impl.background.systemalarm.RescheduleReceiver disabled
2020-09-15 08:38:37.957 7255-7255/com.sample D/WM-Processor: Processor 2daaf311-db13-4f1d-ba96-f7e5af3070a4 executed; reschedule = false
2020-09-15 08:38:37.957 7255-7255/com.sample D/WM-SystemJobService: 2daaf311-db13-4f1d-ba96-f7e5af3070a4 executed on JobScheduler
2020-09-15 08:38:37.959 7255-7306/com.sample D/WM-GreedyScheduler: Cancelling work ID 2daaf311-db13-4f1d-ba96-f7e5af3070a4
2020-09-15 08:38:37.961 7255-7255/com.sample D/WM-SystemJobService: onStopJob for 2daaf311-db13-4f1d-ba96-f7e5af3070a4
2020-09-15 08:38:37.963 7255-7306/com.sample D/WM-Processor: Processor stopping background work 2daaf311-db13-4f1d-ba96-f7e5af3070a4
2020-09-15 08:38:37.964 7255-7306/com.sample D/WM-Processor: WorkerWrapper could not be found for 2daaf311-db13-4f1d-ba96-f7e5af3070a4
2020-09-15 08:38:37.964 7255-7306/com.sample D/WM-StopWorkRunnable: StopWorkRunnable for 2daaf311-db13-4f1d-ba96-f7e5af3070a4; Processor.stopWork = false
2020-09-15 08:38:37.965 7255-7305/com.sample D/WM-Processor: Processor stopping background work 2daaf311-db13-4f1d-ba96-f7e5af3070a4
2020-09-15 08:38:37.965 7255-7305/com.sample D/WM-Processor: WorkerWrapper could not be found for 2daaf311-db13-4f1d-ba96-f7e5af3070a4
2020-09-15 08:38:37.965 7255-7305/com.sample D/WM-StopWorkRunnable: StopWorkRunnable for 2daaf311-db13-4f1d-ba96-f7e5af3070a4; Processor.stopWork = false

Issue behavior (notification is not hidden):

2020-09-15 08:42:01.792 8542-8542/com.sample D/WM-PackageManagerHelper: androidx.work.impl.background.systemjob.SystemJobService enabled
2020-09-15 08:42:01.793 8542-8542/com.sample D/WM-Schedulers: Created SystemJobScheduler and enabled SystemJobService
2020-09-15 08:42:01.800 8542-8564/com.sample D/WM-ForceStopRunnable: Performing cleanup operations.
2020-09-15 08:42:01.846 8542-8568/com.sample D/WM-PackageManagerHelper: androidx.work.impl.background.systemalarm.RescheduleReceiver enabled
2020-09-15 08:42:01.859 8542-8568/com.sample D/WM-SystemJobScheduler: Scheduling work ID 543e6b41-9099-42d0-9330-1be877dfed5c Job ID 2
2020-09-15 08:42:01.864 8542-8568/com.sample D/WM-GreedyScheduler: Starting work for 543e6b41-9099-42d0-9330-1be877dfed5c
2020-09-15 08:42:01.871 8542-8570/com.sample D/WM-Processor: Processor: processing 543e6b41-9099-42d0-9330-1be877dfed5c
2020-09-15 08:42:01.932 8542-8542/com.sample D/WM-WorkerWrapper: Starting work for com.sample.WorkApplication$ForegroundWorker
2020-09-15 08:42:01.953 8542-8542/com.sample D/WM-SystemJobService: onStartJob for 543e6b41-9099-42d0-9330-1be877dfed5c
2020-09-15 08:42:01.954 8542-8569/com.sample D/WM-Processor: Work 543e6b41-9099-42d0-9330-1be877dfed5c is already enqueued for processing
2020-09-15 08:42:01.956 8542-8569/com.sample I/WM-Processor: Moving WorkSpec (543e6b41-9099-42d0-9330-1be877dfed5c) to the foreground
2020-09-15 08:42:01.966 8542-8573/com.sample D/WM-Foreground-Worker: I AM DONE!
2020-09-15 08:42:01.967 8542-8570/com.sample D/WM-WorkerWrapper: com.sample.WorkApplication$ForegroundWorker returned a Success {mOutputData=Data {}} result.
2020-09-15 08:42:01.968 8542-8570/com.sample I/WM-WorkerWrapper: Worker result SUCCESS for Work [ id=543e6b41-9099-42d0-9330-1be877dfed5c, tags={ com.sample.WorkApplication$ForegroundWorker } ]
2020-09-15 08:42:01.974 8542-8570/com.sample D/WM-PackageManagerHelper: androidx.work.impl.background.systemalarm.RescheduleReceiver disabled
2020-09-15 08:42:01.974 8542-8570/com.sample D/WM-Processor: No more foreground work. Stopping SystemForegroundService
2020-09-15 08:42:01.976 8542-8542/com.sample I/WM-SystemFgDispatcher: Started foreground service Intent { act=ACTION_START_FOREGROUND cmp=com.sample/androidx.work.impl.foreground.SystemForegroundService (has extras) }
2020-09-15 08:42:01.977 8542-8542/com.sample D/WM-SystemFgDispatcher: Notifying with (id: 42, workSpecId: 543e6b41-9099-42d0-9330-1be877dfed5c, notificationType: 0)
2020-09-15 08:42:01.979 8542-8542/com.sample D/WM-SystemFgDispatcher: Notifying with (id: 42, workSpecId: 543e6b41-9099-42d0-9330-1be877dfed5c, notificationType: 0)
2020-09-15 08:42:01.980 8542-8570/com.sample D/WM-GreedyScheduler: Cancelling work ID 543e6b41-9099-42d0-9330-1be877dfed5c
2020-09-15 08:42:01.981 8542-8542/com.sample I/WM-SystemFgDispatcher: Stopping foreground service
2020-09-15 08:42:01.981 8542-8542/com.sample D/WM-SystemFgService: All commands completed.
2020-09-15 08:42:01.982 8542-8542/com.sample D/WM-Processor: Processor 543e6b41-9099-42d0-9330-1be877dfed5c executed; reschedule = false
2020-09-15 08:42:01.982 8542-8542/com.sample D/WM-SystemJobService: 543e6b41-9099-42d0-9330-1be877dfed5c executed on JobScheduler
2020-09-15 08:42:01.983 8542-8542/com.sample D/WM-SystemJobService: onStopJob for 543e6b41-9099-42d0-9330-1be877dfed5c
2020-09-15 08:42:01.990 8542-8564/com.sample D/WM-Processor: Processor stopping background work 543e6b41-9099-42d0-9330-1be877dfed5c
2020-09-15 08:42:01.990 8542-8564/com.sample D/WM-Processor: WorkerWrapper could not be found for 543e6b41-9099-42d0-9330-1be877dfed5c
2020-09-15 08:42:01.990 8542-8564/com.sample D/WM-StopWorkRunnable: StopWorkRunnable for 543e6b41-9099-42d0-9330-1be877dfed5c; Processor.stopWork = false
2020-09-15 08:42:01.992 8542-8564/com.sample D/WM-Processor: Processor stopping background work 543e6b41-9099-42d0-9330-1be877dfed5c
2020-09-15 08:42:01.992 8542-8564/com.sample D/WM-Processor: WorkerWrapper could not be found for 543e6b41-9099-42d0-9330-1be877dfed5c
2020-09-15 08:42:01.992 8542-8564/com.sample D/WM-StopWorkRunnable: StopWorkRunnable for 543e6b41-9099-42d0-9330-1be877dfed5c; Processor.stopWork = false

Diagnostics:

2020-09-15 08:42:46.313 8542-8542/com.sample D/WM-DiagnosticsRcvr: Requesting diagnostics
2020-09-15 08:42:46.325 8542-8568/com.sample D/WM-PackageManagerHelper: androidx.work.impl.background.systemalarm.RescheduleReceiver enabled
2020-09-15 08:42:46.337 8542-8568/com.sample D/WM-SystemJobScheduler: Scheduling work ID a13fe29d-e9cf-4444-ba4d-98c7d31e3308 Job ID 3
2020-09-15 08:42:46.351 8542-8542/com.sample D/WM-SystemJobService: onStartJob for a13fe29d-e9cf-4444-ba4d-98c7d31e3308
2020-09-15 08:42:46.357 8542-8568/com.sample D/WM-GreedyScheduler: Starting work for a13fe29d-e9cf-4444-ba4d-98c7d31e3308
2020-09-15 08:42:46.361 8542-8570/com.sample D/WM-Processor: Processor: processing a13fe29d-e9cf-4444-ba4d-98c7d31e3308
2020-09-15 08:42:46.361 8542-8570/com.sample D/WM-Processor: Work a13fe29d-e9cf-4444-ba4d-98c7d31e3308 is already enqueued for processing
2020-09-15 08:42:46.372 8542-8542/com.sample D/WM-WorkerWrapper: Starting work for androidx.work.impl.workers.DiagnosticsWorker
2020-09-15 08:42:46.387 8542-8601/com.sample I/WM-DiagnosticsWrkr: Recently completed work:
2020-09-15 08:42:46.397 8542-8601/com.sample I/WM-DiagnosticsWrkr:  Id 	 Class Name	 Job Id	 State	 Unique Name	 Tags	
    543e6b41-9099-42d0-9330-1be877dfed5c	 com.sample.WorkApplication$ForegroundWorker	 null	 SUCCEEDED	 foreground-work	 com.sample.WorkApplication$ForegroundWorker	
2020-09-15 08:42:46.397 8542-8601/com.sample I/WM-DiagnosticsWrkr: Running work:
2020-09-15 08:42:46.401 8542-8601/com.sample I/WM-DiagnosticsWrkr:  Id 	 Class Name	 Job Id	 State	 Unique Name	 Tags	
    a13fe29d-e9cf-4444-ba4d-98c7d31e3308	 androidx.work.impl.workers.DiagnosticsWorker	 3	 RUNNING	 	 androidx.work.impl.workers.DiagnosticsWorker	
2020-09-15 08:42:46.403 8542-8564/com.sample D/WM-WorkerWrapper: androidx.work.impl.workers.DiagnosticsWorker returned a Success {mOutputData=Data {}} result.
2020-09-15 08:42:46.405 8542-8564/com.sample I/WM-WorkerWrapper: Worker result SUCCESS for Work [ id=a13fe29d-e9cf-4444-ba4d-98c7d31e3308, tags={ androidx.work.impl.workers.DiagnosticsWorker } ]
2020-09-15 08:42:46.410 8542-8564/com.sample D/WM-PackageManagerHelper: androidx.work.impl.background.systemalarm.RescheduleReceiver disabled
2020-09-15 08:42:46.412 8542-8542/com.sample D/WM-Processor: Processor a13fe29d-e9cf-4444-ba4d-98c7d31e3308 executed; reschedule = false
2020-09-15 08:42:46.412 8542-8542/com.sample D/WM-SystemJobService: a13fe29d-e9cf-4444-ba4d-98c7d31e3308 executed on JobScheduler
2020-09-15 08:42:46.419 8542-8564/com.sample D/WM-GreedyScheduler: Cancelling work ID a13fe29d-e9cf-4444-ba4d-98c7d31e3308
2020-09-15 08:42:46.428 8542-8564/com.sample D/WM-Processor: Processor stopping background work a13fe29d-e9cf-4444-ba4d-98c7d31e3308
2020-09-15 08:42:46.428 8542-8564/com.sample D/WM-Processor: WorkerWrapper could not be found for a13fe29d-e9cf-4444-ba4d-98c7d31e3308
2020-09-15 08:42:46.429 8542-8564/com.sample D/WM-StopWorkRunnable: StopWorkRunnable for a13fe29d-e9cf-4444-ba4d-98c7d31e3308; Processor.stopWork = false

Issue 168502234

Mentioned issues (2)

Links (7)

Issue metadata