search

AltBeacon / android-beacon-library

Allows Android apps to interact with BLE beacons
Apache License 2.0
2.84k stars 836 forks source link

Not allowed to start service Intent: BeaconIntentProcessor #1028

Closed romaomb closed 3 years ago

romaomb commented 3 years ago

Expected behavior

App should receive didEnterRegion when waking up in background.

Actual behavior

As soon as the process starts, the app throws an exception on Callback.java:61: 

Failed attempting to start service: org.altbeacon.beaconreference/org.altbeacon.beacon.BeaconIntentProcessor

After building the library locally, checking out 2.18 tag, here's the stacktrace:

2021-05-12 03:17:57.806 5919-5949/org.altbeacon.beaconreference E/AndroidRuntime: FATAL EXCEPTION: pool-3-thread-2
    Process: org.altbeacon.beaconreference, PID: 5919
    java.lang.RuntimeException: An error occurred while executing doInBackground()
        at android.os.AsyncTask$4.done(AsyncTask.java:415)
        at java.util.concurrent.FutureTask.finishCompletion(FutureTask.java:383)
        at java.util.concurrent.FutureTask.setException(FutureTask.java:252)
        at java.util.concurrent.FutureTask.run(FutureTask.java:271)
        at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1167)
        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:641)
        at java.lang.Thread.run(Thread.java:923)
     Caused by: java.lang.IllegalStateException: Not allowed to start service Intent { cmp=org.altbeacon.beaconreference/org.altbeacon.beacon.BeaconIntentProcessor (has extras) }: app is in background uid UidRecord{48b9abe u0a909 TRNB idle procs:1 seq(0,0,0)}
        at android.app.ContextImpl.startServiceCommon(ContextImpl.java:1791)
        at android.app.ContextImpl.startService(ContextImpl.java:1736)
        at android.content.ContextWrapper.startService(ContextWrapper.java:738)
        at org.altbeacon.beacon.service.Callback.call(Callback.java:60)
        at org.altbeacon.beacon.service.MonitoringStatus.updateNewlyInsideInRegionsContaining(MonitoringStatus.java:124)
        at org.altbeacon.beacon.service.ScanHelper.processBeaconFromScan(ScanHelper.java:343)
        at org.altbeacon.beacon.service.ScanHelper.access$200(ScanHelper.java:62)
        at org.altbeacon.beacon.service.ScanHelper$ScanProcessor.doInBackground(ScanHelper.java:420)
        at org.altbeacon.beacon.service.ScanHelper$ScanProcessor.doInBackground(ScanHelper.java:386)
        at android.os.AsyncTask$3.call(AsyncTask.java:394)
        at java.util.concurrent.FutureTask.run(FutureTask.java:266)
        at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1167) 
        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:641) 
        at java.lang.Thread.run(Thread.java:923) 
2021-05-12 03:17:57.816 5919-5949/org.altbeacon.beaconreference I/Process: Sending signal. PID: 5919 SIG: 9

Full log:

Click to expand ``` 2021-05-12 03:17:57.257 5919-5919/? I/beaconreferenc: Late-enabling -Xcheck:jni 2021-05-12 03:17:57.290 5919-5919/? I/beaconreferenc: Unquickening 20 vdex files! 2021-05-12 03:17:57.302 5919-5919/? D/ActivityThread: setConscryptValidator 2021-05-12 03:17:57.302 5919-5919/? D/ActivityThread: setConscryptValidator - put 2021-05-12 03:17:57.402 5919-5919/org.altbeacon.beaconreference D/NetworkSecurityConfig: No Network Security Config specified, using platform default 2021-05-12 03:17:57.441 5919-5919/org.altbeacon.beaconreference D/NetworkSecurityConfig: No Network Security Config specified, using platform default 2021-05-12 03:17:57.452 5919-5919/org.altbeacon.beaconreference I/BeaconManager: BeaconManager started up on pid 5919 named 'org.altbeacon.beaconreference' for application package 'org.altbeacon.beaconreference'. isMainProcess=true 2021-05-12 03:17:57.457 5919-5919/org.altbeacon.beaconreference D/BeaconParser: Parsing beacon layout: m:2-3=beac,i:4-19,i:20-21,i:22-23,p:24-24,d:25-25 2021-05-12 03:17:57.460 5919-5919/org.altbeacon.beaconreference D/BeaconParser: Parsing beacon layout: m:2-3=0215,i:4-19,i:20-21,i:22-23,p:24-24 2021-05-12 03:17:57.474 5919-5919/org.altbeacon.beaconreference I/BluetoothTestJob: Using BluetoothTestJob JobId from manifest: 1799803768 2021-05-12 03:17:57.501 5919-5919/org.altbeacon.beaconreference W/BeaconManager: The BeaconManager is not bound to the service. Call beaconManager.bind(BeaconConsumer consumer) and wait for a callback to onBeaconServiceConnect() 2021-05-12 03:17:57.613 5919-5919/org.altbeacon.beaconreference I/ScanJob: Using immediateScanJobId from manifest: 208352939 2021-05-12 03:17:57.618 5919-5919/org.altbeacon.beaconreference I/ScanJob: Using periodicScanJobId from manifest: 208352940 2021-05-12 03:17:57.619 5919-5919/org.altbeacon.beaconreference W/JobInfo: Requested interval +5m10s0ms for job 208352940 is too small; raising to +15m0s0ms 2021-05-12 03:17:57.619 5919-5919/org.altbeacon.beaconreference W/JobInfo: Requested flex 0 for job 208352940 is too small; raising to +5m0s0ms 2021-05-12 03:17:57.638 5919-5919/org.altbeacon.beaconreference D/BeaconReference: didDetermineStateForRegion | state: 0 region: id1: null id2: null id3: null 2021-05-12 03:17:57.679 5919-5919/org.altbeacon.beaconreference I/ScanJob: Using immediateScanJobId from manifest: 208352939 2021-05-12 03:17:57.684 5919-5919/org.altbeacon.beaconreference I/ScanJob: Using periodicScanJobId from manifest: 208352940 2021-05-12 03:17:57.684 5919-5919/org.altbeacon.beaconreference W/JobInfo: Requested interval +5m10s0ms for job 208352940 is too small; raising to +15m0s0ms 2021-05-12 03:17:57.684 5919-5919/org.altbeacon.beaconreference W/JobInfo: Requested flex 0 for job 208352940 is too small; raising to +5m0s0ms 2021-05-12 03:17:57.727 5919-5943/org.altbeacon.beaconreference I/BluetoothTestJob: Bluetooth Test Job running 2021-05-12 03:17:57.727 5919-5943/org.altbeacon.beaconreference I/BluetoothMedic: Starting scan test 2021-05-12 03:17:57.731 5919-5943/org.altbeacon.beaconreference D/BluetoothAdapter: STATE_ON 2021-05-12 03:17:57.732 5919-5943/org.altbeacon.beaconreference D/BluetoothAdapter: STATE_ON 2021-05-12 03:17:57.736 5919-5943/org.altbeacon.beaconreference D/BluetoothLeScanner: Start Scan with callback 2021-05-12 03:17:57.740 5919-5939/org.altbeacon.beaconreference D/BluetoothLeScanner: onScannerRegistered() - status=0 scannerId=19 mScannerId=0 2021-05-12 03:17:57.762 5919-5919/org.altbeacon.beaconreference I/BluetoothMedic: Scan test succeeded 2021-05-12 03:17:57.762 5919-5919/org.altbeacon.beaconreference D/BluetoothAdapter: STATE_ON 2021-05-12 03:17:57.762 5919-5919/org.altbeacon.beaconreference D/BluetoothLeScanner: Stop Scan with callback 2021-05-12 03:17:57.782 5919-5946/org.altbeacon.beaconreference I/CycledLeScanner: Using Android O scanner 2021-05-12 03:17:57.788 5919-5946/org.altbeacon.beaconreference I/ScanJob: Using immediateScanJobId from manifest: 208352939 2021-05-12 03:17:57.788 5919-5946/org.altbeacon.beaconreference I/ScanJob: Running immediate scan job: instance is org.altbeacon.beacon.service.ScanJob@d149140 2021-05-12 03:17:57.791 5919-5946/org.altbeacon.beaconreference I/ScanJob: scanJob version 2.18 is starting up on the main process 2021-05-12 03:17:57.795 5919-5946/org.altbeacon.beaconreference W/ModelSpecificDistanceCalculator: Cannot find match for this device. Using default 2021-05-12 03:17:57.796 5919-5946/org.altbeacon.beaconreference W/ModelSpecificDistanceCalculator: Cannot find match for this device. Using default 2021-05-12 03:17:57.796 5919-5946/org.altbeacon.beaconreference D/BluetoothAdapter: STATE_ON 2021-05-12 03:17:57.796 5919-5950/org.altbeacon.beaconreference I/ScanHelper: Non-distinct packets detected in a single scan. Restarting scans unecessary. 2021-05-12 03:17:57.798 5919-5946/org.altbeacon.beaconreference D/BluetoothAdapter: STATE_ON 2021-05-12 03:17:57.799 5919-5946/org.altbeacon.beaconreference D/BluetoothLeScanner: Stop Scan with callback intent 2021-05-12 03:17:57.806 5919-5949/org.altbeacon.beaconreference E/AndroidRuntime: FATAL EXCEPTION: pool-3-thread-2 Process: org.altbeacon.beaconreference, PID: 5919 java.lang.RuntimeException: An error occurred while executing doInBackground() at android.os.AsyncTask$4.done(AsyncTask.java:415) at java.util.concurrent.FutureTask.finishCompletion(FutureTask.java:383) at java.util.concurrent.FutureTask.setException(FutureTask.java:252) at java.util.concurrent.FutureTask.run(FutureTask.java:271) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1167) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:641) at java.lang.Thread.run(Thread.java:923) Caused by: java.lang.IllegalStateException: Not allowed to start service Intent { cmp=org.altbeacon.beaconreference/org.altbeacon.beacon.BeaconIntentProcessor (has extras) }: app is in background uid UidRecord{48b9abe u0a909 TRNB idle procs:1 seq(0,0,0)} at android.app.ContextImpl.startServiceCommon(ContextImpl.java:1791) at android.app.ContextImpl.startService(ContextImpl.java:1736) at android.content.ContextWrapper.startService(ContextWrapper.java:738) at org.altbeacon.beacon.service.Callback.call(Callback.java:60) at org.altbeacon.beacon.service.MonitoringStatus.updateNewlyInsideInRegionsContaining(MonitoringStatus.java:124) at org.altbeacon.beacon.service.ScanHelper.processBeaconFromScan(ScanHelper.java:343) at org.altbeacon.beacon.service.ScanHelper.access$200(ScanHelper.java:62) at org.altbeacon.beacon.service.ScanHelper$ScanProcessor.doInBackground(ScanHelper.java:420) at org.altbeacon.beacon.service.ScanHelper$ScanProcessor.doInBackground(ScanHelper.java:386) at android.os.AsyncTask$3.call(AsyncTask.java:394) at java.util.concurrent.FutureTask.run(FutureTask.java:266) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1167)  at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:641)  at java.lang.Thread.run(Thread.java:923)  2021-05-12 03:17:57.816 5919-5949/org.altbeacon.beaconreference I/Process: Sending signal. PID: 5919 SIG: 9 ```

The only thing that I did change was commenting the BackgroundPowerSaver instatiation and, inside didDetermineStateForRegion, I also commented the block responsible for start ranging, since I'm just looking for monitoring.

Steps to reproduce this behavior

  1. Clone either kotlin or java version of the reference app
  2. Run the app and allow access to location all the time
  3. Close the app
  4. Start advertising iBeacon

Mobile device model and OS version

Samsung S21+ Android 11

Android Beacon Library version

2.18

IMPORTANT: This forum is reserved for feature requests or reproducible bugs with the library itself. If you need help with using the library with your project, please open a new question on StackOverflow.com.

romaomb commented 3 years ago

Debug logs:

Click to expand ``` 2021-05-12 03:41:23.059 12132-12132/? I/beaconreferenc: Late-enabling -Xcheck:jni 2021-05-12 03:41:23.093 12132-12132/? I/beaconreferenc: Unquickening 20 vdex files! 2021-05-12 03:41:23.104 12132-12132/? D/ActivityThread: setConscryptValidator 2021-05-12 03:41:23.104 12132-12132/? D/ActivityThread: setConscryptValidator - put 2021-05-12 03:41:23.225 12132-12132/org.altbeacon.beaconreference D/NetworkSecurityConfig: No Network Security Config specified, using platform default 2021-05-12 03:41:23.268 12132-12132/org.altbeacon.beaconreference D/NetworkSecurityConfig: No Network Security Config specified, using platform default 2021-05-12 03:41:23.278 12132-12132/org.altbeacon.beaconreference I/BeaconManager: BeaconManager started up on pid 12132 named 'org.altbeacon.beaconreference' for application package 'org.altbeacon.beaconreference'. isMainProcess=true 2021-05-12 03:41:23.282 12132-12132/org.altbeacon.beaconreference D/BeaconParser: Parsing beacon layout: m:2-3=beac,i:4-19,i:20-21,i:22-23,p:24-24,d:25-25 2021-05-12 03:41:23.284 12132-12132/org.altbeacon.beaconreference D/BeaconParser: Parsing beacon layout: m:2-3=0215,i:4-19,i:20-21,i:22-23,p:24-24 2021-05-12 03:41:23.292 12132-12132/org.altbeacon.beaconreference D/BluetoothMedic: Medic monitoring for transmission and scan failure notifications 2021-05-12 03:41:23.292 12132-12132/org.altbeacon.beaconreference D/BluetoothMedic: Medic scheduling periodic tests of types 3 2021-05-12 03:41:23.294 12132-12132/org.altbeacon.beaconreference I/BluetoothTestJob: Using BluetoothTestJob JobId from manifest: 1799803768 2021-05-12 03:41:23.321 12132-12132/org.altbeacon.beaconreference D/BeaconManager: updating background flag to true 2021-05-12 03:41:23.322 12132-12132/org.altbeacon.beaconreference D/BeaconManager: updating scan period to 10000, 300000 2021-05-12 03:41:23.322 12132-12132/org.altbeacon.beaconreference W/BeaconManager: The BeaconManager is not bound to the service. Call beaconManager.bind(BeaconConsumer consumer) and wait for a callback to onBeaconServiceConnect() 2021-05-12 03:41:23.323 12132-12132/org.altbeacon.beaconreference D/BeaconManager: This consumer is not bound. Binding now: org.altbeacon.beacon.startup.RegionBootstrap$InternalBeaconConsumer@ec23084 2021-05-12 03:41:23.323 12132-12132/org.altbeacon.beaconreference D/BeaconManager: Not starting beacon scanning service. Using scheduled jobs 2021-05-12 03:41:23.323 12132-12132/org.altbeacon.beaconreference D/AppStarter: Activating background region monitoring 2021-05-12 03:41:23.324 12132-12132/org.altbeacon.beaconreference D/AppStarter: Background region monitoring activated for region id1: null id2: null id3: null 2021-05-12 03:41:23.325 12132-12132/org.altbeacon.beaconreference D/BeaconManager: callback packageName: org.altbeacon.beaconreference 2021-05-12 03:41:23.346 12132-12132/org.altbeacon.beaconreference D/MonitoringStatus: Restored region monitoring state for 1 regions. 2021-05-12 03:41:23.346 12132-12132/org.altbeacon.beaconreference D/MonitoringStatus: Region id1: null id2: null id3: null uniqueId: wildcard-region state: org.altbeacon.beacon.service.RegionMonitoringState@8ed28ee 2021-05-12 03:41:23.346 12132-12132/org.altbeacon.beaconreference D/MonitoringStatus: Done restoring monitoring status 2021-05-12 03:41:23.347 12132-12132/org.altbeacon.beaconreference D/MonitoringStatus: saveMonitoringStatusIfOn() 2021-05-12 03:41:23.352 12132-12132/org.altbeacon.beaconreference D/ScanJobScheduler: Applying settings to ScanJob 2021-05-12 03:41:23.403 12132-12132/org.altbeacon.beaconreference D/ScanState: Scan state restore regions: monitored=1 ranged=0 2021-05-12 03:41:23.404 12132-12132/org.altbeacon.beaconreference D/ScanState: ranged regions: old=0 new=0 2021-05-12 03:41:23.404 12132-12132/org.altbeacon.beaconreference D/ScanState: monitored regions: old=1 new=1 2021-05-12 03:41:23.404 12132-12132/org.altbeacon.beaconreference D/ScanState: Updated state with 0 ranging regions and 1 monitoring regions. 2021-05-12 03:41:23.419 12132-12132/org.altbeacon.beaconreference D/ScanState: Temp file is /data/user/0/org.altbeacon.beaconreference/files/android-beacon-library-scan-state-temp 2021-05-12 03:41:23.420 12132-12132/org.altbeacon.beaconreference D/ScanState: Perm file is /data/user/0/org.altbeacon.beaconreference/files/android-beacon-library-scan-state 2021-05-12 03:41:23.420 12132-12132/org.altbeacon.beaconreference D/MonitoringStatus: saveMonitoringStatusIfOn() 2021-05-12 03:41:23.423 12132-12132/org.altbeacon.beaconreference D/ScanJobScheduler: Applying scan job settings with background mode true 2021-05-12 03:41:23.423 12132-12132/org.altbeacon.beaconreference D/ScanJobScheduler: This is the first time we schedule a job and we are in background, set immediate scan flag to true in order to trigger the HW filter install. 2021-05-12 03:41:23.423 12132-12132/org.altbeacon.beaconreference D/BeaconLocalBroadcastProcessor: Register calls: global=1 2021-05-12 03:41:23.423 12132-12132/org.altbeacon.beaconreference D/ScanState: ScanState says background mode for ScanJob is true 2021-05-12 03:41:23.423 12132-12132/org.altbeacon.beaconreference D/ScanJobScheduler: We just woke up in the background based on a new scan result or first run of the app. Start scan job immediately. 2021-05-12 03:41:23.424 12132-12132/org.altbeacon.beaconreference D/ScanJobScheduler: Scheduling immediate ScanJob to run in 0 millis 2021-05-12 03:41:23.426 12132-12132/org.altbeacon.beaconreference I/ScanJob: Using immediateScanJobId from manifest: 208352939 2021-05-12 03:41:23.429 12132-12132/org.altbeacon.beaconreference D/ScanJobScheduler: First immediate scan job scheduled successful, change the flag to false. 2021-05-12 03:41:23.431 12132-12132/org.altbeacon.beaconreference I/ScanJob: Using periodicScanJobId from manifest: 208352940 2021-05-12 03:41:23.431 12132-12132/org.altbeacon.beaconreference W/JobInfo: Requested interval +5m10s0ms for job 208352940 is too small; raising to +15m0s0ms 2021-05-12 03:41:23.431 12132-12132/org.altbeacon.beaconreference W/JobInfo: Requested flex 0 for job 208352940 is too small; raising to +5m0s0ms 2021-05-12 03:41:23.432 12132-12132/org.altbeacon.beaconreference D/ScanJobScheduler: Scheduling periodic ScanJob (job:208352940/org.altbeacon.beaconreference/org.altbeacon.beacon.service.ScanJob) to run every 310000 millis 2021-05-12 03:41:23.449 12132-12132/org.altbeacon.beaconreference D/BeaconReference: didDetermineStateForRegion | state: 0 region: id1: null id2: null id3: null 2021-05-12 03:41:23.451 12132-12132/org.altbeacon.beaconreference D/BeaconManager: consumer count is now: 1 2021-05-12 03:41:23.451 12132-12132/org.altbeacon.beaconreference D/AppStarter: Waiting for BeaconService connection 2021-05-12 03:41:23.454 12132-12132/org.altbeacon.beaconreference D/StartupBroadcastReceiver: onReceive called in startup broadcast receiver 2021-05-12 03:41:23.456 12132-12132/org.altbeacon.beaconreference D/StartupBroadcastReceiver: Passive background scan callback type: 1 2021-05-12 03:41:23.456 12132-12132/org.altbeacon.beaconreference D/StartupBroadcastReceiver: got Android O background scan via intent 2021-05-12 03:41:23.456 12132-12132/org.altbeacon.beaconreference D/ScanJobScheduler: scheduling an immediate scan job because last did 1620801683456millis ago. 2021-05-12 03:41:23.488 12132-12132/org.altbeacon.beaconreference D/ScanState: Scan state restore regions: monitored=1 ranged=0 2021-05-12 03:41:23.488 12132-12132/org.altbeacon.beaconreference D/BeaconLocalBroadcastProcessor: Register calls: global=1 2021-05-12 03:41:23.488 12132-12132/org.altbeacon.beaconreference D/ScanState: ScanState says background mode for ScanJob is true 2021-05-12 03:41:23.488 12132-12132/org.altbeacon.beaconreference D/ScanJobScheduler: We just woke up in the background based on a new scan result or first run of the app. Start scan job immediately. 2021-05-12 03:41:23.489 12132-12132/org.altbeacon.beaconreference D/ScanJobScheduler: Scheduling immediate ScanJob to run in 0 millis 2021-05-12 03:41:23.491 12132-12132/org.altbeacon.beaconreference I/ScanJob: Using immediateScanJobId from manifest: 208352939 2021-05-12 03:41:23.494 12132-12132/org.altbeacon.beaconreference I/ScanJob: Using periodicScanJobId from manifest: 208352940 2021-05-12 03:41:23.494 12132-12132/org.altbeacon.beaconreference W/JobInfo: Requested interval +5m10s0ms for job 208352940 is too small; raising to +15m0s0ms 2021-05-12 03:41:23.494 12132-12132/org.altbeacon.beaconreference W/JobInfo: Requested flex 0 for job 208352940 is too small; raising to +5m0s0ms 2021-05-12 03:41:23.495 12132-12132/org.altbeacon.beaconreference D/ScanJobScheduler: Scheduling periodic ScanJob (job:208352940/org.altbeacon.beaconreference/org.altbeacon.beacon.service.ScanJob) to run every 310000 millis 2021-05-12 03:41:23.526 12132-12132/org.altbeacon.beaconreference D/StartupBroadcastReceiver: onReceive called in startup broadcast receiver 2021-05-12 03:41:23.527 12132-12132/org.altbeacon.beaconreference D/StartupBroadcastReceiver: Passive background scan callback type: 1 2021-05-12 03:41:23.527 12132-12132/org.altbeacon.beaconreference D/StartupBroadcastReceiver: got Android O background scan via intent 2021-05-12 03:41:23.528 12132-12132/org.altbeacon.beaconreference D/ScanJobScheduler: Not scheduling an immediate scan job because we just did recently. 2021-05-12 03:41:23.531 12132-12161/org.altbeacon.beaconreference I/BluetoothTestJob: Bluetooth Test Job running 2021-05-12 03:41:23.531 12132-12161/org.altbeacon.beaconreference D/BluetoothTestJob: Scan test specified. 2021-05-12 03:41:23.531 12132-12161/org.altbeacon.beaconreference I/BluetoothMedic: Starting scan test 2021-05-12 03:41:23.535 12132-12161/org.altbeacon.beaconreference D/BluetoothAdapter: STATE_ON 2021-05-12 03:41:23.536 12132-12161/org.altbeacon.beaconreference D/BluetoothAdapter: STATE_ON 2021-05-12 03:41:23.538 12132-12161/org.altbeacon.beaconreference D/BluetoothLeScanner: Start Scan with callback 2021-05-12 03:41:23.543 12132-12160/org.altbeacon.beaconreference D/BluetoothLeScanner: onScannerRegistered() - status=0 scannerId=19 mScannerId=0 2021-05-12 03:41:23.552 12132-12132/org.altbeacon.beaconreference D/ScanJob: ScanJob Lifecycle START: org.altbeacon.beacon.service.ScanJob@d508bd4 2021-05-12 03:41:23.555 12132-12161/org.altbeacon.beaconreference D/BluetoothMedic: Waiting for scan test to complete... 2021-05-12 03:41:23.581 12132-12132/org.altbeacon.beaconreference I/BluetoothMedic: Scan test succeeded 2021-05-12 03:41:23.581 12132-12132/org.altbeacon.beaconreference D/BluetoothAdapter: STATE_ON 2021-05-12 03:41:23.581 12132-12132/org.altbeacon.beaconreference D/BluetoothLeScanner: Stop Scan with callback 2021-05-12 03:41:23.583 12132-12163/org.altbeacon.beaconreference D/ScanState: Scan state restore regions: monitored=1 ranged=0 2021-05-12 03:41:23.584 12132-12163/org.altbeacon.beaconreference D/ScanHelper: new ScanHelper 2021-05-12 03:41:23.585 12132-12163/org.altbeacon.beaconreference D/ScanHelper: rangeRegionState updated with 0 regions 2021-05-12 03:41:23.585 12132-12163/org.altbeacon.beaconreference I/CycledLeScanner: Using Android O scanner 2021-05-12 03:41:23.590 12132-12163/org.altbeacon.beaconreference D/BeaconLocalBroadcastProcessor: Register calls: global=1 2021-05-12 03:41:23.591 12132-12163/org.altbeacon.beaconreference I/ScanJob: Using immediateScanJobId from manifest: 208352939 2021-05-12 03:41:23.592 12132-12163/org.altbeacon.beaconreference I/ScanJob: Running immediate scan job: instance is org.altbeacon.beacon.service.ScanJob@d508bd4 2021-05-12 03:41:23.592 12132-12163/org.altbeacon.beaconreference D/ScanJob: Processing 2 queued scan results 2021-05-12 03:41:23.595 12132-12163/org.altbeacon.beaconreference D/ScanJob: Done processing queued scan results 2021-05-12 03:41:23.595 12132-12163/org.altbeacon.beaconreference I/ScanJob: scanJob version 2.18 is starting up on the main process 2021-05-12 03:41:23.597 12132-12165/org.altbeacon.beaconreference D/BeaconParser: Ignoring pdu type 01 2021-05-12 03:41:23.597 12132-12166/org.altbeacon.beaconreference D/BeaconParser: Ignoring pdu type 01 2021-05-12 03:41:23.598 12132-12166/org.altbeacon.beaconreference D/BeaconParser: Processing pdu type FF: 0201061aff4c000215030013ac420255bcea11c75f806bb32911223344000000000000000000000000000000000000000000000000000000000000000000 with startIndex: 5, endIndex: 29 2021-05-12 03:41:23.598 12132-12165/org.altbeacon.beaconreference D/BeaconParser: Processing pdu type FF: 0201061aff4c000215030013ac420255bcea11c75f806bb32911223344000000000000000000000000000000000000000000000000000000000000000000 with startIndex: 5, endIndex: 29 2021-05-12 03:41:23.598 12132-12166/org.altbeacon.beaconreference D/BeaconParser: This is not a matching Beacon advertisement. (Was expecting be ac. The bytes I see are: 0201061aff4c000215030013ac420255bcea11c75f806bb32911223344000000000000000000000000000000000000000000000000000000000000000000 2021-05-12 03:41:23.599 12132-12166/org.altbeacon.beaconreference D/BeaconParser: Ignoring pdu type 01 2021-05-12 03:41:23.599 12132-12165/org.altbeacon.beaconreference D/BeaconParser: This is not a matching Beacon advertisement. (Was expecting be ac. The bytes I see are: 0201061aff4c000215030013ac420255bcea11c75f806bb32911223344000000000000000000000000000000000000000000000000000000000000000000 2021-05-12 03:41:23.599 12132-12165/org.altbeacon.beaconreference D/BeaconParser: Ignoring pdu type 01 2021-05-12 03:41:23.599 12132-12166/org.altbeacon.beaconreference D/BeaconParser: Processing pdu type FF: 0201061aff4c000215030013ac420255bcea11c75f806bb32911223344000000000000000000000000000000000000000000000000000000000000000000 with startIndex: 5, endIndex: 29 2021-05-12 03:41:23.599 12132-12165/org.altbeacon.beaconreference D/BeaconParser: Processing pdu type FF: 0201061aff4c000215030013ac420255bcea11c75f806bb32911223344000000000000000000000000000000000000000000000000000000000000000000 with startIndex: 5, endIndex: 29 2021-05-12 03:41:23.600 12132-12166/org.altbeacon.beaconreference D/BeaconParser: This is a recognized beacon advertisement -- 02 15 seen 2021-05-12 03:41:23.600 12132-12166/org.altbeacon.beaconreference D/BeaconParser: Bytes are: 0201061aff4c000215030013ac420255bcea11c75f806bb32911223344000000000000000000000000000000000000000000000000000000000000000000 2021-05-12 03:41:23.600 12132-12165/org.altbeacon.beaconreference D/BeaconParser: This is a recognized beacon advertisement -- 02 15 seen 2021-05-12 03:41:23.600 12132-12165/org.altbeacon.beaconreference D/BeaconParser: Bytes are: 0201061aff4c000215030013ac420255bcea11c75f806bb32911223344000000000000000000000000000000000000000000000000000000000000000000 2021-05-12 03:41:23.601 12132-12163/org.altbeacon.beaconreference D/ModelSpecificDistanceCalculator: Finding best distance calculator for 11, RP1A.200720.012, SM-G985F, samsung 2021-05-12 03:41:23.602 12132-12163/org.altbeacon.beaconreference D/AndroidModel: Score is 0 for LGE;Nexus 5;LPV79;4.4.2 compared to samsung;SM-G985F;RP1A.200720.012;11 2021-05-12 03:41:23.602 12132-12163/org.altbeacon.beaconreference D/AndroidModel: Score is 0 for XT1115;Moto X Pro;LXG22.67-7.1;5.0.2 compared to samsung;SM-G985F;RP1A.200720.012;11 2021-05-12 03:41:23.602 12132-12163/org.altbeacon.beaconreference D/AndroidModel: Score is 0 for LGE;Nexus 4;KOT49H;4.4.2 compared to samsung;SM-G985F;RP1A.200720.012;11 2021-05-12 03:41:23.603 12132-12163/org.altbeacon.beaconreference D/AndroidModel: Score is 0 for Motorola;XT1092;MPE24.49-18;6.0 compared to samsung;SM-G985F;RP1A.200720.012;11 2021-05-12 03:41:23.603 12132-12163/org.altbeacon.beaconreference W/ModelSpecificDistanceCalculator: Cannot find match for this device. Using default 2021-05-12 03:41:23.603 12132-12163/org.altbeacon.beaconreference D/ModelSpecificDistanceCalculator: Finding best distance calculator for 11, RP1A.200720.012, SM-G985F, samsung 2021-05-12 03:41:23.603 12132-12163/org.altbeacon.beaconreference D/AndroidModel: Score is 0 for LGE;Nexus 5;LPV79;4.4.2 compared to samsung;SM-G985F;RP1A.200720.012;11 2021-05-12 03:41:23.604 12132-12163/org.altbeacon.beaconreference D/AndroidModel: Score is 0 for XT1115;Moto X Pro;LXG22.67-7.1;5.0.2 compared to samsung;SM-G985F;RP1A.200720.012;11 2021-05-12 03:41:23.604 12132-12166/org.altbeacon.beaconreference D/ScanHelper: Beacon packet detected for: id1: id2: 4386 id3: 13124 with rssi -45 2021-05-12 03:41:23.604 12132-12163/org.altbeacon.beaconreference D/AndroidModel: Score is 0 for LGE;Nexus 4;KOT49H;4.4.2 compared to samsung;SM-G985F;RP1A.200720.012;11 2021-05-12 03:41:23.605 12132-12163/org.altbeacon.beaconreference D/AndroidModel: Score is 0 for Motorola;XT1092;MPE24.49-18;6.0 compared to samsung;SM-G985F;RP1A.200720.012;11 2021-05-12 03:41:23.605 12132-12163/org.altbeacon.beaconreference W/ModelSpecificDistanceCalculator: Cannot find match for this device. Using default 2021-05-12 03:41:23.605 12132-12163/org.altbeacon.beaconreference D/BluetoothAdapter: STATE_ON 2021-05-12 03:41:23.608 12132-12163/org.altbeacon.beaconreference D/BluetoothAdapter: STATE_ON 2021-05-12 03:41:23.608 12132-12165/org.altbeacon.beaconreference D/ScanHelper: Beacon packet detected for: id1: id2: 4386 id3: 13124 with rssi -42 2021-05-12 03:41:23.608 12132-12165/org.altbeacon.beaconreference I/ScanHelper: Non-distinct packets detected in a single scan. Restarting scans unecessary. 2021-05-12 03:41:23.610 12132-12163/org.altbeacon.beaconreference D/BluetoothLeScanner: Stop Scan with callback intent 2021-05-12 03:41:23.612 12132-12165/org.altbeacon.beaconreference D/ScanHelper: beacon detected : id1: id2: 4386 id3: 13124 2021-05-12 03:41:23.612 12132-12166/org.altbeacon.beaconreference D/ScanHelper: beacon detected : id1: id2: 4386 id3: 13124 2021-05-12 03:41:23.614 12132-12165/org.altbeacon.beaconreference D/Callback: attempting callback via global broadcast intent: ComponentInfo{org.altbeacon.beaconreference/org.altbeacon.beacon.BeaconIntentProcessor} 2021-05-12 03:41:23.615 12132-12163/org.altbeacon.beaconreference D/CycledLeScanner: Set scan periods called with 10000, 300000 Background mode must have changed. 2021-05-12 03:41:23.615 12132-12163/org.altbeacon.beaconreference D/CycledLeScanner: We are in the background. Setting wakeup alarm 2021-05-12 03:41:23.619 12132-12166/org.altbeacon.beaconreference D/ScanHelper: looking for ranging region matches for this beacon 2021-05-12 03:41:23.621 12132-12163/org.altbeacon.beaconreference D/CycledLeScanner: Set a wakeup alarm to go off in 300000 ms: PendingIntent{c96ee17: android.os.BinderProxy@5989204} 2021-05-12 03:41:23.622 12132-12165/org.altbeacon.beaconreference E/AndroidRuntime: FATAL EXCEPTION: pool-3-thread-1 Process: org.altbeacon.beaconreference, PID: 12132 java.lang.RuntimeException: An error occurred while executing doInBackground() at android.os.AsyncTask$4.done(AsyncTask.java:415) at java.util.concurrent.FutureTask.finishCompletion(FutureTask.java:383) at java.util.concurrent.FutureTask.setException(FutureTask.java:252) at java.util.concurrent.FutureTask.run(FutureTask.java:271) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1167) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:641) at java.lang.Thread.run(Thread.java:923) Caused by: java.lang.IllegalStateException: Not allowed to start service Intent { cmp=org.altbeacon.beaconreference/org.altbeacon.beacon.BeaconIntentProcessor (has extras) }: app is in background uid UidRecord{6cf69e5 u0a911 TRNB idle procs:1 seq(0,0,0)} at android.app.ContextImpl.startServiceCommon(ContextImpl.java:1791) at android.app.ContextImpl.startService(ContextImpl.java:1736) at android.content.ContextWrapper.startService(ContextWrapper.java:738) at org.altbeacon.beacon.service.Callback.call(Callback.java:60) at org.altbeacon.beacon.service.MonitoringStatus.updateNewlyInsideInRegionsContaining(MonitoringStatus.java:124) at org.altbeacon.beacon.service.ScanHelper.processBeaconFromScan(ScanHelper.java:343) at org.altbeacon.beacon.service.ScanHelper.access$200(ScanHelper.java:62) at org.altbeacon.beacon.service.ScanHelper$ScanProcessor.doInBackground(ScanHelper.java:420) at org.altbeacon.beacon.service.ScanHelper$ScanProcessor.doInBackground(ScanHelper.java:386) at android.os.AsyncTask$3.call(AsyncTask.java:394) at java.util.concurrent.FutureTask.run(FutureTask.java:266) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1167)  at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:641)  at java.lang.Thread.run(Thread.java:923)  2021-05-12 03:41:23.626 12132-12163/org.altbeacon.beaconreference D/CycledLeScanner: start called 2021-05-12 03:41:23.626 12132-12163/org.altbeacon.beaconreference D/CycledLeScanner: starting a new scan cycle 2021-05-12 03:41:23.627 12132-12163/org.altbeacon.beaconreference D/CycledLeScanner: starting a new bluetooth le scan 2021-05-12 03:41:23.628 12132-12163/org.altbeacon.beaconreference D/CycledLeScannerForLollipop: starting a scan in SCAN_MODE_LOW_LATENCY 2021-05-12 03:41:23.629 12132-12163/org.altbeacon.beaconreference D/CycledLeScannerForLollipop: Using a wildcard scan filter on Samsung because the screen is on. We will switch to a non-empty filter if the screen goes off 2021-05-12 03:41:23.635 12132-12163/org.altbeacon.beaconreference D/CycledLeScannerForLollipop: registering SamsungScreenOffReceiver org.altbeacon.beacon.service.scanner.CycledLeScannerForLollipop$5@c9a81e9 2021-05-12 03:41:23.635 12132-12163/org.altbeacon.beaconreference D/CycledLeScannerForLollipop: Making new Android L scanner 2021-05-12 03:41:23.636 12132-12163/org.altbeacon.beaconreference D/BluetoothAdapter: STATE_ON 2021-05-12 03:41:23.637 12132-12164/org.altbeacon.beaconreference D/BluetoothAdapter: STATE_ON 2021-05-12 03:41:23.637 12132-12163/org.altbeacon.beaconreference D/CycledLeScanner: Waiting to stop scan cycle for another 10000 milliseconds 2021-05-12 03:41:23.638 12132-12165/org.altbeacon.beaconreference I/Process: Sending signal. PID: 12132 SIG: 9 ```
davidgyoung commented 3 years ago

I reproduced this today, and put in a fix as part of #1030