09-03 11:08:55.130 10922 10942 I TestRunner: started: startupCompilationBaselineProfiles(com.example.baselineprofile.StartupBenchmarks) 09-03 11:08:55.145 10922 10942 I UiAutomation: Initialized for user 0 on display 0 09-03 11:08:55.151 4197 12840 I AiAiEcho: Settings changed for uri: content://settings/secure/accessibility_enabled 09-03 11:08:55.153 4197 4638 I AiAiEcho: Predicting[8]: { Feature:1, creation:2024-09-03T08:24:31Z, expiry:2024-09-03T09:00:00Z, isExpired: true}, { Feature:1, creation:2024-09-03T09:00:00Z, expiry:2024-09-03T10:00:00Z, isExpired: false}, { Feature:1, creation:2024-09-03T10:00:00Z, expiry:2024-09-03T10:00:00Z, isExpired: true}, { Feature:1, creation:2024-09-03T11:00:00Z, expiry:2024-09-03T11:00:00Z, isExpired: true}, { Feature:1, creation:2024-09-03T12:00:00Z, expiry:2024-09-03T12:00:00Z, isExpired: true}, { Feature:1, creation:2024-09-03T13:00:00Z, expiry:2024-09-03T13:00:00Z, isExpired: true}, { Feature:1, creation:2024-09-03T14:00:00Z, expiry:2024-09-03T14:00:00Z, isExpired: true}, { Feature:2, creation:2024-09-03T14:45:00Z, expiry:2024-09-03T15:15:00Z, isExpired: true} [CONTEXT sampling_count=5 ] 09-03 11:08:55.154 4197 4638 I AiAiEcho: EchoTargets: Feature:1 09-03 11:08:55.154 4197 4638 I AiAiEcho: Filtered by AiAi flag check: 09-03 11:08:55.154 4197 4638 I AiAiEcho: [CONTEXT ratelimit_period="10 SECONDS" ] 09-03 11:08:55.154 4197 4638 I AiAiEcho: Ranked targets strategy: WEIGHTED, count: 0, ranking metadata: [CONTEXT ratelimit_period="10 SECONDS" ] 09-03 11:08:55.154 10922 10942 I UiAutomationShellCmd: Requesting permission: pm grant com.example.baselineprofile android.permission.WRITE_EXTERNAL_STORAGE 09-03 11:08:55.154 10922 10942 W UiAutomation: UiAutomation.grantRuntimePermission() is more robust and should be used instead of 'pm grant' 09-03 11:08:55.154 4197 4638 I AiAiEcho: #remoteViewsTwiddler: feature disabled. 09-03 11:08:55.155 4197 4638 I AiAiEcho: Predicting[8]: { Feature:1, creation:2024-09-03T08:24:31Z, expiry:2024-09-03T09:00:00Z, isExpired: true}, { Feature:1, creation:2024-09-03T09:00:00Z, expiry:2024-09-03T10:00:00Z, isExpired: false}, { Feature:1, creation:2024-09-03T10:00:00Z, expiry:2024-09-03T10:00:00Z, isExpired: true}, { Feature:1, creation:2024-09-03T11:00:00Z, expiry:2024-09-03T11:00:00Z, isExpired: true}, { Feature:1, creation:2024-09-03T12:00:00Z, expiry:2024-09-03T12:00:00Z, isExpired: true}, { Feature:1, creation:2024-09-03T13:00:00Z, expiry:2024-09-03T13:00:00Z, isExpired: true}, { Feature:1, creation:2024-09-03T14:00:00Z, expiry:2024-09-03T14:00:00Z, isExpired: true}, { Feature:2, creation:2024-09-03T14:45:00Z, expiry:2024-09-03T15:15:00Z, isExpired: true} [CONTEXT sampling_count=5 ] 09-03 11:08:55.155 4197 4638 I AiAiEcho: EchoTargets: Feature:1 09-03 11:08:55.155 4197 4638 I AiAiEcho: Filtered by AiAi flag check: 09-03 11:08:55.155 4197 4638 I AiAiEcho: [CONTEXT ratelimit_period="10 SECONDS" ] 09-03 11:08:55.156 4197 4638 I AiAiEcho: Ranked targets strategy: WEIGHTED, count: 1, ranking metadata: { cardId: WEATHER-569377244, card type: 1, update time: 2024-09-03T09:00:00Z, expiration time: 2024-09-03T10:00:00Z, ranking metadata: RankingMetaData(urgency=0.979, priority=0.3, recency=1.0, rankScore=0.2937, importantTimePoints=# vgn@7bca4) } [CONTEXT ratelimit_period="10 SECONDS" ] 09-03 11:08:55.156 4197 4638 I AiAiEcho: #weatherTwiddler: Weather removes SubtitleSupplementalItem in surface: lockscreen [CONTEXT ratelimit_period="10 SECONDS" ] 09-03 11:08:55.156 4197 4638 I AiAiEcho: #remoteViewsTwiddler: feature disabled. 09-03 11:08:55.157 4197 4638 I AiAiEcho: #postPredictionTargets: Sending updates to UISurface media_data_manager with targets# 0 (types=[]) 09-03 11:08:55.169 4197 4638 I AiAiEcho: #postPredictionTargets: Sending updates to UISurface lockscreen with targets# 1 (types=[1]) 09-03 11:08:55.170 2577 2577 D SsMediaDataProvider: Forwarding Smartspace updates [] 09-03 11:08:55.171 2577 2577 I WeatherSmartspaceView: setFormattedContentDescription: text=21°C, iconDescription=Cloudy, contentDescription=Cloudy, 21°C 09-03 11:08:55.171 2577 2577 I WeatherData: Weather data parsed CLOUDY ("Cloudy") 21°C from Bundle[{description=Cloudy, feedback_intent=null, explanation_intent=Supplier{VAL_PARCELABLE@140+1968}, is_gaia_linked_data=true, use_celsius=true, state=9, temperature=21}] 09-03 11:08:55.171 2577 2577 W PageIndicator: Total number of pages invalid: 0. Assuming 1 page. 09-03 11:08:55.173 10922 10942 I UiAutomationShellCmd: Requesting permission: pm grant com.example.baselineprofile android.permission.READ_EXTERNAL_STORAGE 09-03 11:08:55.173 10922 10942 W UiAutomation: UiAutomation.grantRuntimePermission() is more robust and should be used instead of 'pm grant' 09-03 11:08:55.173 4184 30204 D MediaGrants: Removed 0 media_grants for 0 user for [com.example.baselineprofile]. Reason: Mode changed: android:read_external_storage 09-03 11:08:55.180 4427 4830 I A : NGA Sync: beginOnDataPresent 09-03 11:08:55.188 4427 4830 I A : NGA Sync: isNgaSyncEnabled: false; enableFileGroupPopulator: false; requiresMddAssets: false 09-03 11:08:55.190 4427 4830 I A : NGA disabled since it's running Panthera and at least one a11y feature is on. 09-03 11:08:55.190 4427 4830 I A : Nga state changed to NgaStateDetails{state=DISABLED, ngaStateInfo=Optional[# com.google.assistant.ab.y.r@1a1eb4e]} 09-03 11:08:55.191 4427 4830 I A : isTngActive=false 09-03 11:08:55.195 4427 9729 I A : Successfully received auth token for scope: oauth2:https://www.googleapis.com/auth/googlenow in time 4 ms 09-03 11:08:55.196 24249 24277 I A : Nga on-device enabled based on TNG:MA active state: false 09-03 11:08:55.196 24249 24277 I A : Nga on-device enabled based on TNG:MA active state: false 09-03 11:08:55.199 4427 9731 I A : Successfully received auth token for scope: oauth2:https://www.googleapis.com/auth/googlenow in time 6 ms 09-03 11:08:55.199 4184 30204 D MediaGrants: Removed 0 media_grants for 0 user for [com.example.baselineprofile]. Reason: Mode changed: android:read_external_storage 09-03 11:08:55.200 4427 9735 I A : Successfully received auth token for scope: oauth2:https://www.googleapis.com/auth/googlenow in time 8 ms 09-03 11:08:55.238 10922 10942 D Benchmark: Profiler MethodTracing, freq 1000, duration 5 09-03 11:08:55.238 10922 10942 D Benchmark: additionalTestOutputDir=/sdcard/Android/media/com.example.baselineprofile/additional_test_output 09-03 11:08:55.238 10922 10942 D Benchmark: runOnMainDeadlineSeconds 30 09-03 11:08:55.284 10922 10942 I UiDevice: Creating a new instance, old instance exists: false 09-03 11:08:55.285 10922 10942 D UiDevice: Setting accessibility service flags: 65618 09-03 11:08:55.286 27254 10962 D AdvertisingIdClient: AdvertisingIdClient already created. 09-03 11:08:55.286 27254 10962 D AdvertisingIdClient: AdvertisingIdClient is not bounded. Starting to bind it... 09-03 11:08:55.291 10922 10942 D UiDevice: Turning on screen. 09-03 11:08:55.291 4197 12840 I AiAiEcho: Settings changed for uri: content://settings/secure/accessibility_enabled 09-03 11:08:55.292 4197 4638 I AiAiEcho: Predicting[8]: { Feature:1, creation:2024-09-03T08:24:31Z, expiry:2024-09-03T09:00:00Z, isExpired: true}, { Feature:1, creation:2024-09-03T09:00:00Z, expiry:2024-09-03T10:00:00Z, isExpired: false}, { Feature:1, creation:2024-09-03T10:00:00Z, expiry:2024-09-03T10:00:00Z, isExpired: true}, { Feature:1, creation:2024-09-03T11:00:00Z, expiry:2024-09-03T11:00:00Z, isExpired: true}, { Feature:1, creation:2024-09-03T12:00:00Z, expiry:2024-09-03T12:00:00Z, isExpired: true}, { Feature:1, creation:2024-09-03T13:00:00Z, expiry:2024-09-03T13:00:00Z, isExpired: true}, { Feature:1, creation:2024-09-03T14:00:00Z, expiry:2024-09-03T14:00:00Z, isExpired: true}, { Feature:2, creation:2024-09-03T14:45:00Z, expiry:2024-09-03T15:15:00Z, isExpired: true} [CONTEXT sampling_count=5 ] 09-03 11:08:55.293 4197 4638 I AiAiEcho: EchoTargets: Feature:1 09-03 11:08:55.293 4197 4638 I AiAiEcho: Filtered by AiAi flag check: 09-03 11:08:55.293 4197 4638 I AiAiEcho: [CONTEXT ratelimit_period="10 SECONDS" ] 09-03 11:08:55.293 4197 4638 I AiAiEcho: Ranked targets strategy: WEIGHTED, count: 0, ranking metadata: [CONTEXT ratelimit_period="10 SECONDS" ] 09-03 11:08:55.293 4197 4638 I AiAiEcho: #remoteViewsTwiddler: feature disabled. 09-03 11:08:55.294 4197 4638 I AiAiEcho: Predicting[8]: { Feature:1, creation:2024-09-03T08:24:31Z, expiry:2024-09-03T09:00:00Z, isExpired: true}, { Feature:1, creation:2024-09-03T09:00:00Z, expiry:2024-09-03T10:00:00Z, isExpired: false}, { Feature:1, creation:2024-09-03T10:00:00Z, expiry:2024-09-03T10:00:00Z, isExpired: true}, { Feature:1, creation:2024-09-03T11:00:00Z, expiry:2024-09-03T11:00:00Z, isExpired: true}, { Feature:1, creation:2024-09-03T12:00:00Z, expiry:2024-09-03T12:00:00Z, isExpired: true}, { Feature:1, creation:2024-09-03T13:00:00Z, expiry:2024-09-03T13:00:00Z, isExpired: true}, { Feature:1, creation:2024-09-03T14:00:00Z, expiry:2024-09-03T14:00:00Z, isExpired: true}, { Feature:2, creation:2024-09-03T14:45:00Z, expiry:2024-09-03T15:15:00Z, isExpired: true} [CONTEXT sampling_count=5 ] 09-03 11:08:55.295 4197 4638 I AiAiEcho: EchoTargets: Feature:1 09-03 11:08:55.295 4197 4638 I AiAiEcho: Filtered by AiAi flag check: 09-03 11:08:55.295 4197 4638 I AiAiEcho: [CONTEXT ratelimit_period="10 SECONDS" ] 09-03 11:08:55.295 4197 4638 I AiAiEcho: Ranked targets strategy: WEIGHTED, count: 1, ranking metadata: { cardId: WEATHER-569377244, card type: 1, update time: 2024-09-03T09:00:00Z, expiration time: 2024-09-03T10:00:00Z, ranking metadata: RankingMetaData(urgency=0.979, priority=0.3, recency=1.0, rankScore=0.2937, importantTimePoints=# vgn@7bca4) } [CONTEXT ratelimit_period="10 SECONDS" ] 09-03 11:08:55.295 27254 10962 D AdvertisingIdClient: AdvertisingIdClient is bounded 09-03 11:08:55.295 4197 4638 I AiAiEcho: #weatherTwiddler: Weather removes SubtitleSupplementalItem in surface: lockscreen [CONTEXT ratelimit_period="10 SECONDS" ] 09-03 11:08:55.296 4197 4638 I AiAiEcho: #remoteViewsTwiddler: feature disabled. 09-03 11:08:55.296 4197 4638 I AiAiEcho: #postPredictionTargets: Sending updates to UISurface media_data_manager with targets# 0 (types=[]) 09-03 11:08:55.296 2577 2577 D SsMediaDataProvider: Forwarding Smartspace updates [] 09-03 11:08:55.297 27254 10962 W AdvertisingIdSettings: Package com.garmin.android.apps.connectmobile failed Ad Id permission check. Apps that target Android SDK 33 or higher should declare com.google.android.gms.permission.AD_ID in the app manifest to access Ad Id. 09-03 11:08:55.297 27254 10962 I AdvertisingIdClient: shouldSendLog 330169583 09-03 11:08:55.297 27254 10962 I AdvertisingIdClient: GetInfoInternal elapse 11ms 09-03 11:08:55.298 4218 19177 D AccessibilitySourceService: enabled a11y services count 0 09-03 11:08:55.298 4218 19177 D AccessibilitySourceService: a11y source sending 0 issue to sc 09-03 11:08:55.299 1647 1715 D SafetySourceDataValidat: No cert check requested for package com.google.android.permissioncontroller 09-03 11:08:55.299 4197 4638 I AiAiEcho: #postPredictionTargets: Sending updates to UISurface lockscreen with targets# 1 (types=[1]) 09-03 11:08:55.300 2577 2577 I WeatherSmartspaceView: setFormattedContentDescription: text=21°C, iconDescription=Cloudy, contentDescription=Cloudy, 21°C 09-03 11:08:55.301 2577 2577 I WeatherData: Weather data parsed CLOUDY ("Cloudy") 21°C from Bundle[{description=Cloudy, feedback_intent=null, explanation_intent=Supplier{VAL_PARCELABLE@140+1968}, is_gaia_linked_data=true, use_celsius=true, state=9, temperature=21}] 09-03 11:08:55.301 2577 2577 W PageIndicator: Total number of pages invalid: 0. Assuming 1 page. 09-03 11:08:55.303 2577 2577 D KeyguardViewMediator: KeyguardViewMediator queue processing message: NOTIFY_STARTED_WAKING_UP 09-03 11:08:55.309 1082 1082 D usf_sensor_hal: auto brightness: Enter Batch. Period = 250000000, latency = 0. 09-03 11:08:55.311 1082 1717 D usf_sensor_hal: auto brightness: Enter Activate. Enable = 1. 09-03 11:08:55.312 1082 1771 I sensor-suez: Vendor atom [id = 100243] reported. 09-03 11:08:55.312 1324 1324 D AOC : A3:MSG: usf_sensor.cc, 1573: USF: Received start sampling request for Auto Brightness (active:1). 09-03 11:08:55.312 708 708 I RefreshRateSelector: Display 4619827677550801152 policy changed 09-03 11:08:55.312 708 708 I RefreshRateSelector: Previous: {defaultModeId=1, allowGroupSwitching=false, primaryRanges={physical=[120.00 Hz, 120.00 Hz], render=[120.00 Hz, 120.00 Hz]}, appRequestRanges={physical=[120.00 Hz, 120.00 Hz], render=[120.00 Hz, 120.00 Hz]} idleScreenConfig=nullptr} 09-03 11:08:55.312 708 708 I RefreshRateSelector: Current: DisplayManagerPolicy{defaultModeId=1, allowGroupSwitching=false, primaryRanges={physical=[0.00 Hz, 120.00 Hz], render=[0.00 Hz, 120.00 Hz]}, appRequestRanges={physical=[0.00 Hz, 120.00 Hz], render=[0.00 Hz, 120.00 Hz]} idleScreenConfig=nullptr} 09-03 11:08:55.312 708 708 I RefreshRateSelector: 0 mode changes were performed under the previous policy 09-03 11:08:55.313 1324 1324 D AOC : A3:MSG: vsc_sensor.cc, 37: VSC: [Auto Brightness] enable: period 1000000000, latency = 0 09-03 11:08:55.313 708 708 D DisplayModeController: setDesiredMode 4619827677550801152 {mode={fps=120.00 Hz, modePtr={id=1, vsyncRate=120.00 Hz, peakRefreshRate=120.00 Hz}}, emitEvent=true, force=false} 09-03 11:08:55.314 1324 1324 D AOC : A3:MSG: vsc_controller.cc, 532: VSC: [Auto Brightness] requestSensorEvents: sensor 0x20009, period=100 latency=0ms. 09-03 11:08:55.314 1324 1324 D AOC : A3:MSG: usf_sensor.cc, 1573: USF: Received start sampling request for TMD3719 Ambient Light (active:3). 09-03 11:08:55.315 1324 1324 D AOC : A3:MSG: vsc_controller.cc, 532: VSC: [Auto Brightness] requestSensorEvents: sensor 0x2000e, period=100 latency=0ms. 09-03 11:08:55.315 1324 1324 D AOC : A3:MSG: usf_sensor.cc, 1573: USF: Received start sampling request for VD6282 Rear Light Sensor (active:1). 09-03 11:08:55.318 1324 1324 D AOC : A3:MSG: usf_vsc_log.cc, 44: USF: [Auto Brightness] Configured instance at 0x7812f500, front-als:1 rear-als:0 rls:1 09-03 11:08:55.327 1082 1082 D usf_sensor_hal: binned brightness: Enter Activate. Enable = 0. 09-03 11:08:55.327 1324 1324 D AOC : A3:MSG: usf_sensor.cc, 1581: USF: Received stop sampling request for Binned Brightness (active:0). 09-03 11:08:55.328 1324 1324 D AOC : A3:MSG: vsc_sensor.cc, 68: VSC: [Binned Brightness] disable. 09-03 11:08:55.328 1324 1324 D AOC : A3:MSG: vsc_controller.cc, 550: VSC: [Binned Brightness] stopSensorEvents: sensor 0x10005 09-03 11:08:55.329 1324 1324 D AOC : A3:MSG: usf_sensor.cc, 1581: USF: Received stop sampling request for TMD3719 Ambient Light (active:2). 09-03 11:08:55.329 1082 1771 I sensor-suez: Vendor atom [id = 100243] reported. 09-03 11:08:55.330 1324 1324 D AOC : A3:MSG: usf_vsc_log.cc, 44: USF: [BINNED_BRIGHTNESS] Deleted instance at 0x4010b158 09-03 11:08:55.330 2577 2678 D KeyguardViewMediator: updateActivityLockScreenState(true, false) 09-03 11:08:55.331 2577 2577 W PageIndicator: Total number of pages invalid: 0. Assuming 1 page. 09-03 11:08:55.331 2577 2577 W BcSmartspaceView: Current card is not present in the Adapter; cannot log. 09-03 11:08:55.332 1324 1324 D AOC : A3:MSG: usf_sensor.cc, 1581: USF: Received stop sampling request for TMD3719 Proximity (active:1). 09-03 11:08:55.332 27764 27764 I ActiveUnlock: onUserMayRequestUnlock [CONTEXT service_id=327 ] 09-03 11:08:55.332 27764 27764 I ActiveUnlock: prepareUnlockResult [CONTEXT service_id=327 ] 09-03 11:08:55.332 1052 1079 D CHRE : @ 330340.254: Parsed nanoapp message from host: app ID 0x476f6f676c001011 endpoint 0x2c msgType 8 payload size 1 09-03 11:08:55.333 1324 1324 D AOC : A3:MSG: usf_sensor.cc, 1573: USF: Received start sampling request for LSM6DSR Accelerometer (active:1). 09-03 11:08:55.333 2577 2577 D DLIndicator: onDozingChanged(false) 09-03 11:08:55.333 2577 2577 D DLIndicator: updateVisibility(), mDozing=false, mDocking=false, mTopIconShowing=false, mViewAttached=true 09-03 11:08:55.335 1324 1324 D AOC : A3:MSG: lsm6dsr_device.cc, 763: USF: Lsm6dsrDevice: Sensor 2: Enabled 1, odr_index 1. 09-03 11:08:55.335 1324 1324 D AOC : A3:MSG: lsm6dsr_device.cc, 249: USF: Lsm6dsrDevice: Config sampling too slow. Took 212158ns. 09-03 11:08:55.335 1324 1324 D AOC : A3:MSG: lsm6dsr_device.cc, 763: USF: Lsm6dsrDevice: Sensor 0: Enabled 1, odr_index 3. 09-03 11:08:55.336 1324 1324 D AOC : A3:MSG: lsm6dsr_fifo.cc, 750: USF: Lsm6dsrFifo: ODR: XL/G/T/TS: 52/0/13/6 WM: 6 Intr: 12. 09-03 11:08:55.336 4197 4638 I AiAiEcho: Predicting[8]: { Feature:1, creation:2024-09-03T08:24:31Z, expiry:2024-09-03T09:00:00Z, isExpired: true}, { Feature:1, creation:2024-09-03T09:00:00Z, expiry:2024-09-03T10:00:00Z, isExpired: false}, { Feature:1, creation:2024-09-03T10:00:00Z, expiry:2024-09-03T10:00:00Z, isExpired: true}, { Feature:1, creation:2024-09-03T11:00:00Z, expiry:2024-09-03T11:00:00Z, isExpired: true}, { Feature:1, creation:2024-09-03T12:00:00Z, expiry:2024-09-03T12:00:00Z, isExpired: true}, { Feature:1, creation:2024-09-03T13:00:00Z, expiry:2024-09-03T13:00:00Z, isExpired: true}, { Feature:1, creation:2024-09-03T14:00:00Z, expiry:2024-09-03T14:00:00Z, isExpired: true}, { Feature:2, creation:2024-09-03T14:45:00Z, expiry:2024-09-03T15:15:00Z, isExpired: true} [CONTEXT sampling_count=5 ] 09-03 11:08:55.336 4197 4638 I AiAiEcho: EchoTargets: Feature:1 09-03 11:08:55.336 4197 4638 I AiAiEcho: Filtered by AiAi flag check: 09-03 11:08:55.336 4197 4638 I AiAiEcho: [CONTEXT ratelimit_period="10 SECONDS" ] 09-03 11:08:55.336 4197 4638 I AiAiEcho: Ranked targets strategy: WEIGHTED, count: 1, ranking metadata: { cardId: WEATHER-569377244, card type: 1, update time: 2024-09-03T09:00:00Z, expiration time: 2024-09-03T10:00:00Z, ranking metadata: RankingMetaData(urgency=0.979, priority=0.3, recency=1.0, rankScore=0.2937, importantTimePoints=# vgn@7bca4) } [CONTEXT ratelimit_period="10 SECONDS" ] 09-03 11:08:55.336 4197 4638 I AiAiEcho: #weatherTwiddler: Weather removes SubtitleSupplementalItem in surface: lockscreen [CONTEXT ratelimit_period="10 SECONDS" ] 09-03 11:08:55.337 4197 4638 I AiAiEcho: #remoteViewsTwiddler: feature disabled. 09-03 11:08:55.337 1082 1082 D usf_sensor_hal: accelerometer: Enter Batch. Period = 200000000, latency = 2000000000. 09-03 11:08:55.337 1082 1082 D usf_sensor_hal: IMU temperature: Enter Batch. Period = 615385000, latency = 2000000000. 09-03 11:08:55.337 4197 4638 I AiAiEcho: Predicting[8]: { Feature:1, creation:2024-09-03T08:24:31Z, expiry:2024-09-03T09:00:00Z, isExpired: true}, { Feature:1, creation:2024-09-03T09:00:00Z, expiry:2024-09-03T10:00:00Z, isExpired: false}, { Feature:1, creation:2024-09-03T10:00:00Z, expiry:2024-09-03T10:00:00Z, isExpired: true}, { Feature:1, creation:2024-09-03T11:00:00Z, expiry:2024-09-03T11:00:00Z, isExpired: true}, { Feature:1, creation:2024-09-03T12:00:00Z, expiry:2024-09-03T12:00:00Z, isExpired: true}, { Feature:1, creation:2024-09-03T13:00:00Z, expiry:2024-09-03T13:00:00Z, isExpired: true}, { Feature:1, creation:2024-09-03T14:00:00Z, expiry:2024-09-03T14:00:00Z, isExpired: true}, { Feature:2, creation:2024-09-03T14:45:00Z, expiry:2024-09-03T15:15:00Z, isExpired: true} [CONTEXT sampling_count=5 ] 09-03 11:08:55.337 4197 4638 I AiAiEcho: EchoTargets: Feature:1 09-03 11:08:55.337 4197 4638 I AiAiEcho: Filtered by AiAi flag check: 09-03 11:08:55.337 4197 4638 I AiAiEcho: [CONTEXT ratelimit_period="10 SECONDS" ] 09-03 11:08:55.337 4197 4638 I AiAiEcho: Ranked targets strategy: WEIGHTED, count: 1, ranking metadata: { cardId: WEATHER-569377244, card type: 1, update time: 2024-09-03T09:00:00Z, expiration time: 2024-09-03T10:00:00Z, ranking metadata: RankingMetaData(urgency=0.979, priority=0.3, recency=1.0, rankScore=0.2937, importantTimePoints=# vgn@7bca4) } [CONTEXT ratelimit_period="10 SECONDS" ] 09-03 11:08:55.337 1052 1079 D CHRE.HAL: Received a nanoapp message from 0x476f6f676c001011 endpoint 0x2c: Type 0x3 size 0 09-03 11:08:55.337 4197 4638 I AiAiEcho: #weatherTwiddler: Weather removes SubtitleSupplementalItem in surface: home [CONTEXT ratelimit_period="10 SECONDS" ] 09-03 11:08:55.337 4197 4638 I AiAiEcho: #remoteViewsTwiddler: feature disabled. 09-03 11:08:55.338 708 708 D SurfaceFlinger: Setting power mode 2 on display 4619827677550801152 09-03 11:08:55.338 710 762 D hwc-display: setPowerMode:: FBIOBLANK mode(2), blank(0) 09-03 11:08:55.338 1082 1771 I sensor-suez: Vendor atom [id = 100243] reported. 09-03 11:08:55.340 1082 1082 D usf_sensor_hal: accelerometer: Enter Activate. Enable = 1. 09-03 11:08:55.341 1324 1324 D AOC : A3:MSG: usf_sensor.cc, 1573: USF: Received start sampling request for LSM6DSR Temperature (active:2). 09-03 11:08:55.341 1324 1324 D AOC : A3:MSG: usf_tbn_server.cc, 52: USF: Change bus owner to AP. 09-03 11:08:55.341 4197 12840 I AiAiEcho: Settings changed for uri: content://settings/secure/restart_nap_after_start 09-03 11:08:55.341 1082 1082 D usf_sensor_hal: IMU temperature: Enter Activate. Enable = 1. 09-03 11:08:55.342 1082 1771 I sensor-suez: Vendor atom [id = 100243] reported. 09-03 11:08:55.341 1324 1324 D AOC : A3:MSG: s6sy79x_device.cc, 173: USF: S6SY79X: Bus: 0, Host: 1; Enabled: 1. 09-03 11:08:55.342 1324 1324 D AOC : A3:MSG: s6sy79x_device.cc, 282: USF: S6SY79X: Device is disabled. 09-03 11:08:55.342 1324 1324 D AOC : A3:MSG: usf_tbn_server.cc, 241: USF: Ack request for AP requests bus 09-03 11:08:55.342 1324 1324 D AOC : A3:MSG: usf_sensor.cc, 1573: USF: Received start sampling request for LSM6DSR Accelerometer (active:2). 09-03 11:08:55.342 1324 1324 D AOC : A3:MSG: usf_sensor.cc, 1573: USF: Received start sampling request for LSM6DSR Temperature (active:3). 09-03 11:08:55.343 1647 1741 E Looper : Error adding epoll events for fd 693 while rebuilding epoll set: Operation not permitted 09-03 11:08:55.343 1647 1741 E Looper : Error adding epoll events for fd 621 while rebuilding epoll set: Operation not permitted 09-03 11:08:55.343 1647 1741 E Looper : Error adding epoll events for fd 811 while rebuilding epoll set: Bad file descriptor 09-03 11:08:55.343 1647 1741 E Looper : Error adding epoll events for fd 764 while rebuilding epoll set: Bad file descriptor 09-03 11:08:55.343 1647 1741 E Looper : Error adding epoll events for fd 799 while rebuilding epoll set: Operation not permitted 09-03 11:08:55.343 1647 1741 E Looper : Error adding epoll events for fd 644 while rebuilding epoll set: Operation not permitted 09-03 11:08:55.343 1647 1741 E Looper : Error adding epoll events for fd 925 while rebuilding epoll set: Bad file descriptor 09-03 11:08:55.343 1647 1741 E Looper : Error adding epoll events for fd 739 while rebuilding epoll set: Operation not permitted 09-03 11:08:55.343 1647 1741 E Looper : Error adding epoll events for fd 744 while rebuilding epoll set: Operation not permitted 09-03 11:08:55.343 1647 1741 E Looper : Error adding epoll events for fd 588 while rebuilding epoll set: Operation not permitted 09-03 11:08:55.343 1647 1741 E Looper : Error adding epoll events for fd 971 while rebuilding epoll set: Operation not permitted 09-03 11:08:55.343 1647 1741 E Looper : Error adding epoll events for fd 1019 while rebuilding epoll set: Bad file descriptor 09-03 11:08:55.343 1647 1741 E Looper : Error adding epoll events for fd 1162 while rebuilding epoll set: Bad file descriptor 09-03 11:08:55.343 1647 1741 W system_server: PointerController instance was released with pending callbacks. events=0x1 09-03 11:08:55.343 1647 1741 I InputManager-JNI: Viewport [0] to add: local:4619827677550801152, isActive: true 09-03 11:08:55.344 1647 1973 I InputReader: Reconfiguring input devices, changes=DISPLAY_INFO 09-03 11:08:55.344 1647 1973 I InputReader: Device reconfigured: id=4, name='sec_touchscreen', size 1440x3120, orientation Rotation0, mode DIRECT, display id 0 09-03 11:08:55.344 4197 4638 I AiAiEcho: #postPredictionTargets: Sending updates to UISurface lockscreen with targets# 1 (types=[1]) 09-03 11:08:55.347 1037 1061 D audio_hw: adev_set_parameters: enter: screen_state=on 09-03 11:08:55.350 2719 15571 W HWUI : Surface doesn't have any previously queued frames, nothing to readback from 09-03 11:08:55.351 1082 1771 I sensor-suez: Vendor atom [id = 100243] reported. 09-03 11:08:55.352 2830 2830 D GRIL-S : [4481]> UPDATE_DEVICE_STATE DeviceState: Usb:1, Wifi: 1, Wifi AP: 0, Earpiece: 0, Bluetooth: 1, Wireless charging: 0, Screen: 1, Camera: 0, 0, -, -, Motion: 0, Bluetooth tethering: 0, Voice: 0, Wifi 2.4G: 1, Radio: 1, Bluetooth connected: 0, Bluetooth media: 0, Wifi Band: 1, Wifi AP Band: 0, Wifi call: 0, mmWave: 0, mCellBackhaul: 0, mHallSensor: 0, mDozeStatus: 1, mAppClassInfo: 0, mWifiCapSensorStatus: 0, mCellCapSensorStatus: 0, mBesideHeadHandStatus: 0, mSpeakerStatus: 0, mProtectiveCaseStatus: 0 09-03 11:08:55.353 625 625 I hwservicemanager: getTransport: Cannot find entry hardware.google.bluetooth.bt_channel_avoidance@1.0::IBTChannelAvoidance/default in either framework or device VINTF manifest. 09-03 11:08:55.353 2824 3416 I HbmSvManager: Request state 0 req 1 09-03 11:08:55.354 2577 2629 D KeyguardTransitionRepository: (Internal) Setting current transition info: TransitionInfo(ownerName=FromDozingTransitionInteractor, from=DOZING, to=LOCKSCREEN, animated) 09-03 11:08:55.356 2577 2577 W View : requestLayout() improperly called by com.android.keyguard.CarrierText{fcb9f1e V.ED..... ......ID 50,0-655,196 #7f0a03fc app:id/keyguard_carrier_text} during layout: running second layout pass 09-03 11:08:55.356 1082 1082 D usf_sensor_hal: auto brightness: Enter Batch. Period = 200000000, latency = 0. 09-03 11:08:55.357 4197 4638 I AiAiEcho: Predicting[8]: { Feature:1, creation:2024-09-03T08:24:31Z, expiry:2024-09-03T09:00:00Z, isExpired: true}, { Feature:1, creation:2024-09-03T09:00:00Z, expiry:2024-09-03T10:00:00Z, isExpired: false}, { Feature:1, creation:2024-09-03T10:00:00Z, expiry:2024-09-03T10:00:00Z, isExpired: true}, { Feature:1, creation:2024-09-03T11:00:00Z, expiry:2024-09-03T11:00:00Z, isExpired: true}, { Feature:1, creation:2024-09-03T12:00:00Z, expiry:2024-09-03T12:00:00Z, isExpired: true}, { Feature:1, creation:2024-09-03T13:00:00Z, expiry:2024-09-03T13:00:00Z, isExpired: true}, { Feature:1, creation:2024-09-03T14:00:00Z, expiry:2024-09-03T14:00:00Z, isExpired: true}, { Feature:2, creation:2024-09-03T14:45:00Z, expiry:2024-09-03T15:15:00Z, isExpired: true} [CONTEXT sampling_count=5 ] 09-03 11:08:55.357 4197 4638 I AiAiEcho: EchoTargets: Feature:1 09-03 11:08:55.357 4197 4638 I AiAiEcho: Filtered by AiAi flag check: 09-03 11:08:55.357 4197 4638 I AiAiEcho: [CONTEXT ratelimit_period="10 SECONDS" ] 09-03 11:08:55.358 4197 4638 I AiAiEcho: Ranked targets strategy: WEIGHTED, count: 0, ranking metadata: [CONTEXT ratelimit_period="10 SECONDS" ] 09-03 11:08:55.358 4197 4638 I AiAiEcho: #remoteViewsTwiddler: feature disabled. 09-03 11:08:55.358 4197 4638 I AiAiEcho: Predicting[8]: { Feature:1, creation:2024-09-03T08:24:31Z, expiry:2024-09-03T09:00:00Z, isExpired: true}, { Feature:1, creation:2024-09-03T09:00:00Z, expiry:2024-09-03T10:00:00Z, isExpired: false}, { Feature:1, creation:2024-09-03T10:00:00Z, expiry:2024-09-03T10:00:00Z, isExpired: true}, { Feature:1, creation:2024-09-03T11:00:00Z, expiry:2024-09-03T11:00:00Z, isExpired: true}, { Feature:1, creation:2024-09-03T12:00:00Z, expiry:2024-09-03T12:00:00Z, isExpired: true}, { Feature:1, creation:2024-09-03T13:00:00Z, expiry:2024-09-03T13:00:00Z, isExpired: true}, { Feature:1, creation:2024-09-03T14:00:00Z, expiry:2024-09-03T14:00:00Z, isExpired: true}, { Feature:2, creation:2024-09-03T14:45:00Z, expiry:2024-09-03T15:15:00Z, isExpired: true} [CONTEXT sampling_count=5 ] 09-03 11:08:55.358 4197 4638 I AiAiEcho: EchoTargets: Feature:1 09-03 11:08:55.358 4197 4638 I AiAiEcho: Filtered by AiAi flag check: 09-03 11:08:55.358 4197 4638 I AiAiEcho: [CONTEXT ratelimit_period="10 SECONDS" ] 09-03 11:08:55.358 4197 4638 I AiAiEcho: Ranked targets strategy: WEIGHTED, count: 1, ranking metadata: { cardId: WEATHER-569377244, card type: 1, update time: 2024-09-03T09:00:00Z, expiration time: 2024-09-03T10:00:00Z, ranking metadata: RankingMetaData(urgency=0.979, priority=0.3, recency=1.0, rankScore=0.2937, importantTimePoints=# vgn@7bca4) } [CONTEXT ratelimit_period="10 SECONDS" ] 09-03 11:08:55.359 4197 4638 I AiAiEcho: #weatherTwiddler: Weather removes SubtitleSupplementalItem in surface: lockscreen [CONTEXT ratelimit_period="10 SECONDS" ] 09-03 11:08:55.359 4197 4638 I AiAiEcho: #remoteViewsTwiddler: feature disabled. 09-03 11:08:55.359 4197 4638 I AiAiEcho: #postPredictionTargets: Sending updates to UISurface media_data_manager with targets# 0 (types=[]) 09-03 11:08:55.360 4197 4638 I AiAiEcho: #postPredictionTargets: Sending updates to UISurface lockscreen with targets# 1 (types=[1]) 09-03 11:08:55.360 1082 1771 I sensor-suez: Vendor atom [id = 100243] reported. 09-03 11:08:55.362 27764 27764 I NearbyDiscovery: (REDACTED) FastPairDisplayObserver: %s -> %s 09-03 11:08:55.362 27764 18504 I NearbyDiscovery: (REDACTED) OffloadFastPairScanner: eventType=%s, isInternalReq=%s, isOffloadScanning=%s, isOffloadScanningAllowed=%s 09-03 11:08:55.363 2931 3839 I SHANNON_IMS: 7875 [PROX] onReceive: intent= [Intent { act=android.intent.action.SCREEN_ON flg=0x50200010 }] (ConnectivityBroadcastReceiver%onReceive:91) 09-03 11:08:55.365 1082 1771 I sensor-suez: Vendor atom [id = 100245] reported. 09-03 11:08:55.365 27764 18504 I NearbyDiscovery: (REDACTED) FastPairScanner: isScreenOn=%s, isLocationEnabled=%s, disableLocationRequirement=%s, isDiscoveryScanningEnabled=%s, during24GhzWifiWarmingUpPeriod=%s 09-03 11:08:55.368 27764 18504 D BluetoothAdapter: isLeEnabled(): ON 09-03 11:08:55.368 27764 18504 I NearbyDiscovery: (REDACTED) FastPairScanner: eventType=%s, intReq=%s, scanning=%s, scanAllowed=%s, bleEnabled=%s, lockScanRate=%s, startScanningByLowPowerMode=%s 09-03 11:08:55.370 27764 18504 I NearbyDiscovery: (REDACTED) FastPairScanner: isScreenOn=%s, isLocationEnabled=%s, disableLocationRequirement=%s, isDiscoveryScanningEnabled=%s, during24GhzWifiWarmingUpPeriod=%s 09-03 11:08:55.370 27764 18504 I NearbyDiscovery: (REDACTED) FastPairScanner: Start scanning internally, %s 09-03 11:08:55.370 27764 18504 D BluetoothAdapter: isLeEnabled(): ON 09-03 11:08:55.372 1287 1317 D RILClient: [OemClient]IND: (clientId = 0, msgId = 5104, dataLength = 7, channel = 0) 09-03 11:08:55.374 2830 3512 D GRIL-S : [4481]< UPDATE_DEVICE_STATE 09-03 11:08:55.374 2789 20526 I bt_shim_scanner: system/main/shim/le_scanning_manager.cc:148 RegisterScanner: in shim layer, UUID=5bd1018b-b16d-4f14-bda3-ca90bad0f5f2 09-03 11:08:55.375 1287 1317 D RILClient: [OemClient]IND: (clientId = 0, msgId = 2015, dataLength = 148, channel = 0) 09-03 11:08:55.375 1129 1930 D RILClient: [OemClient]IND: (clientId = 1, msgId = 5104, dataLength = 7, channel = 0) 09-03 11:08:55.375 1129 1930 D RILClient: [OemClient]IND: (clientId = 1, msgId = 2015, dataLength = 148, channel = 0) 09-03 11:08:55.377 3686 10966 I DeviceStatusMonitor: DeviceStatusMonitor.handleBroadcast():123 onReceive() : Action = android.intent.action.SCREEN_ON 09-03 11:08:55.377 3686 10966 I DeviceStatusMonitor: DeviceStatusMonitor.updateCountryInfo():139 updateCountryInfo(), notifyAnyway = false 09-03 11:08:55.377 27764 27798 D BluetoothLeScanner: onScannerRegistered() - status=0 scannerId=1 mScannerId=0 09-03 11:08:55.379 4197 4197 I GetModelStateManager: Received android.intent.action.SCREEN_ON, fetching model state. 09-03 11:08:55.379 2577 2577 I WeatherSmartspaceView: setFormattedContentDescription: text=21°C, iconDescription=Cloudy, contentDescription=Cloudy, 21°C 09-03 11:08:55.379 2577 2577 I WeatherData: Weather data parsed CLOUDY ("Cloudy") 21°C from Bundle[{description=Cloudy, feedback_intent=null, explanation_intent=Supplier{VAL_PARCELABLE@140+1968}, is_gaia_linked_data=true, use_celsius=true, state=9, temperature=21}] 09-03 11:08:55.379 2577 2577 W PageIndicator: Total number of pages invalid: 0. Assuming 1 page. 09-03 11:08:55.380 2577 2630 D KeyguardTransitionRepository: (Internal) Setting current transition info: TransitionInfo(ownerName=FromLockscreenTransitionInteractor(#listenForLockscreenToPrimaryBouncer), from=LOCKSCREEN, to=PRIMARY_BOUNCER, animated) 09-03 11:08:55.381 2577 2577 I KeyguardTransitionRepository: STARTED transition: TransitionStep(from=DOZING, to=LOCKSCREEN, value=0.0, transitionState=STARTED, ownerName=FromDozingTransitionInteractor) 09-03 11:08:55.382 1340 1340 I android.hardware.biometrics.fingerprint-service.goodix: onContextChanged 09-03 11:08:55.383 1082 1082 D usf_sensor_hal: device orientation: Enter Batch. Period = 66667000, latency = 0. 09-03 11:08:55.383 1082 1082 D usf_sensor_hal: device orientation: Enter Activate. Enable = 1. 09-03 11:08:55.384 1324 1324 D AOC : A3:MSG: usf_sensor.cc, 1573: USF: Received start sampling request for Device Orientation (active:1). 09-03 11:08:55.384 1324 1324 D AOC : A3:MSG: vsc_sensor.cc, 37: VSC: [Device Orientation] enable: period 66667000, latency = 0 09-03 11:08:55.385 1324 1324 D AOC : A3:MSG: vsc_controller.cc, 532: VSC: [Device Orientation] requestSensorEvents: sensor 0x1010001, period=66 latency=40ms 09-03 11:08:55.385 1324 1324 D AOC : A3:MSG: usf_vsc_log.cc, 53: USF: [WO] No second IMU detected 09-03 11:08:55.385 1324 1324 D AOC : A3:MSG: usf_vsc_log.cc, 53: USF: [WO] Set sensor rate and start algorithm version 13_2, imu 0 09-03 11:08:55.385 1082 1771 I sensor-suez: Vendor atom [id = 100243] reported. 09-03 11:08:55.387 1324 1324 D AOC : A3:MSG: usf_vsc_log.cc, 53: USF: [WO] Reset mTask, clear history 09-03 11:08:55.387 1324 1324 D AOC : A3:MSG: vsc_controller.cc, 532: VSC: [Device Orientation] requestSensorEvents: sensor 0x10001, period=66 latency=40ms. 09-03 11:08:55.387 1324 1324 D AOC : A3:MSG: usf_sensor.cc, 1573: USF: Received start sampling request for LSM6DSR Accelerometer (active:3). 09-03 11:08:55.387 27764 18504 I NearbyDiscovery: (REDACTED) FastPairScanner: Starting %s scan for %s seconds 09-03 11:08:55.387 1324 1324 D AOC : A3:MSG: lsm6dsr_device.cc, 763: USF: Lsm6dsrDevice: Sensor 0: Enabled 1, odr_index 3. 09-03 11:08:55.387 1324 1324 D AOC : A3:MSG: lsm6dsr_fifo.cc, 750: USF: Lsm6dsrFifo: ODR: XL/G/T/TS: 52/0/13/6 WM: 3 Intr: 24. 09-03 11:08:55.387 1324 1324 D AOC : A3:MSG: lsm6dsr_device.cc, 671: USF: Lsm6dsrDevice: Fifo was disabled for 358642ns. 09-03 11:08:55.388 2789 3160 I bt_shim_scanner: system/main/shim/le_scanning_manager.cc:249 ScanFilterEnable: in shim layer 09-03 11:08:55.388 1324 1324 D AOC : A3:MSG: vsc_controller.cc, 532: VSC: [Device Orientation] requestSensorEvents: sensor 0x10004, period=66 latency=40ms. 09-03 11:08:55.388 2789 3160 I bt_shim_scanner: system/main/shim/le_scanning_manager.cc:226 ScanFilterAdd: in shim layer 09-03 11:08:55.389 27764 27764 I ActiveUnlock: (REDACTED) onUserRequestedUnlock: dismissKeyguard %s 09-03 11:08:55.389 2789 3160 I bt_shim_scanner: system/main/shim/le_scanning_manager.cc:193 ScanFilterParamSetup: in shim layer, clientIf=1 09-03 11:08:55.389 1324 1324 D AOC : A3:MSG: usf_sensor.cc, 1573: USF: Received start sampling request for LSM6DSR Gyroscope (active:1). 09-03 11:08:55.389 2789 3160 I bt_shim_scanner: system/main/shim/le_scanning_manager.cc:226 ScanFilterAdd: in shim layer 09-03 11:08:55.389 1324 1324 D AOC : A3:MSG: lsm6dsr_device.cc, 763: USF: Lsm6dsrDevice: Sensor 1: Enabled 1, odr_index 3. 09-03 11:08:55.390 1109 1109 I WifiHAL : Creating message to get link statistics; iface = 47 09-03 11:08:55.390 2789 3160 I bt_shim_scanner: system/main/shim/le_scanning_manager.cc:193 ScanFilterParamSetup: in shim layer, clientIf=1 09-03 11:08:55.390 2789 3160 I bt_shim_scanner: system/main/shim/le_scanning_manager.cc:226 ScanFilterAdd: in shim layer 09-03 11:08:55.390 2789 3160 I bt_shim_scanner: system/main/shim/le_scanning_manager.cc:193 ScanFilterParamSetup: in shim layer, clientIf=1 09-03 11:08:55.391 2789 3160 I bt_shim_scanner: system/main/shim/le_scanning_manager.cc:160 Scan: in shim layer started 09-03 11:08:55.391 2789 3160 I bt_shim_scanner: system/main/shim/le_scanning_manager.cc:160 Scan: in shim layer stopped 09-03 11:08:55.391 2789 3160 I bt_shim_scanner: system/main/shim/le_scanning_manager.cc:325 SetScanParameters: in shim layer, scannerId=1 09-03 11:08:55.391 2789 3160 I bt_shim_scanner: system/main/shim/le_scanning_manager.cc:160 Scan: in shim layer started 09-03 11:08:55.394 1324 1324 D AOC : A3:MSG: lsm6dsr_fifo.cc, 750: USF: Lsm6dsrFifo: ODR: XL/G/T/TS: 52/52/13/6 WM: 6 Intr: 21. 09-03 11:08:55.394 1324 1324 D AOC : A3:MSG: lsm6dsr_device.cc, 671: USF: Lsm6dsrDevice: Fifo was disabled for 492920ns. 09-03 11:08:55.394 1324 1324 D AOC : A3:MSG: usf_sensor.cc, 1573: USF: Received start sampling request for LSM6DSR Accelerometer (active:4). 09-03 11:08:55.394 1324 1324 D AOC : A3:MSG: usf_sensor.cc, 1573: USF: Received start sampling request for LSM6DSR Gyroscope (active:2). 09-03 11:08:55.394 1324 1324 D AOC : A3:MSG: usf_sensor.cc, 1573: USF: Received start sampling request for LSM6DSR Temperature (active:5). 09-03 11:08:55.394 1324 1324 D AOC : A3:MSG: lsm6dsr_device.cc, 763: USF: Lsm6dsrDevice: Sensor 2: Enabled 1, odr_index 1. 09-03 11:08:55.394 1324 1324 D AOC : A3:MSG: lsm6dsr_fifo.cc, 750: USF: Lsm6dsrFifo: ODR: XL/G/T/TS: 52/52/13/6 WM: 6 Intr: 21. 09-03 11:08:55.394 1324 1324 D AOC : A3:MSG: lsm6dsr_device.cc, 671: USF: Lsm6dsrDevice: Fifo was disabled for 261475ns. 09-03 11:08:55.397 1324 1324 D AOC : A3:MSG: usf_sensor.cc, 1573: USF: Received start sampling request for Fusion Mag (active:2). 09-03 11:08:55.397 1324 1324 D AOC : A3:MSG: fusion_mag_sensor.cc, 672: USF: FMAG: Reconfig sampling: period(new/old) = 100/100ms, latency = 500ms. 09-03 11:08:55.397 27764 27805 I ActivityScheduler: nextTriggerTime: 330478555, in 123424ms, detectorType: 0, alarmWindowMillis: 30000 09-03 11:08:55.397 1082 1771 I sensor-suez: Vendor atom [id = 100243] reported. 09-03 11:08:55.399 2789 3101 I bluetooth: system/gd/hci/le_scanning_manager.cc:593 stop_scan: Scanning already stopped, return! 09-03 11:08:55.399 2789 3101 I bluetooth: system/gd/hci/le_address_manager.cc:214 register_client: Client registered 09-03 11:08:55.399 2789 3101 I bluetooth: system/gd/hci/le_address_manager.cc:229 unregister_client: Client unregistered 09-03 11:08:55.399 2789 3101 I bluetooth: system/gd/hci/le_scanning_manager.cc:593 stop_scan: Scanning already stopped, return! 09-03 11:08:55.399 2789 3101 I bluetooth: system/gd/hci/le_address_manager.cc:214 register_client: Client registered 09-03 11:08:55.402 4197 4197 I GetModelStateManager: Making request for model state. Last dsp event: 254030ms ago. Last gms event: 254080ms ago. Cooldown: 9000ms recentDsp: false recentGms: false forced: false, recognition active: true. 09-03 11:08:55.403 2577 2577 D SsMediaDataProvider: Forwarding Smartspace updates [] 09-03 11:08:55.403 2577 2577 D SystemEventDataSource: onReceive: intentAction 09-03 11:08:55.403 1647 1715 D TelephonyManager: requestModemActivityInfo: Sending result to app: ModemActivityInfo{ mTimestamp=330355106 mSleepTimeMs=279241299 mIdleTimeMs=8807907 mActivityStatsTechSpecificInfo=[{mRat=UNKNOWN,mFrequencyRange=UNKNOWN,mTxTimeMs[]=[158460, 105595, 371679, 280916, 631317],mRxTimeMs=42354867}]} 09-03 11:08:55.404 2577 2577 I WeatherSmartspaceView: setFormattedContentDescription: text=21°C, iconDescription=Cloudy, contentDescription=Cloudy, 21°C 09-03 11:08:55.404 2577 2577 I WeatherData: Weather data parsed CLOUDY ("Cloudy") 21°C from Bundle[{description=Cloudy, feedback_intent=null, explanation_intent=Supplier{VAL_PARCELABLE@140+1968}, is_gaia_linked_data=true, use_celsius=true, state=9, temperature=21}] 09-03 11:08:55.404 2577 2577 W PageIndicator: Total number of pages invalid: 0. Assuming 1 page. 09-03 11:08:55.404 1037 1061 D sound_trigger_hw: [1] get model stat 09-03 11:08:55.404 1013 1020 W statsd : StateTracker not found for state atom 805 09-03 11:08:55.404 1324 1324 D AOC : F1:MSG: controller.cc, 273: AudioInCtrl: cmd ID: 0x010d, tag: 0x4e [cntr = 6753] 09-03 11:08:55.405 2577 2577 I KeyguardTransitionRepository: Transition still active: TransitionStep(from=DOZING, to=LOCKSCREEN, value=0.0, transitionState=RUNNING, ownerName=FromDozingTransitionInteractor), canceling 09-03 11:08:55.405 2577 2577 I KeyguardTransitionRepository: CANCELED transition: TransitionStep(from=DOZING, to=LOCKSCREEN, value=0.0, transitionState=CANCELED, ownerName=FromDozingTransitionInteractor) 09-03 11:08:55.406 2577 2577 I KeyguardTransitionRepository: STARTED transition: TransitionStep(from=LOCKSCREEN, to=PRIMARY_BOUNCER, value=0.0, transitionState=STARTED, ownerName=FromLockscreenTransitionInteractor(#listenForLockscreenToPrimaryBouncer)) 09-03 11:08:55.417 27764 9041 I ActiveUnlock: (REDACTED) Encountered silent failure %s, finishing silently without unlocking. 09-03 11:08:55.419 1324 1324 D AOC : A3:MSG: usf_vsc_log.cc, 53: USF: [WO] Reset mTask, clear history 09-03 11:08:55.419 2577 2577 D KeyguardViewMediator: KeyguardViewMediator queue processing message: KEYGUARD_DONE_DRAWING 09-03 11:08:55.420 1324 1324 D AOC : F1:MSG: controller.cc, 811: AudioInCtrl: ipc: sound_trigger, cmd ID: 0x010d, tag: 0x4e, rc: 0 09-03 11:08:55.421 1037 1549 D sound_trigger_hw: handle_client_event: client 1 recognition_callback 09-03 11:08:55.426 1039 1067 I bthal.activity: LogActivities: BALANCED ScanMode 09-03 11:08:55.427 27764 9041 I NearbyUWB: (REDACTED) Screen unlocked: %s 09-03 11:08:55.427 1039 1067 I bthal.activity: LogActivities: Enable LE Scanning, 0x2042 09-03 11:08:55.428 1039 1208 I bthal.debug_central: Command Complete Event of LE_SET_EXTENDED_SCAN_ENABLE, opcode:2042 09-03 11:08:55.428 1039 1208 I bthal.flowcontrol: HciEventPreProcess: HCI Event 0xe Opcode 0x2042 Length 6 unhandled by LeSetExtentedScanEnableEvent 09-03 11:08:55.429 1039 1067 I bthal.activity: LogActivities: Disable LE Scanning, 0x2042 09-03 11:08:55.430 4197 4638 I AiAiEcho: Predicting[8]: { Feature:1, creation:2024-09-03T08:24:31Z, expiry:2024-09-03T09:00:00Z, isExpired: true}, { Feature:1, creation:2024-09-03T09:00:00Z, expiry:2024-09-03T10:00:00Z, isExpired: false}, { Feature:1, creation:2024-09-03T10:00:00Z, expiry:2024-09-03T10:00:00Z, isExpired: true}, { Feature:1, creation:2024-09-03T11:00:00Z, expiry:2024-09-03T11:00:00Z, isExpired: true}, { Feature:1, creation:2024-09-03T12:00:00Z, expiry:2024-09-03T12:00:00Z, isExpired: true}, { Feature:1, creation:2024-09-03T13:00:00Z, expiry:2024-09-03T13:00:00Z, isExpired: true}, { Feature:1, creation:2024-09-03T14:00:00Z, expiry:2024-09-03T14:00:00Z, isExpired: true}, { Feature:2, creation:2024-09-03T14:45:00Z, expiry:2024-09-03T15:15:00Z, isExpired: true} [CONTEXT sampling_count=5 ] 09-03 11:08:55.430 1039 1208 I bthal.debug_central: Command Complete Event of LE_SET_EXTENDED_SCAN_ENABLE, opcode:2042 09-03 11:08:55.430 1039 1208 I bthal.flowcontrol: HciEventPreProcess: HCI Event 0xe Opcode 0x2042 Length 6 unhandled by LeSetExtentedScanEnableEvent 09-03 11:08:55.430 4197 4638 I AiAiEcho: EchoTargets: Feature:1 09-03 11:08:55.430 4197 4638 I AiAiEcho: Filtered by AiAi flag check: 09-03 11:08:55.430 4197 4638 I AiAiEcho: [CONTEXT ratelimit_period="10 SECONDS" ] 09-03 11:08:55.431 4197 4638 I AiAiEcho: Ranked targets strategy: WEIGHTED, count: 1, ranking metadata: { cardId: WEATHER-569377244, card type: 1, update time: 2024-09-03T09:00:00Z, expiration time: 2024-09-03T10:00:00Z, ranking metadata: RankingMetaData(urgency=0.979, priority=0.3, recency=1.0, rankScore=0.2937, importantTimePoints=# vgn@7bca4) } [CONTEXT ratelimit_period="10 SECONDS" ] 09-03 11:08:55.431 1039 1067 I bthal.activity: LogActivities: LOW_LATENCY ScanMode 09-03 11:08:55.431 4197 4638 I AiAiEcho: #weatherTwiddler: Weather removes SubtitleSupplementalItem in surface: lockscreen [CONTEXT ratelimit_period="10 SECONDS" ] 09-03 11:08:55.431 4197 4638 I AiAiEcho: #remoteViewsTwiddler: feature disabled. 09-03 11:08:55.431 4197 4638 I AiAiEcho: Predicting[8]: { Feature:1, creation:2024-09-03T08:24:31Z, expiry:2024-09-03T09:00:00Z, isExpired: true}, { Feature:1, creation:2024-09-03T09:00:00Z, expiry:2024-09-03T10:00:00Z, isExpired: false}, { Feature:1, creation:2024-09-03T10:00:00Z, expiry:2024-09-03T10:00:00Z, isExpired: true}, { Feature:1, creation:2024-09-03T11:00:00Z, expiry:2024-09-03T11:00:00Z, isExpired: true}, { Feature:1, creation:2024-09-03T12:00:00Z, expiry:2024-09-03T12:00:00Z, isExpired: true}, { Feature:1, creation:2024-09-03T13:00:00Z, expiry:2024-09-03T13:00:00Z, isExpired: true}, { Feature:1, creation:2024-09-03T14:00:00Z, expiry:2024-09-03T14:00:00Z, isExpired: true}, { Feature:2, creation:2024-09-03T14:45:00Z, expiry:2024-09-03T15:15:00Z, isExpired: true} [CONTEXT sampling_count=5 ] 09-03 11:08:55.431 4197 4638 I AiAiEcho: EchoTargets: Feature:1 09-03 11:08:55.431 4197 4638 I AiAiEcho: Filtered by AiAi flag check: 09-03 11:08:55.431 4197 4638 I AiAiEcho: [CONTEXT ratelimit_period="10 SECONDS" ] 09-03 11:08:55.431 4197 4638 I AiAiEcho: Ranked targets strategy: WEIGHTED, count: 1, ranking metadata: { cardId: WEATHER-569377244, card type: 1, update time: 2024-09-03T09:00:00Z, expiration time: 2024-09-03T10:00:00Z, ranking metadata: RankingMetaData(urgency=0.979, priority=0.3, recency=1.0, rankScore=0.2937, importantTimePoints=# vgn@7bca4) } [CONTEXT ratelimit_period="10 SECONDS" ] 09-03 11:08:55.431 1039 1067 I bthal.activity: LogActivities: Enable LE Scanning, 0x2042 09-03 11:08:55.432 4197 4638 I AiAiEcho: #weatherTwiddler: Weather removes SubtitleSupplementalItem in surface: home [CONTEXT ratelimit_period="10 SECONDS" ] 09-03 11:08:55.432 4197 4638 I AiAiEcho: #remoteViewsTwiddler: feature disabled. 09-03 11:08:55.432 4197 5768 I AmbientMusicServiceMana: Requested model state. Model started: true Immediate return code: 0 09-03 11:08:55.432 1039 1208 I bthal.debug_central: Command Complete Event of LE_SET_EXTENDED_SCAN_ENABLE, opcode:2042 09-03 11:08:55.432 1039 1208 I bthal.flowcontrol: HciEventPreProcess: HCI Event 0xe Opcode 0x2042 Length 6 unhandled by LeSetExtentedScanEnableEvent 09-03 11:08:55.433 4197 4638 I AiAiEcho: #postPredictionTargets: Sending updates to UISurface lockscreen with targets# 1 (types=[1]) 09-03 11:08:55.436 27764 18504 I NearbyDiscovery: (REDACTED) FastPairHandler: Received action %s 09-03 11:08:55.436 27764 18504 I NearbyDiscovery: (REDACTED) OffloadFastPairScanner: eventType=%s, isInternalReq=%s, isOffloadScanning=%s, isOffloadScanningAllowed=%s 09-03 11:08:55.436 27764 18504 I NearbyDiscovery: (REDACTED) FastPairScanner: isScreenOn=%s, isLocationEnabled=%s, disableLocationRequirement=%s, isDiscoveryScanningEnabled=%s, during24GhzWifiWarmingUpPeriod=%s 09-03 11:08:55.436 27764 18504 D BluetoothAdapter: isLeEnabled(): ON 09-03 11:08:55.437 27764 18504 I NearbyDiscovery: (REDACTED) FastPairScanner: eventType=%s, intReq=%s, scanning=%s, scanAllowed=%s, bleEnabled=%s, lockScanRate=%s, startScanningByLowPowerMode=%s 09-03 11:08:55.437 27764 18504 I NearbyDiscovery: FastPairController: onSystemStateChanged: skip due to flag disabled [CONTEXT service_id=265 ] 09-03 11:08:55.440 2577 2630 D SystemEventDataSource: updatedEventData: SystemEventData(intentAction=android.intent.action.SCREEN_ON, plugged=EventData(value=1), batteryScale=EventData(value=100), batteryLevel=EventData(value=68), chargingStatus=EventData(value=1), maxChargingCurrent=EventData(value=3000000), maxChargingVoltage=EventData(value=5000000), batteryStatus=EventData(value=2), halEventData=HalEventData(dockDefendStatus=EventData(value=-3), tempDefendEventData=EventData(value=false), dwellDefendEventData=EventData(value=false)), settingsEventData=SettingsEventData(dockDefenderBypass=EventData(value=0), chargingLimitSettings=EventData(value=0), dndState=EventData(value=false), airplaneState=EventData(value=false)), frameworkApiEventData=FrameworkApiEventData(batterySaverState=EventData(value=false), extremeBatterySaverState=EventData(value=false))) 09-03 11:08:55.440 2577 2630 D ChargingLimitEventModule: validate()=false, chargingStatus=1 09-03 11:08:55.440 2577 2630 D DwellDefendBatteryModule: validate: false -> false 09-03 11:08:55.440 2577 2630 D TempDefendBatteryModule: validate: false -> false 09-03 11:08:55.440 2577 2630 D BatteryEventService: collect BatteryEvents: BatteryEvents(eventTypes=[SCREEN_ON, FAST_CHARGING], batteryLevel=68, pluggedType=1) 09-03 11:08:55.440 2577 2630 D BatteryEventService: notifyForBatteryEventsUpdate: BatteryEvents(eventTypes=[SCREEN_ON, FAST_CHARGING], batteryLevel=68, pluggedType=1) 09-03 11:08:55.440 2577 2630 D BatteryEventService: AIDL callback listeners count: 1 09-03 11:08:55.440 2577 2630 D BatteryEventService: notify AIDL callback to null, cost: 0 ms 09-03 11:08:55.440 2577 2630 I BatteryEventService: notify all AIDL callbacks, cost: 0 ms 09-03 11:08:55.440 2577 2630 D BatteryEventService: BatteryEventsListener (broadcast) count: 0 09-03 11:08:55.440 2577 2630 I BatteryEventService: notify all broadcast intent, cost: 0 ms 09-03 11:08:55.444 27764 27764 D BoundBrokerSvc: onRebind: Intent { act=com.google.android.gms.clearcut.bootcount.service.START dat=chimera-action:/... cmp=com.google.android.gms/.chimera.PersistentBoundBrokerService } 09-03 11:08:55.456 4197 4197 I AmbientMusicDetector: Received music trigger: 'RecognitionEvent{data=pos_music,val:19518, captureSession=12481, captureFormat=AudioFormat: props=7 enc=2 chan=0x10 chan_index=0x0 rate=16000, status=3}' 09-03 11:08:55.458 4197 4197 I AmbientMusicDetector: Running on-device song recognition. 09-03 11:08:55.458 4197 4197 I AudioBufferManager: Creating AudioRecord accessing DSP audio. 09-03 11:08:55.468 1037 1061 D audio_hw_input_stream: adev_open_input_stream handle 6262 channels=0x10 rate=16000 format=0x1 flags=0x2 source=1999 devices=0x80000004 09-03 11:08:55.478 1112 10968 I AudioFlinger: AudioFlinger's thread 0xb4000079f1463b28 tid=10968 ready to run 09-03 11:08:55.487 1082 2027 I suez-nanoapp-clients: Enabling ip_health nanoapp. 09-03 11:08:55.488 1324 1324 D AOC : A3:MSG: usf_sensor.cc, 1573: USF: Received start sampling request for VD6282 Spectral Sensor (active:1). 09-03 11:08:55.488 1324 1324 D AOC : A3:MSG: usf_vsc_log.cc, 53: USF: [WO] displayInfoEvent displayId=0, status=1 09-03 11:08:55.489 1324 1324 D AOC : A3:MSG: tmd3719_device.cc, 4525: USF: TMD3719: dbv:92 leakage:0.000000 refresh_rate:120 status:1 op_hz:120 [1] 09-03 11:08:55.490 1324 1324 D AOC : A3:MSG: tmd3719_device.cc, 562: USF: TMD3719: [ALS]sync_delay_adjustment: 1817490 09-03 11:08:55.490 1324 1324 D AOC : A3:MSG: tmd3719_device.cc, 4169: USF: TMD3719: [PROX]New PPERS: 0 09-03 11:08:55.490 1052 1079 D CHRE : @ 330340.412: Parsed nanoapp message from host: app ID 0x476f6f676c001022 endpoint 0x8082 msgType 1 payload size 0 09-03 11:08:55.491 1052 1079 I CHRE : @ 330340.412: [ip] Received message from host endpoint 0x8082 of type 1 and size 0. 09-03 11:08:55.491 1052 1079 I CHRE : @ 330340.412: [ip] App enabled by host. 09-03 11:08:55.492 1037 26149 D audio_hw_patch: sound-trigger-rec routing: 09-03 11:08:55.492 1037 26149 D audio_hw_patch: 0: 0x80000004 09-03 11:08:55.492 1112 10968 D audioserver: logFgsApiEnd: FGS Logger Transaction failed, -129 09-03 11:08:55.492 1112 10968 D audioserver: logFgsApiBegin: FGS Logger Transaction failed, -129 09-03 11:08:55.493 1109 1109 E IPCThreadState: binder thread pool (1 threads) starved for 103 ms 09-03 11:08:55.494 1324 1324 D AOC : A3:MSG: usf_sensor.cc, 1573: USF: Received start sampling request for ICP10101 Pressure Sensor (active:1). 09-03 11:08:55.495 1324 1324 D AOC : A3:MSG: icp10101_device.cc, 161: USF: ICP10101: Barometer stops sampling. 09-03 11:08:55.495 1324 1324 D AOC : A3:MSG: icp10101_device.cc, 147: USF: ICP10101: Barometer starts sampling: period_ns = 40000000, max_latency_ns = 10000 09-03 11:08:55.499 710 762 I hwc-display: [PrimaryDisplay] setActiveConfig: config(37) 09-03 11:08:55.499 710 762 I hwc-display: [PrimaryDisplay] resetConfigRequestStateLocked: mConfigRequestState (0) is not REQUESTED 09-03 11:08:55.499 1112 17201 D audioserver: logFgsApiBegin: FGS Logger Transaction failed, -129 09-03 11:08:55.499 708 708 D SurfaceFlinger: Finished setting power mode 2 on display 4619827677550801152 09-03 11:08:55.499 1647 2557 D SurfaceControl: Excessive delay in setPowerMode() 09-03 11:08:55.500 1082 2027 I suez-nanoapp-clients: Vendor atom [id = 100046] reported. 09-03 11:08:55.500 1037 1061 D audio_hw_input_stream: in_set_microphone_direction: standby 1 source 1999 dir 0 09-03 11:08:55.502 1037 1061 D audio_hw_input_stream: in_set_microphone_field_dimension: standby 1 source 1999 zoom 0.000000 09-03 11:08:55.504 708 708 I RefreshRateSelector: Display 4619827677550801152 policy changed 09-03 11:08:55.504 708 708 I RefreshRateSelector: Previous: {defaultModeId=1, allowGroupSwitching=false, primaryRanges={physical=[0.00 Hz, 120.00 Hz], render=[0.00 Hz, 120.00 Hz]}, appRequestRanges={physical=[0.00 Hz, 120.00 Hz], render=[0.00 Hz, 120.00 Hz]} idleScreenConfig=nullptr} 09-03 11:08:55.504 708 708 I RefreshRateSelector: Current: DisplayManagerPolicy{defaultModeId=1, allowGroupSwitching=false, primaryRanges={physical=[120.00 Hz, 120.00 Hz], render=[120.00 Hz, 120.00 Hz]}, appRequestRanges={physical=[120.00 Hz, 120.00 Hz], render=[120.00 Hz, 120.00 Hz]} idleScreenConfig=nullptr} 09-03 11:08:55.504 708 708 I RefreshRateSelector: 0 mode changes were performed under the previous policy 09-03 11:08:55.505 708 708 D DisplayModeController: setDesiredMode 4619827677550801152 {mode={fps=120.00 Hz, modePtr={id=1, vsyncRate=120.00 Hz, peakRefreshRate=120.00 Hz}}, emitEvent=true, force=false} 09-03 11:08:55.506 1037 10970 D audio_hw_input_stream: start_in_st_stream: starting st_stream for source 1999 09-03 11:08:55.506 1037 10970 D sound_trigger_hw: handle_get_data_tunnel: enter: voice_recognition = 0, channel_mask=0x10 09-03 11:08:55.506 1037 10970 D sound_trigger_platform: st_platform_start_buffering: type: 1 09-03 11:08:55.506 1037 10970 D sound_trigger_hw: handle_get_data_tunnel: client channel_mask = 0x10 request channel_mask = 0x10 09-03 11:08:55.507 710 762 I hwc-display: path=sysfs, id=0, level=183, nits=0.000000, brightness=0.054311, DimmingOn=0, Hbm=0, LhbmOn=0, OpRate=0 09-03 11:08:55.513 1037 26149 D audio_hw: adev_set_parameters: enter: screen_state=on 09-03 11:08:55.517 2577 2577 I WeatherSmartspaceView: setFormattedContentDescription: text=21°C, iconDescription=Cloudy, contentDescription=Cloudy, 21°C 09-03 11:08:55.517 2577 2577 I WeatherData: Weather data parsed CLOUDY ("Cloudy") 21°C from Bundle[{description=Cloudy, feedback_intent=null, explanation_intent=Supplier{VAL_PARCELABLE@140+1968}, is_gaia_linked_data=true, use_celsius=true, state=9, temperature=21}] 09-03 11:08:55.517 2577 2577 W PageIndicator: Total number of pages invalid: 0. Assuming 1 page. 09-03 11:08:55.520 1082 1082 D usf_sensor_hal: gyroscope: Enter Batch. Period = 20000000, latency = 0. 09-03 11:08:55.521 1082 1082 D usf_sensor_hal: IMU temperature: Enter Batch. Period = 615385000, latency = 0. 09-03 11:08:55.521 1324 1324 D AOC : A3:MSG: tmd3719_device.cc, 4525: USF: TMD3719: dbv:183 leakage:0.000000 refresh_rate:120 status:1 op_hz:120 [1] 09-03 11:08:55.521 1324 1324 D AOC : A3:MSG: tmd3719_device.cc, 562: USF: TMD3719: [ALS]sync_delay_adjustment: 925030 09-03 11:08:55.521 1324 1324 D AOC : A3:MSG: usf_sensor.cc, 2085: USF: Received reconfig sampling request for LSM6DSR Temperature. 09-03 11:08:55.522 1052 1079 D CHRE : @ 330340.436: Parsed nanoapp message from host: app ID 0x476f6f676c00100d endpoint 0x2a msgType 144 payload size 10 09-03 11:08:55.522 1052 1079 I CHRE : @ 330340.436: [NanoMinion] received MSG Type 0x90, Version 1, 6 bytes 09-03 11:08:55.522 1052 1079 I CHRE : @ 330340.436: [cc] disable 09-03 11:08:55.522 1082 1082 D usf_sensor_hal: gyroscope: Enter Activate. Enable = 1. 09-03 11:08:55.522 1324 1324 D AOC : A3:MSG: usf_sensor.cc, 1573: USF: Received start sampling request for LSM6DSR Gyroscope (active:2). 09-03 11:08:55.523 1647 4271 I AudioService.RecordingActivityMonitor: rec update riid:11983 uid:10107 session:12481 src:HOTWORD not silenced pack:com.google.android.as 09-03 11:08:55.523 1324 1324 D AOC : A3:MSG: lsm6dsr_device.cc, 763: USF: Lsm6dsrDevice: Sensor 1: Enabled 1, odr_index 3. 09-03 11:08:55.524 1324 1324 D AOC : A3:MSG: lsm6dsr_fifo.cc, 750: USF: Lsm6dsrFifo: ODR: XL/G/T/TS: 52/52/13/6 WM: 2 Intr: 52. 09-03 11:08:55.524 1109 1109 I WifiHAL : Creating message to get link statistics; iface = 47 09-03 11:08:55.524 1324 1324 D AOC : A3:MSG: lsm6dsr_device.cc, 671: USF: Lsm6dsrDevice: Fifo was disabled for 360839ns. 09-03 11:08:55.527 1052 1079 D CHRE : @ 330340.448: Nanoapp list request from client ID 1 09-03 11:08:55.527 1052 1079 D CHRE.HAL: Received a nanoapp list response for client 1 09-03 11:08:55.528 1082 1771 I sensor-suez: Vendor atom [id = 100243] reported. 09-03 11:08:55.531 1082 1771 I sensor-suez: Vendor atom [id = 100243] reported. 09-03 11:08:55.531 1082 1771 I sensor-suez: Vendor atom [id = 100245] reported. 09-03 11:08:55.546 4197 4959 I AudioBufferManager: Background #0: Audio recording finished, got 128000 samples. 09-03 11:08:55.550 27764 18504 I NearbyDiscovery: (REDACTED) %s 09-03 11:08:55.550 1052 1079 D CHRE.HAL: Received a nanoapp message from 0x476f6f676c00100b endpoint 0x26: Type 0x401 size 26 09-03 11:08:55.551 27764 18504 I NearbyDiscovery: (REDACTED) %s 09-03 11:08:55.551 1052 1079 D CHRE : @ 330340.472: Parsed nanoapp message from host: app ID 0x476f6f676c00100b endpoint 0x26 msgType 112 payload size 80 09-03 11:08:55.551 1052 1079 I CHRE : @ 330340.473: [NanoMinion] received MSG Type 0x70, Version 1, 76 bytes 09-03 11:08:55.551 1052 1079 I CHRE : @ 330340.473: [AR] request.enable latency=2147483647, numTrans=8 09-03 11:08:55.551 1052 1079 I CHRE : @ 330340.473: [NanoMinion] sent MSG Type 0x71, Version 0, 18 bytes, MessageId 127 09-03 11:08:55.551 1052 1079 I CHRE : @ 330340.473: [AR] send response=0 09-03 11:08:55.553 1013 1020 W statsd : StateTracker not found for state atom 805 09-03 11:08:55.553 1013 1020 W statsd : StateTracker not found for state atom 805 09-03 11:08:55.553 4197 5768 I AmbientMusicDetector: Received music trigger with last-matched track ID '0' () from shard '' 09-03 11:08:55.553 1013 1020 W statsd : StateTracker not found for state atom 805 09-03 11:08:55.553 1013 1020 W statsd : StateTracker not found for state atom 805 09-03 11:08:55.553 1013 1020 W statsd : StateTracker not found for state atom 805 09-03 11:08:55.553 1013 1020 W statsd : StateTracker not found for state atom 805 09-03 11:08:55.553 1013 1020 W statsd : StateTracker not found for state atom 805 09-03 11:08:55.553 1013 1020 W statsd : StateTracker not found for state atom 805 09-03 11:08:55.553 1013 1020 W statsd : StateTracker not found for state atom 805 09-03 11:08:55.553 1013 1020 W statsd : StateTracker not found for state atom 805 09-03 11:08:55.553 1013 1020 W statsd : StateTracker not found for state atom 805 09-03 11:08:55.553 1013 1020 W statsd : StateTracker not found for state atom 805 09-03 11:08:55.553 1013 1020 W statsd : StateTracker not found for state atom 805 09-03 11:08:55.553 1013 1020 W statsd : StateTracker not found for state atom 805 09-03 11:08:55.553 1013 1020 W statsd : StateTracker not found for state atom 805 09-03 11:08:55.553 1013 1020 W statsd : StateTracker not found for state atom 805 09-03 11:08:55.553 1013 1020 W statsd : StateTracker not found for state atom 805 09-03 11:08:55.553 1013 1020 W statsd : StateTracker not found for state atom 805 09-03 11:08:55.553 4197 5768 I NnfpMusicRecognizer: SoundSearch FP fallback configuration: AutoOnDemand: false HistoryDonation: false SoSe Fps: false 09-03 11:08:55.553 1013 1020 W statsd : StateTracker not found for state atom 805 09-03 11:08:55.553 1013 1020 W statsd : StateTracker not found for state atom 805 09-03 11:08:55.553 1013 1020 W statsd : StateTracker not found for state atom 805 09-03 11:08:55.553 1013 1020 W statsd : StateTracker not found for state atom 805 09-03 11:08:55.553 1013 1020 W statsd : StateTracker not found for state atom 805 09-03 11:08:55.553 1013 1020 W statsd : StateTracker not found for state atom 805 09-03 11:08:55.553 1013 1020 W statsd : StateTracker not found for state atom 805 09-03 11:08:55.553 1013 1020 W statsd : StateTracker not found for state atom 805 09-03 11:08:55.553 1013 1020 W statsd : StateTracker not found for state atom 805 09-03 11:08:55.553 1013 1020 W statsd : StateTracker not found for state atom 805 09-03 11:08:55.554 1013 1020 W statsd : StateTracker not found for state atom 805 09-03 11:08:55.554 1013 1020 W statsd : StateTracker not found for state atom 805 09-03 11:08:55.554 1013 1020 W statsd : StateTracker not found for state atom 805 09-03 11:08:55.554 1013 1020 W statsd : StateTracker not found for state atom 805 09-03 11:08:55.554 1013 1020 W statsd : StateTracker not found for state atom 805 09-03 11:08:55.555 2577 2577 D UdfpsTouchOverlayBinder: [com.android.systemui.biometrics.ui.view.UdfpsTouchOverlay{7f269d9 V.E...... ........ 0,0-1440,3120 #7f0a08bc app:id/udfps_touch_overlay}]: update shouldHandleTouches=false 09-03 11:08:55.556 1340 1340 I android.hardware.biometrics.fingerprint-service.goodix: onContextChanged 09-03 11:08:55.558 27764 10713 I NearbyDiscovery: (REDACTED) loadFastPairModule: checkFeaturesAreAvailable:%d 09-03 11:08:55.559 27764 10713 I NearbyDiscovery: loadFastPairModule: FastPair module is downloaded. [CONTEXT service_id=265 ] 09-03 11:08:55.573 2577 2577 D DLObserver: remove listener: com.android.systemui.doze.DozeTriggers$DockEventListener@3fd3ac2 09-03 11:08:55.573 1082 1082 D usf_sensor_hal: device pickup: Enter Activate. Enable = 0. 09-03 11:08:55.574 2577 2577 D DLObserver: remove listener: com.android.systemui.doze.DozeDockHandler$DockEventListener@a50b17d 09-03 11:08:55.574 2577 2577 D KeyguardViewMediator: KeyguardViewMediator queue processing message: KEYGUARD_DONE_DRAWING 09-03 11:08:55.575 1324 1324 D AOC : A3:MSG: usf_sensor.cc, 1581: USF: Received stop sampling request for Device Pickup Sensor (active:0). 09-03 11:08:55.575 1324 1324 D AOC : A3:MSG: vsc_sensor.cc, 68: VSC: [Device Pickup Sensor] disable. 09-03 11:08:55.575 1324 1324 D AOC : A3:MSG: vsc_controller.cc, 550: VSC: [Device Pickup Sensor] stopSensorEvents: sensor 0x20002 09-03 11:08:55.576 1082 1082 D usf_sensor_hal: proximity gated single tap: Enter Activate. Enable = 0. 09-03 11:08:55.577 1082 1771 I sensor-suez: Vendor atom [id = 100243] reported. 09-03 11:08:55.577 1324 1324 D AOC : A3:MSG: usf_sensor.cc, 1581: USF: Received stop sampling request for Proximity Gated Single Tap Gesture (active:0). 09-03 11:08:55.577 1324 1324 D AOC : A3:MSG: vsc_sensor.cc, 68: VSC: [Proximity Gated Single Tap Gesture] disable. 09-03 11:08:55.577 1324 1324 D AOC : A3:MSG: vsc_controller.cc, 550: VSC: [Proximity Gated Single Tap Gesture] stopSensorEvents: sensor 0x20006 09-03 11:08:55.577 1324 1324 D AOC : A3:MSG: usf_sensor.cc, 1581: USF: Received stop sampling request for S6SY79X Touch Gesture Sensor (active:0). 09-03 11:08:55.578 1324 1324 D AOC : A3:MSG: s6sy79x_sensor.cc, 93: USF: S6SY79X: StopSampling: S6SY79X Touch Gesture Sensor. 09-03 11:08:55.578 1324 1324 D AOC : A3:MSG: s6sy79x_device.cc, 173: USF: S6SY79X: Bus: 0, Host: 0; Enabled: 0. 09-03 11:08:55.578 2577 2577 D KeyguardViewMediator: KeyguardViewMediator queue processing message: KEYGUARD_DONE_DRAWING 09-03 11:08:55.578 1324 1324 D AOC : A3:MSG: vsc_controller.cc, 550: VSC: [Proximity Gated Single Tap Gesture] stopSensorEvents: sensor 0x10008 09-03 11:08:55.579 1324 1324 D AOC : A3:MSG: usf_vsc_log.cc, 44: USF: gti: [STTW] sensor disabled 09-03 11:08:55.580 1082 1082 D usf_sensor_hal: quick pickup: Enter Activate. Enable = 0. 09-03 11:08:55.581 1324 1324 D AOC : A3:MSG: usf_sensor.cc, 1581: USF: Received stop sampling request for Quick Pickup Sensor (active:0). 09-03 11:08:55.583 1324 1324 D AOC : A3:MSG: vsc_sensor.cc, 68: VSC: [Quick Pickup Sensor] disable. 09-03 11:08:55.583 1324 1324 D AOC : A3:MSG: vsc_controller.cc, 550: VSC: [Quick Pickup Sensor] stopSensorEvents: sensor 0x10008 09-03 11:08:55.583 1324 1324 D AOC : A3:MSG: usf_sensor.cc, 1581: USF: Received stop sampling request for TMD3719 Proximity (active:0). 09-03 11:08:55.583 1082 1180 I usf_sensor_hal: Received REPORT_SENSOR_ACTIVE_STATE_CHANGES event from 'TMD3719 Proximity' with new state:kInactive! 09-03 11:08:55.583 1324 1324 D AOC : A3:MSG: tmd3719_sensor.cc, 327: USF: TMD3719: Report new active state:0 09-03 11:08:55.583 1324 1324 D AOC : A3:MSG: usf_vsc_log.cc, 44: USF: [QuickPickup] prox disabled. 09-03 11:08:55.584 1324 1324 D AOC : A3:MSG: vsc_controller.cc, 550: VSC: [Quick Pickup Sensor] stopSensorEvents: sensor 0x20002 09-03 11:08:55.584 1324 1324 D AOC : A3:MSG: usf_vsc_log.cc, 44: USF: [QuickPickup] MD disabled. 09-03 11:08:55.584 710 749 D hwc-display: setMinIdleRefreshRate requester 0, fps 0 09-03 11:08:55.584 710 749 I hwc-display: ExynosPrimaryDisplay::setMinIdleRefreshRate() writes min_vrefresh(0) to the sysfs node 09-03 11:08:55.584 1324 1324 D AOC : A3:MSG: usf_vsc_log.cc, 44: USF: [QuickPickup] Sensor disabled. 09-03 11:08:55.584 710 749 I hwc-display: setRefreshRateThrottleNanos() requester(0) set delay to 0ns 09-03 11:08:55.585 710 749 I hwc-display: setDisplayIdleDelayNanos() writes idle_delay_ms(0) to the sysfs node (0x0) 09-03 11:08:55.585 2577 2577 D KeyguardViewMediator: KeyguardViewMediator queue processing message: KEYGUARD_DONE_DRAWING 09-03 11:08:55.592 3462 3462 D SconeAwareness: Awareness received [com.google.android.apps.scone.action.FenceAction] action and try to relay info 09-03 11:08:55.592 3462 3462 W JobInfo : Job 'com.google.android.apps.scone/.awareness.AwarenessJobIntentService#188052156' has a deadline with no functional constraints. The deadline won't improve job execution latency. Consider removing the deadline. 09-03 11:08:55.592 1647 1851 W JobInfo : Job 'com.google.android.apps.scone/.awareness.AwarenessJobIntentService#188052156' has a deadline with no functional constraints. The deadline won't improve job execution latency. Consider removing the deadline. 09-03 11:08:55.596 1052 1079 D CHRE : @ 330340.493: Parsed nanoapp message from host: app ID 0x476f6f676c00100d endpoint 0x2a msgType 144 payload size 10 09-03 11:08:55.596 1052 1079 I CHRE : @ 330340.493: [NanoMinion] received MSG Type 0x90, Version 1, 6 bytes 09-03 11:08:55.596 1052 1079 I CHRE : @ 330340.493: [cc] disable 09-03 11:08:55.598 1082 1771 I sensor-suez: Vendor atom [id = 100243] reported. 09-03 11:08:55.598 1082 1771 I sensor-suez: Vendor atom [id = 100243] reported. 09-03 11:08:55.600 1052 1079 D CHRE : @ 330340.521: Nanoapp list request from client ID 1 09-03 11:08:55.600 1052 1079 D CHRE.HAL: Received a nanoapp list response for client 1 09-03 11:08:55.609 1052 1079 D CHRE.HAL: Received a nanoapp message from 0x476f6f676c00100b endpoint 0x26: Type 0x401 size 26 09-03 11:08:55.609 1052 1079 D CHRE : @ 330340.531: Parsed nanoapp message from host: app ID 0x476f6f676c00100b endpoint 0x26 msgType 112 payload size 80 09-03 11:08:55.609 1052 1079 I CHRE : @ 330340.531: [NanoMinion] received MSG Type 0x70, Version 1, 76 bytes 09-03 11:08:55.609 1052 1079 I CHRE : @ 330340.531: [AR] request.enable latency=2147483647, numTrans=8 09-03 11:08:55.609 1052 1079 I CHRE : @ 330340.531: [NanoMinion] sent MSG Type 0x71, Version 0, 18 bytes, MessageId 128 09-03 11:08:55.609 1052 1079 I CHRE : @ 330340.532: [AR] send response=0 09-03 11:08:55.619 2830 2830 D GRIL-S : [4482]> UPDATE_SCONE_INFO Mobility:3 Battery:2 Thermal:0 Screen:1 09-03 11:08:55.626 1013 1020 W statsd : StateTracker not found for state atom 805 09-03 11:08:55.626 1013 1020 W statsd : StateTracker not found for state atom 805 09-03 11:08:55.626 1013 1020 W statsd : StateTracker not found for state atom 805 09-03 11:08:55.626 1013 1020 W statsd : StateTracker not found for state atom 805 09-03 11:08:55.626 1013 1020 W statsd : StateTracker not found for state atom 805 09-03 11:08:55.626 1013 1020 W statsd : StateTracker not found for state atom 805 09-03 11:08:55.626 1013 1020 W statsd : StateTracker not found for state atom 805 09-03 11:08:55.626 1013 1020 W statsd : StateTracker not found for state atom 805 09-03 11:08:55.626 1013 1020 W statsd : StateTracker not found for state atom 805 09-03 11:08:55.626 1013 1020 W statsd : StateTracker not found for state atom 805 09-03 11:08:55.626 1013 1020 W statsd : StateTracker not found for state atom 805 09-03 11:08:55.626 1013 1020 W statsd : StateTracker not found for state atom 805 09-03 11:08:55.626 1013 1020 W statsd : StateTracker not found for state atom 805 09-03 11:08:55.626 1013 1020 W statsd : StateTracker not found for state atom 805 09-03 11:08:55.626 1013 1020 W statsd : StateTracker not found for state atom 805 09-03 11:08:55.626 1013 1020 W statsd : StateTracker not found for state atom 805 09-03 11:08:55.626 1013 1020 W statsd : StateTracker not found for state atom 805 09-03 11:08:55.626 1013 1020 W statsd : StateTracker not found for state atom 805 09-03 11:08:55.626 1013 1020 W statsd : StateTracker not found for state atom 805 09-03 11:08:55.626 1013 1020 W statsd : StateTracker not found for state atom 805 09-03 11:08:55.626 1013 1020 W statsd : StateTracker not found for state atom 805 09-03 11:08:55.627 1013 1020 W statsd : StateTracker not found for state atom 805 09-03 11:08:55.627 1013 1020 W statsd : StateTracker not found for state atom 805 09-03 11:08:55.627 1013 1020 W statsd : StateTracker not found for state atom 805 09-03 11:08:55.627 1013 1020 W statsd : StateTracker not found for state atom 805 09-03 11:08:55.627 1013 1020 W statsd : StateTracker not found for state atom 805 09-03 11:08:55.627 1013 1020 W statsd : StateTracker not found for state atom 805 09-03 11:08:55.627 1013 1020 W statsd : StateTracker not found for state atom 805 09-03 11:08:55.627 1013 1020 W statsd : StateTracker not found for state atom 805 09-03 11:08:55.627 1013 1020 W statsd : StateTracker not found for state atom 805 09-03 11:08:55.627 1013 1020 W statsd : StateTracker not found for state atom 805 09-03 11:08:55.627 1013 1020 W statsd : StateTracker not found for state atom 805 09-03 11:08:55.627 1013 1020 W statsd : StateTracker not found for state atom 805 09-03 11:08:55.627 1013 1020 W statsd : StateTracker not found for state atom 805 09-03 11:08:55.627 1013 1020 W statsd : StateTracker not found for state atom 805 09-03 11:08:55.627 1013 1020 W statsd : StateTracker not found for state atom 805 09-03 11:08:55.627 1013 1020 W statsd : StateTracker not found for state atom 805 09-03 11:08:55.627 1013 1020 W statsd : StateTracker not found for state atom 805 09-03 11:08:55.627 1013 1020 W statsd : StateTracker not found for state atom 805 09-03 11:08:55.627 1013 1020 W statsd : StateTracker not found for state atom 805 09-03 11:08:55.627 1013 1020 W statsd : StateTracker not found for state atom 805 09-03 11:08:55.627 1013 1020 W statsd : StateTracker not found for state atom 805 09-03 11:08:55.627 1013 1020 W statsd : StateTracker not found for state atom 805 09-03 11:08:55.627 1013 1020 W statsd : StateTracker not found for state atom 805 09-03 11:08:55.627 1013 1020 W statsd : StateTracker not found for state atom 805 09-03 11:08:55.627 1013 1020 W statsd : StateTracker not found for state atom 805 09-03 11:08:55.627 1013 1020 W statsd : StateTracker not found for state atom 805 09-03 11:08:55.627 1013 1020 W statsd : StateTracker not found for state atom 805 09-03 11:08:55.627 1013 1020 W statsd : StateTracker not found for state atom 805 09-03 11:08:55.627 1013 1020 W statsd : StateTracker not found for state atom 805 09-03 11:08:55.627 1013 1020 W statsd : StateTracker not found for state atom 805 09-03 11:08:55.627 1013 1020 W statsd : StateTracker not found for state atom 805 09-03 11:08:55.627 1013 1020 W statsd : StateTracker not found for state atom 805 09-03 11:08:55.627 1013 1020 W statsd : StateTracker not found for state atom 805 09-03 11:08:55.627 1013 1020 W statsd : StateTracker not found for state atom 805 09-03 11:08:55.627 1013 1020 W statsd : StateTracker not found for state atom 805 09-03 11:08:55.627 1013 1020 W statsd : StateTracker not found for state atom 805 09-03 11:08:55.627 1013 1020 W statsd : StateTracker not found for state atom 805 09-03 11:08:55.627 1013 1020 W statsd : StateTracker not found for state atom 805 09-03 11:08:55.627 1013 1020 W statsd : StateTracker not found for state atom 805 09-03 11:08:55.627 1013 1020 W statsd : StateTracker not found for state atom 805 09-03 11:08:55.627 1013 1020 W statsd : StateTracker not found for state atom 805 09-03 11:08:55.627 1013 1020 W statsd : StateTracker not found for state atom 805 09-03 11:08:55.627 1013 1020 W statsd : StateTracker not found for state atom 805 09-03 11:08:55.627 1013 1020 W statsd : StateTracker not found for state atom 805 09-03 11:08:55.627 1013 1020 W statsd : StateTracker not found for state atom 805 09-03 11:08:55.627 1013 1020 W statsd : StateTracker not found for state atom 805 09-03 11:08:55.627 1013 1020 W statsd : StateTracker not found for state atom 805 09-03 11:08:55.627 1013 1020 W statsd : StateTracker not found for state atom 805 09-03 11:08:55.627 1013 1020 W statsd : StateTracker not found for state atom 805 09-03 11:08:55.627 1013 1020 W statsd : StateTracker not found for state atom 805 09-03 11:08:55.627 1013 1020 W statsd : StateTracker not found for state atom 805 09-03 11:08:55.627 1013 1020 W statsd : StateTracker not found for state atom 805 09-03 11:08:55.627 1013 1020 W statsd : StateTracker not found for state atom 805 09-03 11:08:55.627 1013 1020 W statsd : StateTracker not found for state atom 805 09-03 11:08:55.627 1013 1020 W statsd : StateTracker not found for state atom 805 09-03 11:08:55.629 1013 1020 W statsd : StateTracker not found for state atom 805 09-03 11:08:55.629 1013 1020 W statsd : StateTracker not found for state atom 805 09-03 11:08:55.629 4606 4606 W JobService: onNetworkChanged() not implemented in de.dwd.warnapp.gpspush.modern.BackgroundLocationJobService. Must override in a subclass. 09-03 11:08:55.636 1013 1020 W statsd : StateTracker not found for state atom 805 09-03 11:08:55.636 1013 1020 W statsd : StateTracker not found for state atom 805 09-03 11:08:55.636 1013 1020 W statsd : StateTracker not found for state atom 805 09-03 11:08:55.636 3239 3344 D IwlanDataService: onCapabilitiesChanged: 151 [ Transports: WIFI Capabilities: NOT_METERED&INTERNET&NOT_RESTRICTED&TRUSTED&NOT_VPN&VALIDATED&NOT_ROAMING&FOREGROUND&NOT_CONGESTED&NOT_SUSPENDED&NOT_VCN_MANAGED&NOT_BANDWIDTH_CONSTRAINED LinkUpBandwidth>=2800Kbps LinkDnBandwidth>=33572Kbps TransportInfo: , Provider friendly name: , Requesting package name: "Fritz!Box 7590 AX"wpa2-pskMLO Information: , Is TID-To-Link negotiation supported by the AP: false, AP MLD Address: , AP MLO Link Id: , AP MLO Affiliated links: , Vendor Data: > SignalStrength: -62 OwnerUid: 1000 AdminUids: [1000] SSID: "Fritz!Box 7590 AX" UnderlyingNetworks: Null] 09-03 11:08:55.636 3239 3344 D IwlanDataService: Network 151 connected using transport WIFI 09-03 11:08:55.638 3239 3530 D IwlanNetworkService: onCapabilitiesChanged: 151 09-03 11:08:55.639 3462 3508 D DeviceStateHelper: Wifi freq: 2437 09-03 11:08:55.641 2903 4032 D SHANNON_RCS: 9713 [0][PROX][TELE] onCapabilitiesChanged, Network: 151, NetworkCapabilities: [ Transports: WIFI Capabilities: NOT_METERED&INTERNET&NOT_RESTRICTED&TRUSTED&NOT_VPN&VALIDATED&NOT_ROAMING&FOREGROUND&NOT_CONGESTED&NOT_SUSPENDED&NOT_VCN_MANAGED&NOT_BANDWIDTH_CONSTRAINED LinkUpBandwidth>=2800Kbps LinkDnBandwidth>=33572Kbps TransportInfo: , BSSID: 02:00:00:00:00:00, MAC: 02:00:00:00:00:00, IP: /192.168.188.71, Security type: 2, Supplicant state: COMPLETED, Wi-Fi standard: 11n, RSSI: -62, Link speed: 173Mbps, Tx Link speed: 173Mbps, Max Supported Tx Link speed: 144Mbps, Rx Link speed: 130Mbps, Max Supported Rx Link speed: 144Mbps, Frequency: 2437MHz, Net ID: -1, Metered hint: false, score: 60, isUsable: true, CarrierMerged: false, SubscriptionId: -1, IsPrimary: -1, Trusted: true, Restricted: false, Ephemeral: false, OEM paid: false, OEM private: false, OSU AP: false, FQDN: , Provider friendly name: , Requesting package name: MLO Information: , Is TID-To-Link negotiation supported by the AP: false, AP MLD Address: , AP MLO Link Id: , AP MLO Affiliated links: , Vendor Data: > SignalStrength: -62 UnderlyingNetworks: Null] (DefaultNetworkCallback%onCapabilitiesChanged:76) 09-03 11:08:55.642 2931 4063 W SHANNON_IMS: 7886 [CONN] NetworkSpecifier and TransportInfo are not valid (DefaultNetworkCallback%updateSubscriptionId:89) 09-03 11:08:55.642 2931 4063 I SHANNON_IMS: 7887 [CONN] network:151 WIFI connected, subId: -1 [SLID:0] (DefaultNetworkCallback%onCapabilitiesChanged:71) 09-03 11:08:55.643 3462 11216 I WifiSignalInfo: onCapabilitiesChanged: rssi:-62 09-03 11:08:55.646 24361 24453 I BugleRcsEngine: handleMessage processing message:[NOTIFY_UPTIME_IGNORE_STATE_CHANGED] with [non-null]:RcsEngineImpl reference [CONTEXT log_prefix="RcsEngineImpl[DUAL_REG]:[8d8dc52d-0216]>Handler" thread_id=71 ] 09-03 11:08:55.647 4427 5555 D WM-NetworkStateTracker: Network capabilities changed: [ Transports: WIFI Capabilities: NOT_METERED&INTERNET&NOT_RESTRICTED&TRUSTED&NOT_VPN&VALIDATED&NOT_ROAMING&FOREGROUND&NOT_CONGESTED&NOT_SUSPENDED&NOT_VCN_MANAGED&NOT_BANDWIDTH_CONSTRAINED LinkUpBandwidth>=2800Kbps LinkDnBandwidth>=33572Kbps TransportInfo: , BSSID: 02:00:00:00:00:00, MAC: 02:00:00:00:00:00, IP: /192.168.188.71, Security type: 2, Supplicant state: COMPLETED, Wi-Fi standard: 11n, RSSI: -62, Link speed: 173Mbps, Tx Link speed: 173Mbps, Max Supported Tx Link speed: 144Mbps, Rx Link speed: 130Mbps, Max Supported Rx Link speed: 144Mbps, Frequency: 2437MHz, Net ID: -1, Metered hint: false, score: 60, isUsable: true, CarrierMerged: false, SubscriptionId: -1, IsPrimary: -1, Trusted: true, Restricted: false, Ephemeral: false, OEM paid: false, OEM private: false, OSU AP: false, FQDN: , Provider friendly name: , Requesting package name: MLO Information: , Is TID-To-Link negotiation supported by the AP: false, AP MLD Address: , AP MLO Link Id: , AP MLO Affiliated links: , Vendor Data: > SignalStrength: -62 UnderlyingNetworks: Null] 09-03 11:08:55.647 24361 24392 I BugleRcsEngine: Connected state: [1], networkType: [WIFI] [CONTEXT thread_id=60 ] 09-03 11:08:55.650 24361 24453 I BugleRcsEngine: Cannot use CS.apk as it's battery optimized [CONTEXT thread_id=71 ] 09-03 11:08:55.652 27764 27981 I ChimeraSrvcProxy: NullBinder for android.net.action.RECOMMEND_NETWORKS triggering remote TransactionTooLargeException due to Service without Chimera impl 09-03 11:08:55.652 27764 27981 W .gms.persistent: Large reply transaction of 1056768 bytes, interface descriptor , code 1 09-03 11:08:55.662 24361 24453 I BugleRcsEngine: Rcs is enabled from user settings: true [CONTEXT log_prefix="ProvisioningEngineDataRetriever" thread_id=71 ] 09-03 11:08:55.671 1109 1109 I WifiHAL : Creating message to get link statistics; iface = 47 09-03 11:08:55.672 2830 3512 D GRIL-S : [4482]< UPDATE_SCONE_INFO 09-03 11:08:55.674 1112 10968 D audioserver: logFgsApiEnd: FGS Logger Transaction failed, -129 09-03 11:08:55.675 1037 1061 D sound_trigger_hw: handle_put_data_tunnel: enter, is_voice_recognition = false 09-03 11:08:55.675 1037 1061 D sound_trigger_platform: st_platform_stop_buffering: type: 1 09-03 11:08:55.677 1037 26149 D audio_hw_patch: patch_release_audio_patch: patch handle 986 09-03 11:08:55.693 1112 20189 D audioserver: logFgsApiEnd: FGS Logger Transaction failed, -129 09-03 11:08:55.721 1013 1020 W statsd : StateTracker not found for state atom 805 09-03 11:08:55.724 1843 1843 I pixel-thermal: smpl_gm:0 raw data: smpl_gm:0 09-03 11:08:55.724 1843 1843 I pixel-thermal: vdroop2:0 raw data: vdroop2:0 09-03 11:08:55.725 1843 1843 I pixel-thermal: FLASH_LED_REDUCE:1 raw data: FLASH_LED_REDUCE:1 battery:30900 battery_cycle:423 vdroop1:0 09-03 11:08:55.725 1843 1843 I pixel-thermal: critical-battery-cell:1 raw data: battery:30900 battery_cycle:423 critical-battery-cell:1 vdroop1:0 09-03 11:08:55.726 1843 1843 I pixel-thermal: VIRTUAL-USB-UI:0 raw data: USB2-MINUS-QI:-4319 USB2-MINUS-USB:-23 VIRTUAL-USB-UI:0 qi_therm:37827 usb_pwr_therm:33531 usb_pwr_therm2:33508 09-03 11:08:55.727 1843 1843 I pixel-thermal: VIRTUAL-SKIN:35.5694 raw data: VIRTUAL-GNSS-BATT:32168.5 VIRTUAL-NEUTRAL-QI:31074 VIRTUAL-QI-DISP:35569.4 VIRTUAL-SKIN:35569.4 VIRTUAL-USB2-BATT:32030.4 battery:30900 disp_therm:32818 gnss_tcxo_therm:30437 neutral_therm:31572 qi_therm:37827 usb_pwr_therm2:33508 09-03 11:08:55.728 1843 1843 I pixel-thermal: vdroop1:0 raw data: vdroop1:0 09-03 11:08:55.728 1843 1843 I pixel-thermal: quiet_therm:30.637 raw data: quiet_therm:30637 09-03 11:08:55.728 1843 1843 I pixel-thermal: batoilo:0 raw data: batoilo:0 09-03 11:08:55.729 1843 1843 I pixel-thermal: cellular-emergency:35.5694 raw data: VIRTUAL-SKIN:35569.4 cellular-emergency:35569.4 09-03 11:08:55.729 1843 1843 I pixel-thermal: usb_pwr_therm2:33.508 raw data: usb_pwr_therm2:33508 09-03 11:08:55.732 2577 2577 W FrameTracker: Missed SF frame:UNKNOWN: 80, 80306311, 0, CUJ=J 09-03 11:08:55.732 2577 2577 W FrameTracker: Missing HWUI jank callback for vsyncId: 80306311, CUJ=J 09-03 11:08:55.732 2577 2577 W FrameTracker: Missed SF frame:UNKNOWN: 68, 80306375, 0, CUJ=J 09-03 11:08:55.732 2577 2577 W FrameTracker: Missing HWUI jank callback for vsyncId: 80306375, CUJ=J 09-03 11:08:55.732 2577 2577 W FrameTracker: Missed SF frame:UNKNOWN: 80, 80306403, 0, CUJ=J 09-03 11:08:55.732 2577 2577 W FrameTracker: Missing HWUI jank callback for vsyncId: 80306403, CUJ=J 09-03 11:08:55.732 2577 2577 W FrameTracker: Missed SF frame:UNKNOWN: 80, 80306417, 0, CUJ=J 09-03 11:08:55.732 2577 2577 W FrameTracker: Missing HWUI jank callback for vsyncId: 80306417, CUJ=J 09-03 11:08:55.732 2577 2577 W FrameTracker: Missed SF frame:UNKNOWN: 80, 80306431, 90083903, CUJ=J 09-03 11:08:55.732 2577 2577 W FrameTracker: Missed App frame:JANK_APP_DEADLINE_MISSED, 80306584, 22753822, CUJ=J 09-03 11:08:55.732 2577 2615 V PerfettoTrigger: Triggering /system/bin/trigger_perfetto com.android.telemetry.interaction-jank-monitor-17 09-03 11:08:55.771 4197 5768 I NnfpMusicRecognizer: Finished recognition for region de and DB version 3098 with result: NOT_MUSIC 09-03 11:08:55.771 4197 5768 I RecognitionStateHandler: Dsp model overrides: reloadDetectionModel = [false] : new squelch = [55000] 09-03 11:08:55.771 4197 5768 I RecognitionStateHandler: Reconfiguration [null] Latest:[# xcq@e9a7e4ea] 09-03 11:08:55.772 4197 5768 I MusicRecognitionHandler: Music recognition was skipped, because secondary music detector score below threshold (= 0.45) 09-03 11:08:55.776 4197 5768 I AmbientMusicDetector: Pipeline run finished, restarting model: true 09-03 11:08:55.781 4197 5768 I AmbientMusicServiceMana: Trying to load model /product/etc/firmware/music_detector.sound_model_tflite from 09-03 11:08:55.781 4197 5768 I AmbientMusicServiceMana: Applying config override: # xcm@cb31000b 09-03 11:08:55.785 4197 5768 I SmoothedLatchingClassif: Reconfiguring tflite model. 09-03 11:08:55.785 4197 5768 I SoundTriggerManagerComp: Loading model 9f6ad62a-1f0b-11e7-87c5-40a8f03d3f15 09-03 11:08:55.785 1037 26149 D sound_trigger_hw: stdev_stop_recognition: entry handle 1 09-03 11:08:55.785 1037 26149 D sound_trigger_platform: enable_ambient_music: enable 0 09-03 11:08:55.786 1037 26149 D sound_trigger_hw: [1] stop sound model 09-03 11:08:55.786 1037 26149 D sound_trigger_hw: send_control_cmd:cmd 1 09-03 11:08:55.786 1037 1549 D sound_trigger_hw: handle_control_cmd:get cmd 1 09-03 11:08:55.786 1324 1324 D AOC : F1:MSG: controller.cc, 273: AudioInCtrl: cmd ID: 0x0101, tag: 0x4f [cntr = 6754] 09-03 11:08:55.786 1324 1324 D AOC : F1:MSG: controller.cc, 811: AudioInCtrl: ipc: sound_trigger, cmd ID: 0x0101, tag: 0x4f, rc: 0 09-03 11:08:55.786 1037 26149 D sound_trigger_hw: stdev_unload_sound_model: entry handle 1 09-03 11:08:55.786 1037 26149 D sound_trigger_hw: [1] unload sound model 09-03 11:08:55.787 1324 1324 D AOC : F1:MSG: controller.cc, 273: AudioInCtrl: cmd ID: 0x0109, tag: 0x50 [cntr = 6755] 09-03 11:08:55.795 1037 26149 D sound_trigger_hw: release client handle 1 09-03 11:08:55.795 1324 1324 D AOC : F1:MSG: controller.cc, 811: AudioInCtrl: ipc: sound_trigger, cmd ID: 0x0109, tag: 0x50, rc: 0 09-03 11:08:55.797 4197 5768 I AmbientMusicServiceMana: Re-starting music detector model. 09-03 11:08:55.799 1037 26149 D sound_trigger_hw: stdev_load_sound_model: entry 09-03 11:08:55.799 1037 26149 D sound_trigger_hw: get client handle: 1 09-03 11:08:55.799 1037 26149 D sound_trigger_hw: [1] load sound model 09-03 11:08:55.799 1324 1324 D AOC : F1:MSG: controller.cc, 273: AudioInCtrl: cmd ID: 0x0107, tag: 0x51 [cntr = 6756] 09-03 11:08:55.801 1324 1324 D AOC : F1:MSG: hotword_input_processor.cc, 754: Update size of ambient model as 16384 09-03 11:08:55.801 1324 1324 D AOC : F1:MSG: controller.cc, 811: AudioInCtrl: ipc: sound_trigger, cmd ID: 0x0107, tag: 0x51, rc: 0 09-03 11:08:55.801 1324 1324 D AOC : F1:MSG: controller.cc, 273: AudioInCtrl: cmd ID: 0x0107, tag: 0x52 [cntr = 6757] 09-03 11:08:55.802 1324 1324 D AOC : F1:MSG: hotword_input_processor.cc, 754: Update size of ambient model as 22932 09-03 11:08:55.802 1324 1324 D AOC : F1:MSG: controller.cc, 811: AudioInCtrl: ipc: sound_trigger, cmd ID: 0x0107, tag: 0x52, rc: 0 09-03 11:08:55.802 1324 1324 D AOC : F1:MSG: controller.cc, 273: AudioInCtrl: cmd ID: 0x0108, tag: 0x53 [cntr = 6758] 09-03 11:08:55.803 1324 1324 D AOC : F1:ERR: hotword_input_processor.cc, 849: Wrong CRC: 0x1e8da48d, expected: 0x24789a4f 09-03 11:08:55.805 1324 1324 D AOC : F1:MSG: controller.cc, 811: AudioInCtrl: ipc: sound_trigger, cmd ID: 0x0108, tag: 0x53, rc: 0 09-03 11:08:55.806 2577 2577 I KeyguardTransitionRepository: FINISHED transition: TransitionStep(from=LOCKSCREEN, to=PRIMARY_BOUNCER, value=1.0, transitionState=FINISHED, ownerName=FromLockscreenTransitionInteractor(#listenForLockscreenToPrimaryBouncer)) 09-03 11:08:55.807 1037 26149 D sound_trigger_hw: stdev_start_recognition_extended: HIDL 2.3 09-03 11:08:55.807 1037 26149 D sound_trigger_hw: stdev_start_recognition_internal: entry handle 1 09-03 11:08:55.807 1037 26149 D sound_trigger_hw: [1] start sound model 09-03 11:08:55.807 1037 26149 D sound_trigger_platform: enable_ambient_music: enable 1 09-03 11:08:55.807 1037 26149 D sound_trigger_hw: send_control_cmd:cmd 1 09-03 11:08:55.807 1037 1549 D sound_trigger_hw: handle_control_cmd:get cmd 1 09-03 11:08:55.807 1324 1324 D AOC : F1:MSG: controller.cc, 273: AudioInCtrl: cmd ID: 0x0100, tag: 0x54 [cntr = 6759] 09-03 11:08:55.808 1324 1324 D AOC : F1:MSG: controller.cc, 811: AudioInCtrl: ipc: sound_trigger, cmd ID: 0x0100, tag: 0x54, rc: 0 09-03 11:08:55.808 4197 5768 I AmbientMusicServiceMana: Trying to unloading model 12caddb1-acdb-4dce-8cb0-2e95a2313aee 09-03 11:08:55.808 4197 5768 I SoundTriggerManagerComp: Unloading model 12caddb1-acdb-4dce-8cb0-2e95a2313aee 09-03 11:08:55.813 4197 5768 I AmbientMusicServiceMana: Update config: # xcm@cb31000b Success: true 09-03 11:08:55.879 10922 10942 D Benchmark: Usable output directory: /storage/emulated/0/Android/media/com.example.baselineprofile 09-03 11:08:55.879 10922 10942 D Benchmark: Output Directory: /sdcard/Android/media/com.example.baselineprofile/additional_test_output 09-03 11:08:55.895 4184 4881 W MediaProvider: isAppCloneUserPair for user 0: false 09-03 11:08:55.915 4184 6669 W MediaProvider: isAppCloneUserPair for user 0: false 09-03 11:08:55.972 4184 10897 I MediaProvider: Deleted 1 0 0 0 0 0 0 items on external_primary due to com.example.baselineprofile 09-03 11:08:55.978 27764 10817 I NetworkScheduler.Stats: (REDACTED) Task %s/%s started execution. cause:%s exec_start_elapsed_seconds: %s 09-03 11:08:55.983 27764 10721 I NetworkScheduler.Stats: (REDACTED) Task %s/%s finished executing. cause:%s result: %s elapsed_millis: %s uptime_millis: %s exec_start_elapsed_seconds: %s 09-03 11:08:56.022 4184 4881 W MediaProvider: Ignoring param:deletedata post U for external apps 09-03 11:08:56.024 4184 4881 I MediaProvider: Invalidating LocalCallingIdentity cache for package com.example.baselineprofile. Reason: owned_database_row_deleted:/storage/emulated/0/Android/media/com.example.baselineprofile/temporaryScript44459667538488997.tmp 09-03 11:08:56.034 4184 4881 I MediaProvider: Deleted 1 0 0 0 0 0 0 items on external_primary due to com.android.shell 09-03 11:08:56.037 27764 10713 I NetworkScheduler.Stats: (REDACTED) Task %s/%s started execution. cause:%s exec_start_elapsed_seconds: %s 09-03 11:08:56.044 27254 3955 I PlayGamesServices[GamesSyncAdapter]: Starting sync for 194dbad1 09-03 11:08:56.047 27254 3955 I PlayGamesServices[GamesSyncAdapter]: Sync duration for 194dbad1: 1 09-03 11:08:56.048 27764 10721 I NetworkScheduler.Stats: (REDACTED) Task %s/%s started execution. cause:%s exec_start_elapsed_seconds: %s 09-03 11:08:56.055 4184 6669 W MediaProvider: isAppCloneUserPair for user 0: false 09-03 11:08:56.059 27764 10721 I NetworkScheduler.Stats: (REDACTED) Task %s/%s finished executing. cause:%s result: %s elapsed_millis: %s uptime_millis: %s exec_start_elapsed_seconds: %s 09-03 11:08:56.069 27254 3998 I PlayGamesServices[GamesSyncAdapter]: Starting sync for ce96bccd 09-03 11:08:56.073 27254 3998 I PlayGamesServices[GamesSyncAdapter]: Sync duration for ce96bccd: 4 09-03 11:08:56.077 27254 9519 W PlayGamesServices[UiUtils]: AppVersion: Cannot find the installed destination app. 09-03 11:08:56.077 27254 9519 I GetServiceValidatorImpl: (REDACTED) onGetService() from Client SDK version [%s], Module version [%s], PGA version [%s], Account [%s], Calling package [%s], Game package [%s] 09-03 11:08:56.080 27254 9519 W GetGamesServiceReqFacto: Parsing request with ignored arguments. signInOptionsBundle [Bundle[{com.google.android.gms.signin.internal.serverClientId=null, com.google.android.gms.signin.internal.usePromptModeForAuthCode=true, com.google.android.gms.signin.internal.logSessionId=null, com.google.android.gms.signin.internal.hostedDomain=null, com.google.android.gms.signin.internal.offlineAccessRequested=false, com.google.android.gms.signin.internal.waitForAccessTokenRefresh=false, com.google.android.gms.signin.internal.clientRequestedAccount=Account {name=<>, type=com.google}, com.google.android.gms.signin.internal.idTokenRequested=false, com.google.android.gms.signin.internal.forceCodeForRefreshToken=false}]], isRetryingSignIn [false], skipWelcomePopup [false], requireGooglePlus [false] 09-03 11:08:56.080 27254 9519 I GetGamesServiceReqFacto: Replacing request for `games` scope with `games_lite` 09-03 11:08:56.086 1647 4271 I ArtService: Job is not running 09-03 11:08:56.092 27254 11015 I SignInPerformer-6: (REDACTED) Handling request [%s], isFirstPartyCaller [%s] 09-03 11:08:56.093 27254 11015 I SignInPerformer-6: Successfully brokered first-party unauthenticated GamesService [CONTEXT service_id=1 ] 09-03 11:08:56.096 27254 11015 I GetGamesServiceV2Op: (REDACTED) Successfully brokered GamesService for request %s 09-03 11:08:56.098 27254 9519 W PlayGamesServices[UiUtils]: AppVersion: Cannot find the installed destination app. 09-03 11:08:56.099 27254 9519 W PlayGamesServices[UiUtils]: AppVersionName: Cannot find the installed destination app. 09-03 11:08:56.102 27254 9519 W PlayGamesServices[UiUtils]: AppVersion: Cannot find the installed destination app. 09-03 11:08:56.103 27254 3955 I PlayGamesServices[GamesSyncAdapter]: Starting sync for d5476274 09-03 11:08:56.103 4184 30886 W MediaProvider: Ignoring param:deletedata post U for external apps 09-03 11:08:56.104 27254 9519 W PlayGamesServices[UiUtils]: AppVersionName: Cannot find the installed destination app. 09-03 11:08:56.105 27764 10820 I NetworkScheduler.Stats: (REDACTED) Task %s/%s started execution. cause:%s exec_start_elapsed_seconds: %s 09-03 11:08:56.107 4184 30886 I MediaProvider: Invalidating LocalCallingIdentity cache for package com.example.baselineprofile. Reason: owned_database_row_deleted:/storage/emulated/0/Android/media/com.example.baselineprofile/temporaryScript5758376879288898301.tmp 09-03 11:08:56.108 27254 3955 I PlayGamesServices[GamesSyncAdapter]: Sync duration for d5476274: 4 09-03 11:08:56.109 4184 30886 I MediaProvider: Deleted 1 0 0 0 0 0 0 items on external_primary due to com.android.shell 09-03 11:08:56.110 27764 10820 I NetworkScheduler.Stats: (REDACTED) Task %s/%s finished executing. cause:%s result: %s elapsed_millis: %s uptime_millis: %s exec_start_elapsed_seconds: %s 09-03 11:08:56.112 10922 10942 D Benchmark: Killing process com.example.composeplayground 09-03 11:08:56.112 10922 10942 D UiDevice: Executing shell command: am force-stop com.example.composeplayground 09-03 11:08:56.120 27254 9519 W PlayGamesServices[UiUtils]: AppVersion: Cannot find the installed destination app. 09-03 11:08:56.120 27254 9519 W PlayGamesServices[UiUtils]: AppVersionName: Cannot find the installed destination app. 09-03 11:08:56.155 2948 2948 D CarrierSvcBindHelper: onHandleForceStop: [com.example.composeplayground] 09-03 11:08:56.155 2948 3201 D EuiccConnector: handleMessage: E msg.what=1 09-03 11:08:56.156 2948 3201 D EuiccConnector: processMsg: ConnectedState 09-03 11:08:56.156 2948 3201 D EuiccConnector: processMsg: DisconnectedState 09-03 11:08:56.157 2948 2948 D CarrierSvcBindHelper: No carrier app for: 0 09-03 11:08:56.157 2948 2948 D CarrierSvcBindHelper: No carrier app for: 1 09-03 11:08:56.159 27764 10713 I NetworkScheduler.Stats: (REDACTED) Task %s/%s finished executing. cause:%s result: %s elapsed_millis: %s uptime_millis: %s exec_start_elapsed_seconds: %s 09-03 11:08:56.161 2948 3201 D EuiccConnector: handleMessage: X 09-03 11:08:56.165 27764 10713 I NetworkScheduler.Stats: (REDACTED) Task %s/%s started execution. cause:%s exec_start_elapsed_seconds: %s 09-03 11:08:56.167 27764 10713 I NetworkScheduler.Stats: (REDACTED) Task %s/%s finished executing. cause:%s result: %s elapsed_millis: %s uptime_millis: %s exec_start_elapsed_seconds: %s 09-03 11:08:56.204 10922 10942 D Benchmark: Clearing ART profiles for com.example.composeplayground 09-03 11:08:56.204 10922 10942 D Benchmark: Resetting profiles for com.example.composeplayground 09-03 11:08:56.215 4184 10897 W MediaProvider: isAppCloneUserPair for user 0: false 09-03 11:08:56.248 27764 10821 I NetworkScheduler.Stats: (REDACTED) Task %s/%s started execution. cause:%s exec_start_elapsed_seconds: %s 09-03 11:08:56.252 9456 9459 I artd : Dex parent of /data/app/~~2vm_r7Q14Id3z1VGG8fJ5A==/com.example.composeplayground-L2JKaaJyL2hVvyWLFzk21Q==/base.apk is not writable: Permission denied 09-03 11:08:56.252 9456 9459 I artd : GetBestInfo checking odex in dalvik-cache (/data/dalvik-cache/arm64/data@app@~~2vm_r7Q14Id3z1VGG8fJ5A==@com.example.composeplayground-L2JKaaJyL2hVvyWLFzk21Q==@base.apk@classes.dex) 09-03 11:08:56.252 9456 9459 I artd : Loading /data/dalvik-cache/arm64/data@app@~~2vm_r7Q14Id3z1VGG8fJ5A==@com.example.composeplayground-L2JKaaJyL2hVvyWLFzk21Q==@base.apk@classes.dex with executable: 0 09-03 11:08:56.252 9456 9459 I artd : OatFileAssistant test for existing oat file /data/dalvik-cache/arm64/data@app@~~2vm_r7Q14Id3z1VGG8fJ5A==@com.example.composeplayground-L2JKaaJyL2hVvyWLFzk21Q==@base.apk@classes.dex: File /data/dalvik-cache/arm64/data@app@~~2vm_r7Q14Id3z1VGG8fJ5A==@com.example.composeplayground-L2JKaaJyL2hVvyWLFzk21Q==@base.apk@classes.vdex does not exist. 09-03 11:08:56.252 9456 9459 I artd : GetBestInfo checking odex next to the dex file (/data/app/~~2vm_r7Q14Id3z1VGG8fJ5A==/com.example.composeplayground-L2JKaaJyL2hVvyWLFzk21Q==/oat/arm64/base.odex) 09-03 11:08:56.252 9456 9459 I artd : Loading /data/app/~~2vm_r7Q14Id3z1VGG8fJ5A==/com.example.composeplayground-L2JKaaJyL2hVvyWLFzk21Q==/oat/arm64/base.odex with executable: 0 09-03 11:08:56.252 27764 10713 I NetworkScheduler.Stats: (REDACTED) Task %s/%s finished executing. cause:%s result: %s elapsed_millis: %s uptime_millis: %s exec_start_elapsed_seconds: %s 09-03 11:08:56.252 9456 9459 I artd : Successfully loaded /data/app/~~2vm_r7Q14Id3z1VGG8fJ5A==/com.example.composeplayground-L2JKaaJyL2hVvyWLFzk21Q==/oat/arm64/base.odex with executable: 0 09-03 11:08:56.252 9456 9459 I artd : /data/app/~~2vm_r7Q14Id3z1VGG8fJ5A==/com.example.composeplayground-L2JKaaJyL2hVvyWLFzk21Q==/oat/arm64/base.odex is kOatUpToDate with filter verify 09-03 11:08:56.252 9456 9459 I artd : Should recompile: targetFilterIsSame (current: verify, target: verify) 09-03 11:08:56.257 9456 9459 I artd : Running dex2oat: /apex/com.android.art/bin/art_exec --drop-capabilities --set-task-profile=Dex2OatBootComplete --set-priority=background --keep-fds=6:7:8:9:10 -- /apex/com.android.art/bin/dex2oat64 --zip-fd=6 --zip-location=/data/app/~~2vm_r7Q14Id3z1VGG8fJ5A==/com.example.composeplayground-L2JKaaJyL2hVvyWLFzk21Q==/base.apk --oat-fd=7 --oat-location=/data/app/~~2vm_r7Q14Id3z1VGG8fJ5A==/com.example.composeplayground-L2JKaaJyL2hVvyWLFzk21Q==/oat/arm64/base.odex --output-vdex-fd=8 --swap-fd=9 --class-loader-context=PCL[] --classpath-dir=/data/app/~~2vm_r7Q14Id3z1VGG8fJ5A==/com.example.composeplayground-L2JKaaJyL2hVvyWLFzk21Q== --input-vdex-fd=10 --instruction-set=arm64 --instruction-set-features=default --instruction-set-variant=cortex-a55 --compiler-filter=verify --compilation-reason=cmdline --compact-dex-level=none --max-image-block-size=524288 --resolve-startup-const-strings=true --generate-mini-debug-info --runtime-arg -Xtarget-sdk-version:34 --runtime-arg -Xhidden-api-policy:enabled --cpu-set=0,1,2,3,4,5,6,7 -j8 --runtime-arg -Xms64m --runtime-arg -Xmx512m --comments=app-name:com.example.composeplayground,app-version-name:1.0,app-version-code:1,art-version:350820260 09-03 11:08:56.257 9456 9459 I artd : Opened FDs: 6:/data/app/~~2vm_r7Q14Id3z1VGG8fJ5A==/com.example.composeplayground-L2JKaaJyL2hVvyWLFzk21Q==/base.apk 7:/data/app/~~2vm_r7Q14Id3z1VGG8fJ5A==/com.example.composeplayground-L2JKaaJyL2hVvyWLFzk21Q==/oat/arm64/base.odex.0GMhQw.tmp 8:/data/app/~~2vm_r7Q14Id3z1VGG8fJ5A==/com.example.composeplayground-L2JKaaJyL2hVvyWLFzk21Q==/oat/arm64/base.vdex.GWFAVf.tmp 9:/data/app/~~2vm_r7Q14Id3z1VGG8fJ5A==/com.example.composeplayground-L2JKaaJyL2hVvyWLFzk21Q==/oat/arm64/base.odex.swap.diNeBX.tmp 10:/data/app/~~2vm_r7Q14Id3z1VGG8fJ5A==/com.example.composeplayground-L2JKaaJyL2hVvyWLFzk21Q==/oat/arm64/base.vdex 09-03 11:08:56.274 11035 11035 I dex2oat64: MMAP_TRYLOCK is supported in uffd errno:2 addr:0x7354b0a000 size:4096 09-03 11:08:56.276 11035 11035 W dex2oat64: Obsolete flag --compact-dex-level ignored 09-03 11:08:56.277 11035 11035 W dex2oat64: Mismatch between dex2oat instruction set features to use (ISA: Arm64 Feature string: -a53,crc,lse,fp16,dotprod,-sve) and those from CPP defines (ISA: Arm64 Feature string: -a53,-crc,-lse,-fp16,-dotprod,-sve) for the command line: 09-03 11:08:56.277 11035 11035 W dex2oat64: /apex/com.android.art/bin/dex2oat64 --zip-fd=6 --zip-location=/data/app/~~2vm_r7Q14Id3z1VGG8fJ5A==/com.example.composeplayground-L2JKaaJyL2hVvyWLFzk21Q==/base.apk --oat-fd=7 --oat-location=/data/app/~~2vm_r7Q14Id3z1VGG8fJ5A==/com.example.composeplayground-L2JKaaJyL2hVvyWLFzk21Q==/oat/arm64/base.odex --output-vdex-fd=8 --swap-fd=9 --class-loader-context=PCL[] --classpath-dir=/data/app/~~2vm_r7Q14Id3z1VGG8fJ5A==/com.example.composeplayground-L2JKaaJyL2hVvyWLFzk21Q== --input-vdex-fd=10 --instruction-set=arm64 --instruction-set-features=default --instruction-set-variant=cortex-a55 --compiler-filter=verify --compilation-reason=cmdline --compact-dex-level=none --max-image-block-size=524288 --resolve-startup-const-strings=true --generate-mini-debug-info --runtime-arg -Xtarget-sdk-version:34 --runtime-arg -Xhidden-api-policy:enabled --cpu-set=0,1,2,3,4,5,6,7 -j8 --runtime-arg -Xms64m --runtime-arg -Xmx512m --comments=app-name:com.example.composeplayground,app-version-name:1.0,app-version-code:1,art-version:350820260 09-03 11:08:56.277 11035 11035 I dex2oat64: /apex/com.android.art/bin/dex2oat64 --output-vdex-fd=8 --class-loader-context=PCL[] --classpath-dir=/data/app/~~2vm_r7Q14Id3z1VGG8fJ5A==/com.example.composeplayground-L2JKaaJyL2hVvyWLFzk21Q== --input-vdex-fd=10 --compiler-filter=verify --compilation-reason=cmdline --compact-dex-level=none --max-image-block-size=524288 --resolve-startup-const-strings=true --generate-mini-debug-info --cpu-set=0,1,2,3,4,5,6,7 -j8 --comments=app-name:com.example.composeplayground,app-version-name:1.0,app-version-code:1,art-version:350820260 09-03 11:08:56.280 11035 11035 I dex2oat64: Using CollectorTypeCMC GC. 09-03 11:08:56.284 2577 2577 W FrameTracker: Missed App frame:JANK_APP_DEADLINE_MISSED, 80306584, 22753822, CUJ=J 09-03 11:08:56.471 11035 11035 I dex2oat64: dex2oat took 195.590ms (249.242ms cpu) (threads: 8) arena alloc=0B (0B) java alloc=8182KB (8379008B) native alloc=1831KB (1875360B) free=1020KB (1045200B) 09-03 11:08:56.484 9456 9459 I artd : dex2oat returned code 0 09-03 11:08:56.491 1647 2670 I ArtService: Dexopt result: [packageName = com.example.composeplayground] DexContainerFileDexoptResult{dexContainerFile=/data/app/~~2vm_r7Q14Id3z1VGG8fJ5A==/com.example.composeplayground-L2JKaaJyL2hVvyWLFzk21Q==/base.apk, primaryAbi=true, abi=arm64-v8a, actualCompilerFilter=verify, status=PERFORMED, dex2oatWallTimeMillis=-157751112, dex2oatCpuTimeMillis=270, sizeBytes=473468, sizeBeforeBytes=473468, extendedStatusFlags=[]} 09-03 11:08:56.516 4184 6852 W MediaProvider: Ignoring param:deletedata post U for external apps 09-03 11:08:56.518 4184 6852 I MediaProvider: Invalidating LocalCallingIdentity cache for package com.example.baselineprofile. Reason: owned_database_row_deleted:/storage/emulated/0/Android/media/com.example.baselineprofile/temporaryScript1897892543227719716.tmp 09-03 11:08:56.518 4184 6852 I MediaProvider: Deleted 1 0 0 0 0 0 0 items on external_primary due to com.android.shell 09-03 11:08:56.532 4184 6669 W MediaProvider: isAppCloneUserPair for user 0: false 09-03 11:08:56.571 27764 10821 I NetworkScheduler.Stats: (REDACTED) Task %s/%s started execution. cause:%s exec_start_elapsed_seconds: %s 09-03 11:08:56.578 27764 10820 I NetworkScheduler.Stats: (REDACTED) Task %s/%s finished executing. cause:%s result: %s elapsed_millis: %s uptime_millis: %s exec_start_elapsed_seconds: %s 09-03 11:08:56.619 4184 30886 W MediaProvider: Ignoring param:deletedata post U for external apps 09-03 11:08:56.620 4184 30886 I MediaProvider: Invalidating LocalCallingIdentity cache for package com.example.baselineprofile. Reason: owned_database_row_deleted:/storage/emulated/0/Android/media/com.example.baselineprofile/temporaryScript3671715015059936444.tmp 09-03 11:08:56.625 4184 30886 I MediaProvider: Deleted 1 0 0 0 0 0 0 items on external_primary due to com.android.shell 09-03 11:08:56.629 10922 10942 D Benchmark: Profile Installer - Skip File Operation: WRITE_SKIP_FILE 09-03 11:08:56.658 1647 4271 I ActivityManager: Broadcasting: Intent { act=androidx.profileinstaller.action.SKIP_FILE flg=0x400000 cmp=com.example.composeplayground/androidx.profileinstaller.ProfileInstallReceiver (has extras) } 09-03 11:08:56.660 1647 1756 V GrammaticalInflectionUtils: AttributionSource: android.content.AttributionSource@59aa0a13 does not have READ_SYSTEM_GRAMMATICAL_GENDER permission. 09-03 11:08:56.664 27764 10713 I NetworkScheduler.Stats: (REDACTED) Task %s/%s started execution. cause:%s exec_start_elapsed_seconds: %s 09-03 11:08:56.673 1015 1015 D Zygote : Forked child process 11071 09-03 11:08:56.675 27764 10820 I NetworkScheduler.Stats: (REDACTED) Task %s/%s finished executing. cause:%s result: %s elapsed_millis: %s uptime_millis: %s exec_start_elapsed_seconds: %s 09-03 11:08:56.694 11071 11071 I mposeplayground: Using CollectorTypeCMC GC. 09-03 11:08:56.701 1359 1388 I adbd : jdwp connection from 11071 09-03 11:08:56.705 11071 11071 D nativeloader: Load libframework-connectivity-tiramisu-jni.so using APEX ns com_android_tethering for caller /apex/com.android.tethering/javalib/framework-connectivity-t.jar: ok 09-03 11:08:56.720 11071 11071 I ActivityThread: Relaunch all activities: onCoreSettingsChange 09-03 11:08:56.722 11071 11071 D CompatChangeReporter: Compat change id reported: 242716250; UID 10457; state: ENABLED 09-03 11:08:56.729 11071 11071 D nativeloader: Configuring clns-7 for other apk /data/app/~~2vm_r7Q14Id3z1VGG8fJ5A==/com.example.composeplayground-L2JKaaJyL2hVvyWLFzk21Q==/base.apk. target_sdk_version=34, uses_libraries=, library_path=/data/app/~~2vm_r7Q14Id3z1VGG8fJ5A==/com.example.composeplayground-L2JKaaJyL2hVvyWLFzk21Q==/lib/arm64:/data/app/~~2vm_r7Q14Id3z1VGG8fJ5A==/com.example.composeplayground-L2JKaaJyL2hVvyWLFzk21Q==/base.apk!/lib/arm64-v8a, permitted_path=/data:/mnt/expand:/data/user/0/com.example.composeplayground 09-03 11:08:56.735 11071 11071 V GraphicsEnvironment: Currently set values for: 09-03 11:08:56.735 11071 11071 V GraphicsEnvironment: angle_gl_driver_selection_pkgs=[com.android.angle, com.linecorp.b612.android, com.campmobile.snow, com.google.android.apps.tachyon] 09-03 11:08:56.735 11071 11071 V GraphicsEnvironment: angle_gl_driver_selection_values=[angle, native, native, native] 09-03 11:08:56.735 11071 11071 V GraphicsEnvironment: com.example.composeplayground is not listed in per-application setting 09-03 11:08:56.735 11071 11071 V GraphicsEnvironment: Neither updatable production driver nor prerelease driver is supported. 09-03 11:08:56.763 11071 11071 D ProfileInstaller: RESULT_INSTALL_SKIP_FILE_SUCCESS 09-03 11:08:56.763 1647 1738 I ActivityManager: Broadcast completed: result=10 09-03 11:08:56.767 10922 10942 D Benchmark: Killing process com.example.composeplayground 09-03 11:08:56.767 10922 10942 D Benchmark: Killing process com.example.composeplayground 09-03 11:08:56.767 10922 10942 D UiDevice: Executing shell command: am force-stop com.example.composeplayground 09-03 11:08:56.817 10922 10942 D Benchmark: Profile Installer - Install profile 09-03 11:08:56.821 2948 2948 D CarrierSvcBindHelper: onHandleForceStop: [com.example.composeplayground] 09-03 11:08:56.822 2948 3201 D EuiccConnector: handleMessage: E msg.what=1 09-03 11:08:56.822 2948 3201 D EuiccConnector: processMsg: ConnectedState 09-03 11:08:56.822 2948 3201 D EuiccConnector: processMsg: DisconnectedState 09-03 11:08:56.822 2948 2948 D CarrierSvcBindHelper: No carrier app for: 0 09-03 11:08:56.823 2948 2948 D CarrierSvcBindHelper: No carrier app for: 1 09-03 11:08:56.823 2948 3201 D EuiccConnector: handleMessage: X 09-03 11:08:56.829 1359 1359 I adbd : Remote process closed the socket (on MSG_PEEK) 09-03 11:08:56.830 1015 1015 I Zygote : Process 11071 exited due to signal 9 (Killed) 09-03 11:08:56.830 1647 1759 I libprocessgroup: Removed cgroup /sys/fs/cgroup/uid_10457/pid_11071 09-03 11:08:56.832 1647 4271 I ActivityManager: Broadcasting: Intent { act=androidx.profileinstaller.action.INSTALL_PROFILE flg=0x400000 cmp=com.example.composeplayground/androidx.profileinstaller.ProfileInstallReceiver } 09-03 11:08:56.833 1647 1756 V GrammaticalInflectionUtils: AttributionSource: android.content.AttributionSource@59aa0a13 does not have READ_SYSTEM_GRAMMATICAL_GENDER permission. 09-03 11:08:56.848 1015 1015 D Zygote : Forked child process 11104 09-03 11:08:56.868 11104 11104 I mposeplayground: Using CollectorTypeCMC GC. 09-03 11:08:56.875 1359 1388 I adbd : jdwp connection from 11104 09-03 11:08:56.880 11104 11104 D nativeloader: Load libframework-connectivity-tiramisu-jni.so using APEX ns com_android_tethering for caller /apex/com.android.tethering/javalib/framework-connectivity-t.jar: ok 09-03 11:08:56.894 11104 11104 I ActivityThread: Relaunch all activities: onCoreSettingsChange 09-03 11:08:56.895 11104 11104 D CompatChangeReporter: Compat change id reported: 242716250; UID 10457; state: ENABLED 09-03 11:08:56.905 11104 11104 D nativeloader: Configuring clns-7 for other apk /data/app/~~2vm_r7Q14Id3z1VGG8fJ5A==/com.example.composeplayground-L2JKaaJyL2hVvyWLFzk21Q==/base.apk. target_sdk_version=34, uses_libraries=, library_path=/data/app/~~2vm_r7Q14Id3z1VGG8fJ5A==/com.example.composeplayground-L2JKaaJyL2hVvyWLFzk21Q==/lib/arm64:/data/app/~~2vm_r7Q14Id3z1VGG8fJ5A==/com.example.composeplayground-L2JKaaJyL2hVvyWLFzk21Q==/base.apk!/lib/arm64-v8a, permitted_path=/data:/mnt/expand:/data/user/0/com.example.composeplayground 09-03 11:08:56.910 11104 11104 V GraphicsEnvironment: Currently set values for: 09-03 11:08:56.910 11104 11104 V GraphicsEnvironment: angle_gl_driver_selection_pkgs=[com.android.angle, com.linecorp.b612.android, com.campmobile.snow, com.google.android.apps.tachyon] 09-03 11:08:56.910 11104 11104 V GraphicsEnvironment: angle_gl_driver_selection_values=[angle, native, native, native] 09-03 11:08:56.910 11104 11104 V GraphicsEnvironment: com.example.composeplayground is not listed in per-application setting 09-03 11:08:56.910 11104 11104 V GraphicsEnvironment: Neither updatable production driver nor prerelease driver is supported. 09-03 11:08:56.927 11104 11104 D ProfileInstaller: Installing profile for com.example.composeplayground 09-03 11:08:57.179 9449 9449 I ContentProtectionServic: CP service destroyed 09-03 11:08:57.300 11104 11104 D ProfileInstaller: RESULT_INSTALL_SUCCESS 09-03 11:08:57.311 1647 1738 I ActivityManager: Broadcast completed: result=1 09-03 11:08:57.316 10922 10942 D Benchmark: Killing process com.example.composeplayground 09-03 11:08:57.316 10922 10942 D Benchmark: Killing process com.example.composeplayground 09-03 11:08:57.316 10922 10942 D UiDevice: Executing shell command: am force-stop com.example.composeplayground 09-03 11:08:57.364 2948 2948 D CarrierSvcBindHelper: onHandleForceStop: [com.example.composeplayground] 09-03 11:08:57.364 2948 3201 D EuiccConnector: handleMessage: E msg.what=1 09-03 11:08:57.364 2948 3201 D EuiccConnector: processMsg: ConnectedState 09-03 11:08:57.364 2948 3201 D EuiccConnector: processMsg: DisconnectedState 09-03 11:08:57.365 2948 2948 D CarrierSvcBindHelper: No carrier app for: 0 09-03 11:08:57.365 2948 3201 D EuiccConnector: handleMessage: X 09-03 11:08:57.365 2948 2948 D CarrierSvcBindHelper: No carrier app for: 1 09-03 11:08:57.381 4184 10897 W MediaProvider: isAppCloneUserPair for user 0: false 09-03 11:08:57.395 1359 1359 I adbd : Remote process closed the socket (on MSG_PEEK) 09-03 11:08:57.395 1647 1759 I libprocessgroup: Removed cgroup /sys/fs/cgroup/uid_10457/pid_11104 09-03 11:08:57.396 1015 1015 I Zygote : Process 11104 exited due to signal 9 (Killed) 09-03 11:08:57.423 1647 2499 D InetDiagMessage: Destroyed 0 sockets, proto=IPPROTO_TCP, family=AF_INET, states=14 09-03 11:08:57.425 1647 2499 D InetDiagMessage: Destroyed 0 sockets, proto=IPPROTO_TCP, family=AF_INET6, states=14 09-03 11:08:57.426 1647 2499 D InetDiagMessage: Destroyed live tcp sockets for uids={10200} in 4ms 09-03 11:08:57.433 27764 10820 I NetworkScheduler.Stats: (REDACTED) Task %s/%s started execution. cause:%s exec_start_elapsed_seconds: %s 09-03 11:08:57.435 27764 10821 I NetworkScheduler.Stats: (REDACTED) Task %s/%s finished executing. cause:%s result: %s elapsed_millis: %s uptime_millis: %s exec_start_elapsed_seconds: %s 09-03 11:08:57.444 9456 9458 I artd : Running profman: /apex/com.android.art/bin/art_exec --drop-capabilities --keep-fds=6:7:8 -- /apex/com.android.art/bin/profman --profile-file-fd=6 --reference-profile-file-fd=7 --apk-fd=8 09-03 11:08:57.444 9456 9458 I artd : Opened FDs: 6:/data/misc/profiles/cur/0/com.example.composeplayground/primary.prof 7:/data/misc/profiles/ref/com.example.composeplayground/primary.prof.Hl03Rc.tmp 8:/data/app/~~2vm_r7Q14Id3z1VGG8fJ5A==/com.example.composeplayground-L2JKaaJyL2hVvyWLFzk21Q==/base.apk 09-03 11:08:57.523 9456 9458 I artd : profman returned code 1 09-03 11:08:57.525 9456 9458 I artd : Dex parent of /data/app/~~2vm_r7Q14Id3z1VGG8fJ5A==/com.example.composeplayground-L2JKaaJyL2hVvyWLFzk21Q==/base.apk is not writable: Permission denied 09-03 11:08:57.525 9456 9458 I artd : GetBestInfo checking odex in dalvik-cache (/data/dalvik-cache/arm64/data@app@~~2vm_r7Q14Id3z1VGG8fJ5A==@com.example.composeplayground-L2JKaaJyL2hVvyWLFzk21Q==@base.apk@classes.dex) 09-03 11:08:57.525 9456 9458 I artd : Loading /data/dalvik-cache/arm64/data@app@~~2vm_r7Q14Id3z1VGG8fJ5A==@com.example.composeplayground-L2JKaaJyL2hVvyWLFzk21Q==@base.apk@classes.dex with executable: 0 09-03 11:08:57.525 9456 9458 I artd : OatFileAssistant test for existing oat file /data/dalvik-cache/arm64/data@app@~~2vm_r7Q14Id3z1VGG8fJ5A==@com.example.composeplayground-L2JKaaJyL2hVvyWLFzk21Q==@base.apk@classes.dex: File /data/dalvik-cache/arm64/data@app@~~2vm_r7Q14Id3z1VGG8fJ5A==@com.example.composeplayground-L2JKaaJyL2hVvyWLFzk21Q==@base.apk@classes.vdex does not exist. 09-03 11:08:57.525 9456 9458 I artd : GetBestInfo checking odex next to the dex file (/data/app/~~2vm_r7Q14Id3z1VGG8fJ5A==/com.example.composeplayground-L2JKaaJyL2hVvyWLFzk21Q==/oat/arm64/base.odex) 09-03 11:08:57.525 9456 9458 I artd : Loading /data/app/~~2vm_r7Q14Id3z1VGG8fJ5A==/com.example.composeplayground-L2JKaaJyL2hVvyWLFzk21Q==/oat/arm64/base.odex with executable: 0 09-03 11:08:57.526 9456 9458 I artd : Successfully loaded /data/app/~~2vm_r7Q14Id3z1VGG8fJ5A==/com.example.composeplayground-L2JKaaJyL2hVvyWLFzk21Q==/oat/arm64/base.odex with executable: 0 09-03 11:08:57.526 9456 9458 I artd : /data/app/~~2vm_r7Q14Id3z1VGG8fJ5A==/com.example.composeplayground-L2JKaaJyL2hVvyWLFzk21Q==/oat/arm64/base.odex is kOatUpToDate with filter verify 09-03 11:08:57.526 9456 9458 I artd : Should recompile: targetFilterIsBetter (current: verify, target: speed-profile) 09-03 11:08:57.549 9456 9457 I artd : Running dex2oat: /apex/com.android.art/bin/art_exec --drop-capabilities --set-task-profile=Dex2OatBootComplete --set-priority=background --keep-fds=6:7:8:9:10:11:12 -- /apex/com.android.art/bin/dex2oat64 --zip-fd=6 --zip-location=/data/app/~~2vm_r7Q14Id3z1VGG8fJ5A==/com.example.composeplayground-L2JKaaJyL2hVvyWLFzk21Q==/base.apk --oat-fd=7 --oat-location=/data/app/~~2vm_r7Q14Id3z1VGG8fJ5A==/com.example.composeplayground-L2JKaaJyL2hVvyWLFzk21Q==/oat/arm64/base.odex --output-vdex-fd=8 --app-image-fd=9 --image-format=lz4 --swap-fd=10 --class-loader-context=PCL[] --classpath-dir=/data/app/~~2vm_r7Q14Id3z1VGG8fJ5A==/com.example.composeplayground-L2JKaaJyL2hVvyWLFzk21Q== --input-vdex-fd=11 --profile-file-fd=12 --instruction-set=arm64 --instruction-set-features=default --instruction-set-variant=cortex-a55 --compiler-filter=speed-profile --compilation-reason=cmdline --compact-dex-level=none --max-image-block-size=524288 --resolve-startup-const-strings=true --generate-mini-debug-info --runtime-arg -Xtarget-sdk-version:34 --runtime-arg -Xhidden-api-policy:enabled --cpu-set=0,1,2,3,4,5,6,7 -j8 --runtime-arg -Xms64m --runtime-arg -Xmx512m --comments=app-name:com.example.composeplayground,app-version-name:1.0,app-version-code:1,art-version:350820260 09-03 11:08:57.549 9456 9457 I artd : Opened FDs: 6:/data/app/~~2vm_r7Q14Id3z1VGG8fJ5A==/com.example.composeplayground-L2JKaaJyL2hVvyWLFzk21Q==/base.apk 7:/data/app/~~2vm_r7Q14Id3z1VGG8fJ5A==/com.example.composeplayground-L2JKaaJyL2hVvyWLFzk21Q==/oat/arm64/base.odex.bAFfWn.tmp 8:/data/app/~~2vm_r7Q14Id3z1VGG8fJ5A==/com.example.composeplayground-L2JKaaJyL2hVvyWLFzk21Q==/oat/arm64/base.vdex.jFSezy.tmp 9:/data/app/~~2vm_r7Q14Id3z1VGG8fJ5A==/com.example.composeplayground-L2JKaaJyL2hVvyWLFzk21Q==/oat/arm64/base.art.whUMUM.tmp 10:/data/app/~~2vm_r7Q14Id3z1VGG8fJ5A==/com.example.composeplayground-L2JKaaJyL2hVvyWLFzk21Q==/oat/arm64/base.odex.swap.VEcFmU.tmp 11:/data/app/~~2vm_r7Q14Id3z1VGG8fJ5A==/com.example.composeplayground-L2JKaaJyL2hVvyWLFzk21Q==/oat/arm64/base.vdex 12:/data/misc/profiles/ref/com.example.composeplayground/primary.prof.Hl03Rc.tmp 09-03 11:08:57.579 11144 11144 I dex2oat64: MMAP_TRYLOCK is supported in uffd errno:2 addr:0x7d68d02000 size:4096 09-03 11:08:57.580 11144 11144 W dex2oat64: Obsolete flag --compact-dex-level ignored 09-03 11:08:57.581 11144 11144 W dex2oat64: Mismatch between dex2oat instruction set features to use (ISA: Arm64 Feature string: -a53,crc,lse,fp16,dotprod,-sve) and those from CPP defines (ISA: Arm64 Feature string: -a53,-crc,-lse,-fp16,-dotprod,-sve) for the command line: 09-03 11:08:57.581 11144 11144 W dex2oat64: /apex/com.android.art/bin/dex2oat64 --zip-fd=6 --zip-location=/data/app/~~2vm_r7Q14Id3z1VGG8fJ5A==/com.example.composeplayground-L2JKaaJyL2hVvyWLFzk21Q==/base.apk --oat-fd=7 --oat-location=/data/app/~~2vm_r7Q14Id3z1VGG8fJ5A==/com.example.composeplayground-L2JKaaJyL2hVvyWLFzk21Q==/oat/arm64/base.odex --output-vdex-fd=8 --app-image-fd=9 --image-format=lz4 --swap-fd=10 --class-loader-context=PCL[] --classpath-dir=/data/app/~~2vm_r7Q14Id3z1VGG8fJ5A==/com.example.composeplayground-L2JKaaJyL2hVvyWLFzk21Q== --input-vdex-fd=11 --profile-file-fd=12 --instruction-set=arm64 --instruction-set-features=default --instruction-set-variant=cortex-a55 --compiler-filter=speed-profile --compilation-reason=cmdline --compact-dex-level=none --max-image-block-size=524288 --resolve-startup-const-strings=true --generate-mini-debug-info --runtime-arg -Xtarget-sdk-version:34 --runtime-arg -Xhidden-api-policy:enabled --cpu-set=0,1,2,3,4,5,6,7 -j8 --runtime-arg -Xms64m --runtime-arg -Xmx512m --comments=app-name:com.example.composeplayground,app-version-name:1.0,app-version-code:1,art-version:350820260 09-03 11:08:57.590 11144 11144 I dex2oat64: /apex/com.android.art/bin/dex2oat64 --output-vdex-fd=8 --class-loader-context=PCL[] --classpath-dir=/data/app/~~2vm_r7Q14Id3z1VGG8fJ5A==/com.example.composeplayground-L2JKaaJyL2hVvyWLFzk21Q== --input-vdex-fd=11 --profile-file-fd=12 --compiler-filter=speed-profile --compilation-reason=cmdline --compact-dex-level=none --max-image-block-size=524288 --resolve-startup-const-strings=true --generate-mini-debug-info --cpu-set=0,1,2,3,4,5,6,7 -j8 --comments=app-name:com.example.composeplayground,app-version-name:1.0,app-version-code:1,art-version:350820260 09-03 11:08:57.595 11144 11144 I dex2oat64: Using CollectorTypeCMC GC. 09-03 11:08:57.949 1647 2499 D InetDiagMessage: Destroyed 0 sockets, proto=IPPROTO_TCP, family=AF_INET, states=14 09-03 11:08:57.950 1647 2499 D InetDiagMessage: Destroyed 0 sockets, proto=IPPROTO_TCP, family=AF_INET6, states=14 09-03 11:08:57.951 1647 2499 D InetDiagMessage: Destroyed live tcp sockets for uids={10162} in 3ms 09-03 11:08:57.952 1647 2499 D InetDiagMessage: Destroyed 0 sockets, proto=IPPROTO_TCP, family=AF_INET, states=14 09-03 11:08:57.954 1647 2499 D InetDiagMessage: Destroyed 0 sockets, proto=IPPROTO_TCP, family=AF_INET6, states=14 09-03 11:08:57.954 1647 2499 D InetDiagMessage: Destroyed live tcp sockets for uids={20162} in 4ms 09-03 11:08:58.086 1647 2499 D InetDiagMessage: Destroyed 0 sockets, proto=IPPROTO_TCP, family=AF_INET, states=14 09-03 11:08:58.088 1647 2499 D InetDiagMessage: Destroyed 0 sockets, proto=IPPROTO_TCP, family=AF_INET6, states=14 09-03 11:08:58.088 1647 2499 D InetDiagMessage: Destroyed live tcp sockets for uids={10220} in 4ms 09-03 11:08:58.090 1647 2499 D InetDiagMessage: Destroyed 0 sockets, proto=IPPROTO_TCP, family=AF_INET, states=14 09-03 11:08:58.092 1647 2499 D InetDiagMessage: Destroyed 0 sockets, proto=IPPROTO_TCP, family=AF_INET6, states=14 09-03 11:08:58.092 1647 2499 D InetDiagMessage: Destroyed live tcp sockets for uids={20220} in 4ms 09-03 11:08:58.628 1109 1109 I WifiHAL : Creating message to get link statistics; iface = 47 09-03 11:08:58.714 1647 2499 D ConnectivityService: NetReassign [no changes] [c 2] [a 2] [i 4] 09-03 11:08:58.754 11144 11144 I dex2oat64: Explicit concurrent mark compact GC freed 6012KB AllocSpace bytes, 0(0B) LOS objects, 91% free, 2390KB/26MB, paused 16us,103us total 15.121ms 09-03 11:08:59.155 1647 2499 D InetDiagMessage: Destroyed 0 sockets, proto=IPPROTO_TCP, family=AF_INET, states=14 09-03 11:08:59.160 1647 2499 D InetDiagMessage: Destroyed 0 sockets, proto=IPPROTO_TCP, family=AF_INET6, states=14 09-03 11:08:59.160 1647 2499 D InetDiagMessage: Destroyed live tcp sockets for uids={10131} in 10ms 09-03 11:08:59.164 1647 2499 D InetDiagMessage: Destroyed 0 sockets, proto=IPPROTO_TCP, family=AF_INET, states=14 09-03 11:08:59.167 1647 2499 D InetDiagMessage: Destroyed 0 sockets, proto=IPPROTO_TCP, family=AF_INET6, states=14 09-03 11:08:59.167 1647 2499 D InetDiagMessage: Destroyed live tcp sockets for uids={20131} in 7ms 09-03 11:08:59.174 11144 11144 I dex2oat64: dex2oat took 1.594s (5.752s cpu) (threads: 8) arena alloc=10231KB (10477024B) java alloc=2390KB (2447968B) native alloc=28MB (29592704B) free=10MB (10954608B) 09-03 11:08:59.190 9456 9457 I artd : dex2oat returned code 0 09-03 11:08:59.201 1647 2670 I ArtService: Dexopt result: [packageName = com.example.composeplayground] DexContainerFileDexoptResult{dexContainerFile=/data/app/~~2vm_r7Q14Id3z1VGG8fJ5A==/com.example.composeplayground-L2JKaaJyL2hVvyWLFzk21Q==/base.apk, primaryAbi=true, abi=arm64-v8a, actualCompilerFilter=speed-profile, status=PERFORMED, dex2oatWallTimeMillis=-157749697, dex2oatCpuTimeMillis=5780, sizeBytes=12013932, sizeBeforeBytes=473468, extendedStatusFlags=[]} 09-03 11:08:59.249 4184 10897 W MediaProvider: Ignoring param:deletedata post U for external apps 09-03 11:08:59.252 4184 10897 I MediaProvider: Invalidating LocalCallingIdentity cache for package com.example.baselineprofile. Reason: owned_database_row_deleted:/storage/emulated/0/Android/media/com.example.baselineprofile/temporaryScript7455393615007934366.tmp 09-03 11:08:59.253 4184 10897 I MediaProvider: Deleted 1 0 0 0 0 0 0 items on external_primary due to com.android.shell 09-03 11:08:59.264 10922 10942 I System.out: startserver(1m) 09-03 11:08:59.267 10922 10942 D PerfettoCapture: Supported ABIs: arm64-v8a, armeabi-v7a, armeabi 09-03 11:08:59.281 4184 5455 W MediaProvider: isAppCloneUserPair for user 0: false 09-03 11:08:59.342 27764 10820 I NetworkScheduler.Stats: (REDACTED) Task %s/%s started execution. cause:%s exec_start_elapsed_seconds: %s 09-03 11:08:59.346 27764 10821 I NetworkScheduler.Stats: (REDACTED) Task %s/%s finished executing. cause:%s result: %s elapsed_millis: %s uptime_millis: %s exec_start_elapsed_seconds: %s 09-03 11:08:59.579 4184 6852 I MediaProvider: Deleted 1 0 0 0 0 0 0 items on external_primary due to com.example.baselineprofile 09-03 11:08:59.589 4184 30886 W MediaProvider: isAppCloneUserPair for user 0: false 09-03 11:08:59.628 27764 10820 I NetworkScheduler.Stats: (REDACTED) Task %s/%s started execution. cause:%s exec_start_elapsed_seconds: %s 09-03 11:08:59.638 10922 10942 D TrafficStats: tagSocket(104) with statsTag=0xffffffff, statsUid=-1 09-03 11:08:59.639 27764 10821 I NetworkScheduler.Stats: (REDACTED) Task %s/%s finished executing. cause:%s result: %s elapsed_millis: %s uptime_millis: %s exec_start_elapsed_seconds: %s 09-03 11:08:59.650 10922 10942 D TrafficStats: tagSocket(104) with statsTag=0xffffffff, statsUid=-1 09-03 11:08:59.659 10922 10942 D TrafficStats: tagSocket(104) with statsTag=0xffffffff, statsUid=-1 09-03 11:08:59.667 10922 10942 D TrafficStats: tagSocket(104) with statsTag=0xffffffff, statsUid=-1 09-03 11:08:59.676 10922 10942 D TrafficStats: tagSocket(104) with statsTag=0xffffffff, statsUid=-1 09-03 11:08:59.685 10922 10942 D TrafficStats: tagSocket(104) with statsTag=0xffffffff, statsUid=-1 09-03 11:08:59.693 10922 10942 D TrafficStats: tagSocket(104) with statsTag=0xffffffff, statsUid=-1 09-03 11:08:59.695 11178 11178 W perfetto: httpd.cc:102 [HTTP] Starting RPC server on localhost:9001 09-03 11:08:59.696 11178 11178 I perfetto: httpd.cc:107 [HTTP] This server can be used by reloading https://ui.perfetto.dev and clicking on YES on the "Trace Processor native acceleration" dialog or through the Python API (see https://perfetto.dev/docs/analysis/trace-processor#python-api). 09-03 11:08:59.701 10922 10942 D TrafficStats: tagSocket(104) with statsTag=0xffffffff, statsUid=-1 09-03 11:08:59.702 11178 11178 I perfetto: http_server.cc:83 [HTTP] New connection 09-03 11:08:59.703 11178 11178 I perfetto: http_server.cc:231 [HTTP] GET /status [body=0B, origin=""] 09-03 11:08:59.710 10922 10942 I PerfettoHttpServer: Perfetto trace processor shell server started (pid=11178). 09-03 11:08:59.711 10922 10942 D UiDevice: Turning on screen. 09-03 11:08:59.712 10922 10942 D UiDevice: Pressing home button. 09-03 11:08:59.725 2577 2577 I FalsingManager: {belief=0.20006906960055304 confidence=1.0} 09-03 11:09:00.015 4197 4638 I AiAiEcho: Predicting[8]: { Feature:1, creation:2024-09-03T08:24:31Z, expiry:2024-09-03T09:00:00Z, isExpired: true}, { Feature:1, creation:2024-09-03T09:00:00Z, expiry:2024-09-03T10:00:00Z, isExpired: false}, { Feature:1, creation:2024-09-03T10:00:00Z, expiry:2024-09-03T10:00:00Z, isExpired: true}, { Feature:1, creation:2024-09-03T11:00:00Z, expiry:2024-09-03T11:00:00Z, isExpired: true}, { Feature:1, creation:2024-09-03T12:00:00Z, expiry:2024-09-03T12:00:00Z, isExpired: true}, { Feature:1, creation:2024-09-03T13:00:00Z, expiry:2024-09-03T13:00:00Z, isExpired: true}, { Feature:1, creation:2024-09-03T14:00:00Z, expiry:2024-09-03T14:00:00Z, isExpired: true}, { Feature:2, creation:2024-09-03T14:45:00Z, expiry:2024-09-03T15:15:00Z, isExpired: true} [CONTEXT sampling_count=5 ] 09-03 11:09:00.017 4197 4638 I AiAiEcho: EchoTargets: Feature:1 09-03 11:09:00.017 4197 4638 I AiAiEcho: Filtered by AiAi flag check: 09-03 11:09:00.017 4197 4638 I AiAiEcho: [CONTEXT ratelimit_period="10 SECONDS" ] 09-03 11:09:00.017 4197 4638 I AiAiEcho: Ranked targets strategy: WEIGHTED, count: 1, ranking metadata: { cardId: WEATHER-569377244, card type: 1, update time: 2024-09-03T09:00:00Z, expiration time: 2024-09-03T10:00:00Z, ranking metadata: RankingMetaData(urgency=0.979, priority=0.3, recency=1.0, rankScore=0.2937, importantTimePoints=# vgn@7bca4) } [CONTEXT ratelimit_period="10 SECONDS" ] 09-03 11:09:00.018 4197 4638 I AiAiEcho: #weatherTwiddler: Weather removes SubtitleSupplementalItem in surface: lockscreen [CONTEXT ratelimit_period="10 SECONDS" ] 09-03 11:09:00.018 4197 4638 I AiAiEcho: #remoteViewsTwiddler: feature disabled. 09-03 11:09:00.018 4197 4638 I AiAiEcho: Predicting[8]: { Feature:1, creation:2024-09-03T08:24:31Z, expiry:2024-09-03T09:00:00Z, isExpired: true}, { Feature:1, creation:2024-09-03T09:00:00Z, expiry:2024-09-03T10:00:00Z, isExpired: false}, { Feature:1, creation:2024-09-03T10:00:00Z, expiry:2024-09-03T10:00:00Z, isExpired: true}, { Feature:1, creation:2024-09-03T11:00:00Z, expiry:2024-09-03T11:00:00Z, isExpired: true}, { Feature:1, creation:2024-09-03T12:00:00Z, expiry:2024-09-03T12:00:00Z, isExpired: true}, { Feature:1, creation:2024-09-03T13:00:00Z, expiry:2024-09-03T13:00:00Z, isExpired: true}, { Feature:1, creation:2024-09-03T14:00:00Z, expiry:2024-09-03T14:00:00Z, isExpired: true}, { Feature:2, creation:2024-09-03T14:45:00Z, expiry:2024-09-03T15:15:00Z, isExpired: true} [CONTEXT sampling_count=5 ] 09-03 11:09:00.019 4197 4638 I AiAiEcho: EchoTargets: Feature:1 09-03 11:09:00.019 4197 4638 I AiAiEcho: Filtered by AiAi flag check: 09-03 11:09:00.019 4197 4638 I AiAiEcho: [CONTEXT ratelimit_period="10 SECONDS" ] 09-03 11:09:00.020 4197 4638 I AiAiEcho: Ranked targets strategy: WEIGHTED, count: 1, ranking metadata: { cardId: WEATHER-569377244, card type: 1, update time: 2024-09-03T09:00:00Z, expiration time: 2024-09-03T10:00:00Z, ranking metadata: RankingMetaData(urgency=0.979, priority=0.3, recency=1.0, rankScore=0.2937, importantTimePoints=# vgn@7bca4) } [CONTEXT ratelimit_period="10 SECONDS" ] 09-03 11:09:00.020 4197 4638 I AiAiEcho: #weatherTwiddler: Weather removes SubtitleSupplementalItem in surface: home [CONTEXT ratelimit_period="10 SECONDS" ] 09-03 11:09:00.020 4197 4638 I AiAiEcho: #remoteViewsTwiddler: feature disabled. 09-03 11:09:00.024 4197 4638 I AiAiEcho: #postPredictionTargets: Sending updates to UISurface lockscreen with targets# 1 (types=[1]) 09-03 11:09:00.030 2577 2577 I WeatherSmartspaceView: setFormattedContentDescription: text=21°C, iconDescription=Cloudy, contentDescription=Cloudy, 21°C 09-03 11:09:00.030 2577 2577 I WeatherData: Weather data parsed CLOUDY ("Cloudy") 21°C from Bundle[{description=Cloudy, feedback_intent=null, explanation_intent=Supplier{VAL_PARCELABLE@140+1968}, is_gaia_linked_data=true, use_celsius=true, state=9, temperature=21}] 09-03 11:09:00.030 2577 2577 W PageIndicator: Total number of pages invalid: 0. Assuming 1 page. 09-03 11:09:00.119 10922 10942 D Benchmark: Dropping shader cache for com.example.composeplayground 09-03 11:09:00.119 10922 10942 D Benchmark: Profile Installer - Benchmark Operation: DROP_SHADER_CACHE 09-03 11:09:00.150 1647 4304 I ActivityManager: Broadcasting: Intent { act=androidx.profileinstaller.action.BENCHMARK_OPERATION flg=0x400000 cmp=com.example.composeplayground/androidx.profileinstaller.ProfileInstallReceiver (has extras) } 09-03 11:09:00.151 1647 1756 V GrammaticalInflectionUtils: AttributionSource: android.content.AttributionSource@59aa0a13 does not have READ_SYSTEM_GRAMMATICAL_GENDER permission. 09-03 11:09:00.165 1015 1015 D Zygote : Forked child process 11190 09-03 11:09:00.186 11190 11190 I mposeplayground: Using CollectorTypeCMC GC. 09-03 11:09:00.193 1359 1388 I adbd : jdwp connection from 11190 09-03 11:09:00.197 11190 11190 D nativeloader: Load libframework-connectivity-tiramisu-jni.so using APEX ns com_android_tethering for caller /apex/com.android.tethering/javalib/framework-connectivity-t.jar: ok 09-03 11:09:00.211 11190 11190 I ActivityThread: Relaunch all activities: onCoreSettingsChange 09-03 11:09:00.212 11190 11190 D CompatChangeReporter: Compat change id reported: 242716250; UID 10457; state: ENABLED 09-03 11:09:00.235 11190 11190 D nativeloader: Configuring clns-7 for other apk /data/app/~~2vm_r7Q14Id3z1VGG8fJ5A==/com.example.composeplayground-L2JKaaJyL2hVvyWLFzk21Q==/base.apk. target_sdk_version=34, uses_libraries=, library_path=/data/app/~~2vm_r7Q14Id3z1VGG8fJ5A==/com.example.composeplayground-L2JKaaJyL2hVvyWLFzk21Q==/lib/arm64:/data/app/~~2vm_r7Q14Id3z1VGG8fJ5A==/com.example.composeplayground-L2JKaaJyL2hVvyWLFzk21Q==/base.apk!/lib/arm64-v8a, permitted_path=/data:/mnt/expand:/data/user/0/com.example.composeplayground 09-03 11:09:00.241 11190 11190 V GraphicsEnvironment: Currently set values for: 09-03 11:09:00.241 11190 11190 V GraphicsEnvironment: angle_gl_driver_selection_pkgs=[com.android.angle, com.linecorp.b612.android, com.campmobile.snow, com.google.android.apps.tachyon] 09-03 11:09:00.241 11190 11190 V GraphicsEnvironment: angle_gl_driver_selection_values=[angle, native, native, native] 09-03 11:09:00.241 11190 11190 V GraphicsEnvironment: com.example.composeplayground is not listed in per-application setting 09-03 11:09:00.241 11190 11190 V GraphicsEnvironment: Neither updatable production driver nor prerelease driver is supported. 09-03 11:09:00.267 11190 11190 D ProfileInstaller: 09-03 11:09:00.268 1647 1738 I ActivityManager: Broadcast completed: result=14 09-03 11:09:00.272 10922 10942 D Benchmark: Killing process com.example.composeplayground 09-03 11:09:00.272 10922 10942 D UiDevice: Executing shell command: am force-stop com.example.composeplayground 09-03 11:09:00.321 2948 2948 D CarrierSvcBindHelper: onHandleForceStop: [com.example.composeplayground] 09-03 11:09:00.323 2948 3201 D EuiccConnector: handleMessage: E msg.what=1 09-03 11:09:00.323 2948 3201 D EuiccConnector: processMsg: ConnectedState 09-03 11:09:00.323 2948 3201 D EuiccConnector: processMsg: DisconnectedState 09-03 11:09:00.324 2948 2948 D CarrierSvcBindHelper: No carrier app for: 0 09-03 11:09:00.325 2948 2948 D CarrierSvcBindHelper: No carrier app for: 1 09-03 11:09:00.327 4184 5455 W MediaProvider: isAppCloneUserPair for user 0: false 09-03 11:09:00.328 2948 3201 D EuiccConnector: handleMessage: X 09-03 11:09:00.356 1359 1359 I adbd : Remote process closed the socket (on MSG_PEEK) 09-03 11:09:00.356 1647 1759 I libprocessgroup: Removed cgroup /sys/fs/cgroup/uid_10457/pid_11190 09-03 11:09:00.356 1015 1015 I Zygote : Process 11190 exited due to signal 9 (Killed) 09-03 11:09:00.400 27764 10820 I NetworkScheduler.Stats: (REDACTED) Task %s/%s started execution. cause:%s exec_start_elapsed_seconds: %s 09-03 11:09:00.406 27764 10821 I NetworkScheduler.Stats: (REDACTED) Task %s/%s finished executing. cause:%s result: %s elapsed_millis: %s uptime_millis: %s exec_start_elapsed_seconds: %s 09-03 11:09:00.412 10922 11231 D ProfileInstaller: Installing profile for com.example.baselineprofile 09-03 11:09:00.431 4184 10897 W MediaProvider: Ignoring param:deletedata post U for external apps 09-03 11:09:00.435 4184 10897 I MediaProvider: Invalidating LocalCallingIdentity cache for package com.example.baselineprofile. Reason: owned_database_row_deleted:/storage/emulated/0/Android/media/com.example.baselineprofile/temporaryScript8620934502903673951.tmp 09-03 11:09:00.439 4184 10897 I MediaProvider: Deleted 1 0 0 0 0 0 0 items on external_primary due to com.android.shell 09-03 11:09:00.492 27764 10820 I NetworkScheduler.Stats: (REDACTED) Task %s/%s started execution. cause:%s exec_start_elapsed_seconds: %s 09-03 11:09:00.496 27764 10821 I NetworkScheduler.Stats: (REDACTED) Task %s/%s finished executing. cause:%s result: %s elapsed_millis: %s uptime_millis: %s exec_start_elapsed_seconds: %s 09-03 11:09:00.503 4184 6669 W MediaProvider: isAppCloneUserPair for user 0: false 09-03 11:09:00.544 27764 10820 I NetworkScheduler.Stats: (REDACTED) Task %s/%s started execution. cause:%s exec_start_elapsed_seconds: %s 09-03 11:09:00.546 27764 10821 I NetworkScheduler.Stats: (REDACTED) Task %s/%s finished executing. cause:%s result: %s elapsed_millis: %s uptime_millis: %s exec_start_elapsed_seconds: %s 09-03 11:09:00.579 4184 4880 W MediaProvider: Ignoring param:deletedata post U for external apps 09-03 11:09:00.583 4184 4880 I MediaProvider: Invalidating LocalCallingIdentity cache for package com.example.baselineprofile. Reason: owned_database_row_deleted:/storage/emulated/0/Android/media/com.example.baselineprofile/temporaryScript5369058497013521531.tmp 09-03 11:09:00.584 4184 4880 I MediaProvider: Deleted 1 0 0 0 0 0 0 items on external_primary due to com.android.shell 09-03 11:09:00.620 27764 10820 I NetworkScheduler.Stats: (REDACTED) Task %s/%s started execution. cause:%s exec_start_elapsed_seconds: %s 09-03 11:09:00.622 27764 10721 I NetworkScheduler.Stats: (REDACTED) Task %s/%s finished executing. cause:%s result: %s elapsed_millis: %s uptime_millis: %s exec_start_elapsed_seconds: %s 09-03 11:09:00.653 4184 4881 W MediaProvider: isAppCloneUserPair for user 0: false 09-03 11:09:00.703 27764 10821 I NetworkScheduler.Stats: (REDACTED) Task %s/%s started execution. cause:%s exec_start_elapsed_seconds: %s 09-03 11:09:00.706 27764 10713 I NetworkScheduler.Stats: (REDACTED) Task %s/%s finished executing. cause:%s result: %s elapsed_millis: %s uptime_millis: %s exec_start_elapsed_seconds: %s 09-03 11:09:00.740 4184 5455 W MediaProvider: Ignoring param:deletedata post U for external apps 09-03 11:09:00.743 4184 5455 I MediaProvider: Invalidating LocalCallingIdentity cache for package com.example.baselineprofile. Reason: owned_database_row_deleted:/storage/emulated/0/Android/media/com.example.baselineprofile/temporaryScript7816361431687304662.tmp 09-03 11:09:00.745 4184 5455 I MediaProvider: Deleted 1 0 0 0 0 0 0 items on external_primary due to com.android.shell 09-03 11:09:00.781 10922 10942 D PerfettoCapture: Supported ABIs: arm64-v8a, armeabi-v7a, armeabi 09-03 11:09:00.781 10922 10942 D PerfettoCapture: Recording perfetto trace 09-03 11:09:00.803 27764 10821 I NetworkScheduler.Stats: (REDACTED) Task %s/%s started execution. cause:%s exec_start_elapsed_seconds: %s 09-03 11:09:00.807 27764 10713 I NetworkScheduler.Stats: (REDACTED) Task %s/%s finished executing. cause:%s result: %s elapsed_millis: %s uptime_millis: %s exec_start_elapsed_seconds: %s 09-03 11:09:00.816 4184 4880 W MediaProvider: isAppCloneUserPair for user 0: false 09-03 11:09:00.831 10922 10942 I PerfettoCapture: Cleanup perfetto before starting. 09-03 11:09:00.858 27764 27768 I .gms.persistent: Background concurrent mark compact GC freed 49MB AllocSpace bytes, 289(36MB) LOS objects, 75% free, 30MB/121MB, paused 2.934ms,5.128ms total 171.377ms 09-03 11:09:00.867 4184 6852 W MediaProvider: isAppCloneUserPair for user 0: false 09-03 11:09:00.894 27764 27770 W .gms.persistent: ApkAssets: Deleting an ApkAssets object ' and /data/app/~~3PQgyvWyWuPiyDFL7p46og==/com.google.android.videos-ZoffdZ4yQbZow-Bp_wMceA==/base.apk' with 1 weak references 09-03 11:09:00.896 27764 27770 W .gms.persistent: ApkAssets: Deleting an ApkAssets object ' and /data/app/~~bg8V4GzJDctqTrTc7fNZRA==/com.google.android.apps.carrier.carrierwifi-ak3PBqNVTRtmPi_EbNStWg==/base.apk' with 1 weak references 09-03 11:09:00.897 27764 27770 W .gms.persistent: ApkAssets: Deleting an ApkAssets object ' and /data/app/~~2vm_r7Q14Id3z1VGG8fJ5A==/com.example.composeplayground-L2JKaaJyL2hVvyWLFzk21Q==/base.apk' with 1 weak references 09-03 11:09:00.898 27764 27770 W .gms.persistent: ApkAssets: Deleting an ApkAssets object ' and /data/app/~~7IoUe12OGu-1WVIg1dYkCw==/com.example.baselineprofile-3hZdaAxBpJDZmYTFVtgNxQ==/base.apk' with 1 weak references 09-03 11:09:00.908 27764 10821 I NetworkScheduler.Stats: (REDACTED) Task %s/%s started execution. cause:%s exec_start_elapsed_seconds: %s 09-03 11:09:00.911 27764 10820 I NetworkScheduler.Stats: (REDACTED) Task %s/%s finished executing. cause:%s result: %s elapsed_millis: %s uptime_millis: %s exec_start_elapsed_seconds: %s 09-03 11:09:00.913 4184 10897 W MediaProvider: Ignoring param:deletedata post U for external apps 09-03 11:09:00.916 4184 10897 I MediaProvider: Invalidating LocalCallingIdentity cache for package com.example.baselineprofile. Reason: owned_database_row_deleted:/storage/emulated/0/Android/media/com.example.baselineprofile/temporaryScript4622587663092100293.tmp 09-03 11:09:00.918 4184 10897 I MediaProvider: Deleted 1 0 0 0 0 0 0 items on external_primary due to com.android.shell 09-03 11:09:00.923 10922 10942 D Benchmark: kill -TERM command output - Output(stdout=, stderr=/storage/emulated/0/Android/media/com.example.baselineprofile/temporaryScript4622587663092100293.tmp[2]: kill: 24965: Operation not permitted 09-03 11:09:00.923 10922 10942 D Benchmark: ) 09-03 11:09:01.064 10922 10942 D Benchmark: Waiting 100 ms for [ProcessPid(processName=perfetto, pid=24965)] to die 09-03 11:09:01.248 10922 10942 D Benchmark: Waiting 100 ms for [ProcessPid(processName=perfetto, pid=24965)] to die 09-03 11:09:01.393 10922 10942 D Benchmark: Waiting 100 ms for [ProcessPid(processName=perfetto, pid=24965)] to die 09-03 11:09:01.405 27764 18504 I NearbyDiscovery: (REDACTED) FastPairScanner: isScreenOn=%s, isLocationEnabled=%s, disableLocationRequirement=%s, isDiscoveryScanningEnabled=%s, during24GhzWifiWarmingUpPeriod=%s 09-03 11:09:01.406 27764 18504 D BluetoothAdapter: isLeEnabled(): ON 09-03 11:09:01.406 27764 18504 I NearbyDiscovery: (REDACTED) FastPairScanner: eventType=%s, intReq=%s, scanning=%s, scanAllowed=%s, bleEnabled=%s, lockScanRate=%s, startScanningByLowPowerMode=%s 09-03 11:09:01.411 27764 18504 I NearbyDiscovery: (REDACTED) FastPairScanner: Stopping scan, %s 09-03 11:09:01.412 27764 18504 D BluetoothAdapter: isLeEnabled(): ON 09-03 11:09:01.418 2789 3160 I bt_shim_scanner: system/main/shim/le_scanning_manager.cc:160 Scan: in shim layer stopped 09-03 11:09:01.418 2789 3101 I bluetooth: system/gd/hci/le_address_manager.cc:229 unregister_client: Client unregistered 09-03 11:09:01.418 2789 3160 I bt_shim_scanner: system/main/shim/le_scanning_manager.cc:193 ScanFilterParamSetup: in shim layer, clientIf=1 09-03 11:09:01.418 2789 3160 I bt_shim_scanner: system/main/shim/le_scanning_manager.cc:193 ScanFilterParamSetup: in shim layer, clientIf=1 09-03 11:09:01.418 1039 1067 I bthal.activity: LogActivities: Disable LE Scanning, 0x2042 09-03 11:09:01.418 2789 3160 I bt_shim_scanner: system/main/shim/le_scanning_manager.cc:193 ScanFilterParamSetup: in shim layer, clientIf=1 09-03 11:09:01.419 2789 20526 I bt_shim_scanner: system/main/shim/le_scanning_manager.cc:154 Unregister: in shim layer, scannerId=1 09-03 11:09:01.420 27764 18504 I NearbyDiscovery: (REDACTED) FastPairScanner: isScreenOn=%s, isLocationEnabled=%s, disableLocationRequirement=%s, isDiscoveryScanningEnabled=%s, during24GhzWifiWarmingUpPeriod=%s 09-03 11:09:01.420 27764 18504 I NearbyDiscovery: (REDACTED) FastPairScanner: Start scanning internally, %s 09-03 11:09:01.420 27764 18504 D BluetoothAdapter: isLeEnabled(): ON 09-03 11:09:01.423 2789 20526 I bt_shim_scanner: system/main/shim/le_scanning_manager.cc:148 RegisterScanner: in shim layer, UUID=3058e67b-1cd9-4782-a9c7-4bad480a9c47 09-03 11:09:01.431 1039 1208 I bthal.debug_central: Command Complete Event of LE_SET_EXTENDED_SCAN_ENABLE, opcode:2042 09-03 11:09:01.431 1039 1208 I bthal.flowcontrol: HciEventPreProcess: HCI Event 0xe Opcode 0x2042 Length 6 unhandled by LeSetExtentedScanEnableEvent 09-03 11:09:01.431 27764 27981 D BluetoothLeScanner: onScannerRegistered() - status=0 scannerId=1 mScannerId=0 09-03 11:09:01.438 27764 18504 I NearbyDiscovery: (REDACTED) FastPairScanner: Starting %s scanning 09-03 11:09:01.438 2789 3160 I bt_shim_scanner: system/main/shim/le_scanning_manager.cc:249 ScanFilterEnable: in shim layer 09-03 11:09:01.439 2789 3160 I bt_shim_scanner: system/main/shim/le_scanning_manager.cc:226 ScanFilterAdd: in shim layer 09-03 11:09:01.439 2789 3160 I bt_shim_scanner: system/main/shim/le_scanning_manager.cc:193 ScanFilterParamSetup: in shim layer, clientIf=1 09-03 11:09:01.440 2789 3160 I bt_shim_scanner: system/main/shim/le_scanning_manager.cc:226 ScanFilterAdd: in shim layer 09-03 11:09:01.440 2789 3160 I bt_shim_scanner: system/main/shim/le_scanning_manager.cc:193 ScanFilterParamSetup: in shim layer, clientIf=1 09-03 11:09:01.441 2789 3160 I bt_shim_scanner: system/main/shim/le_scanning_manager.cc:226 ScanFilterAdd: in shim layer 09-03 11:09:01.441 2789 3160 I bt_shim_scanner: system/main/shim/le_scanning_manager.cc:193 ScanFilterParamSetup: in shim layer, clientIf=1 09-03 11:09:01.442 2789 3160 I bt_shim_scanner: system/main/shim/le_scanning_manager.cc:160 Scan: in shim layer started 09-03 11:09:01.442 2789 3101 I bluetooth: system/gd/hci/le_scanning_manager.cc:593 stop_scan: Scanning already stopped, return! 09-03 11:09:01.442 2789 3101 I bluetooth: system/gd/hci/le_address_manager.cc:214 register_client: Client registered 09-03 11:09:01.442 2789 3160 I bt_shim_scanner: system/main/shim/le_scanning_manager.cc:160 Scan: in shim layer stopped 09-03 11:09:01.442 2789 3101 I bluetooth: system/gd/hci/le_address_manager.cc:229 unregister_client: Client unregistered 09-03 11:09:01.443 2789 3160 I bt_shim_scanner: system/main/shim/le_scanning_manager.cc:325 SetScanParameters: in shim layer, scannerId=1 09-03 11:09:01.443 2789 3160 I bt_shim_scanner: system/main/shim/le_scanning_manager.cc:160 Scan: in shim layer started 09-03 11:09:01.443 2789 3101 I bluetooth: system/gd/hci/le_scanning_manager.cc:593 stop_scan: Scanning already stopped, return! 09-03 11:09:01.443 2789 3101 I bluetooth: system/gd/hci/le_address_manager.cc:214 register_client: Client registered 09-03 11:09:01.450 1039 1067 I bthal.activity: LogActivities: LOW_LATENCY ScanMode 09-03 11:09:01.451 1039 1067 I bthal.activity: LogActivities: Enable LE Scanning, 0x2042 09-03 11:09:01.452 1039 1208 I bthal.debug_central: Command Complete Event of LE_SET_EXTENDED_SCAN_ENABLE, opcode:2042 09-03 11:09:01.453 1039 1208 I bthal.flowcontrol: HciEventPreProcess: HCI Event 0xe Opcode 0x2042 Length 6 unhandled by LeSetExtentedScanEnableEvent 09-03 11:09:01.453 1039 1067 I bthal.activity: LogActivities: Disable LE Scanning, 0x2042 09-03 11:09:01.454 1039 1208 I bthal.debug_central: Command Complete Event of LE_SET_EXTENDED_SCAN_ENABLE, opcode:2042 09-03 11:09:01.454 1039 1208 I bthal.flowcontrol: HciEventPreProcess: HCI Event 0xe Opcode 0x2042 Length 6 unhandled by LeSetExtentedScanEnableEvent 09-03 11:09:01.455 1039 1067 I bthal.activity: LogActivities: BALANCED ScanMode 09-03 11:09:01.456 1039 1067 I bthal.activity: LogActivities: Enable LE Scanning, 0x2042 09-03 11:09:01.457 1039 1208 I bthal.debug_central: Command Complete Event of LE_SET_EXTENDED_SCAN_ENABLE, opcode:2042 09-03 11:09:01.457 1039 1208 I bthal.flowcontrol: HciEventPreProcess: HCI Event 0xe Opcode 0x2042 Length 6 unhandled by LeSetExtentedScanEnableEvent 09-03 11:09:01.585 10922 10942 D Benchmark: Waiting 100 ms for [ProcessPid(processName=perfetto, pid=24965)] to die 09-03 11:09:01.710 1109 1109 I WifiHAL : Creating message to get link statistics; iface = 47 09-03 11:09:01.767 10922 10942 D Benchmark: Waiting 100 ms for [ProcessPid(processName=perfetto, pid=24965)] to die 09-03 11:09:01.813 1109 1109 E IPCThreadState: binder thread pool (1 threads) starved for 103 ms 09-03 11:09:01.845 1647 2499 D ConnectivityService: NetReassign [no changes] [c 0] [a 1] [i 2] 09-03 11:09:01.964 10922 10942 D Benchmark: Waiting 100 ms for [ProcessPid(processName=perfetto, pid=24965)] to die 09-03 11:09:02.156 10922 10942 D Benchmark: Waiting 100 ms for [ProcessPid(processName=perfetto, pid=24965)] to die 09-03 11:09:02.184 709 7831 W gralloc4: Unable to set buffer name bbq-adapter#685(BLAST Consumer)685: File name too long 09-03 11:09:02.185 1398 1398 D Vibrator: perform 09-03 11:09:02.195 1398 1398 D Vibrator: on 09-03 11:09:02.196 1398 11297 D Vibrator: waitForComplete 09-03 11:09:02.219 1398 11297 D Vibrator: waitForComplete: Get STOP! Set active to 0. 09-03 11:09:02.220 1398 1398 D Vibrator: off 09-03 11:09:02.273 2760 2783 I deeptouch: I0000 00:00:1725354542.273796 2783 tf_lite_classifier.cc:413] Event stream classified as kNone 09-03 11:09:02.277 2577 2577 I FalsingManager: {belief=0.11111580059283288 confidence=1.0} 09-03 11:09:02.282 2577 2577 D KeyguardViewMediator: KeyguardViewMediator queue processing message: KEYGUARD_DONE_DRAWING 09-03 11:09:02.328 10922 10942 D Benchmark: Waiting 100 ms for [ProcessPid(processName=perfetto, pid=24965)] to die 09-03 11:09:02.355 1398 1398 D Vibrator: perform 09-03 11:09:02.355 709 7831 W gralloc4: Unable to set buffer name bbq-adapter#686(BLAST Consumer)686: File name too long 09-03 11:09:02.356 1398 1398 D Vibrator: on 09-03 11:09:02.356 1398 11301 D Vibrator: waitForComplete 09-03 11:09:02.377 1398 11301 D Vibrator: waitForComplete: Get STOP! Set active to 0. 09-03 11:09:02.379 1398 1398 D Vibrator: off 09-03 11:09:02.444 2760 2783 I deeptouch: I0000 00:00:1725354542.444682 2783 tf_lite_classifier.cc:413] Event stream classified as kNone 09-03 11:09:02.447 2577 2577 I FalsingManager: {belief=0.033357353973509425 confidence=1.0} 09-03 11:09:02.452 2577 2577 D KeyguardViewMediator: KeyguardViewMediator queue processing message: KEYGUARD_DONE_DRAWING 09-03 11:09:02.487 10922 10942 D Benchmark: Waiting 100 ms for [ProcessPid(processName=perfetto, pid=24965)] to die 09-03 11:09:02.582 709 7831 W gralloc4: Unable to set buffer name bbq-adapter#685(BLAST Consumer)685: File name too long 09-03 11:09:02.584 1398 1398 D Vibrator: perform 09-03 11:09:02.585 1398 1398 D Vibrator: on 09-03 11:09:02.586 1398 11305 D Vibrator: waitForComplete 09-03 11:09:02.608 1398 11305 D Vibrator: waitForComplete: Get STOP! Set active to 0. 09-03 11:09:02.608 1398 1398 D Vibrator: off 09-03 11:09:02.635 10922 10942 D Benchmark: Waiting 100 ms for [ProcessPid(processName=perfetto, pid=24965)] to die 09-03 11:09:02.670 2760 2783 I deeptouch: I0000 00:00:1725354542.670831 2783 tf_lite_classifier.cc:413] Event stream classified as kNone 09-03 11:09:02.672 2577 2577 I FalsingManager: {belief=0.010564648334670504 confidence=1.0} 09-03 11:09:02.678 2577 2577 D KeyguardViewMediator: KeyguardViewMediator queue processing message: KEYGUARD_DONE_DRAWING 09-03 11:09:02.781 10922 10942 D Benchmark: Waiting 100 ms for [ProcessPid(processName=perfetto, pid=24965)] to die 09-03 11:09:02.841 709 7831 W gralloc4: Unable to set buffer name bbq-adapter#686(BLAST Consumer)686: File name too long 09-03 11:09:02.845 1398 1398 D Vibrator: perform 09-03 11:09:02.846 1398 1398 D Vibrator: on 09-03 11:09:02.847 1398 11312 D Vibrator: waitForComplete 09-03 11:09:02.868 1398 11312 D Vibrator: waitForComplete: Get STOP! Set active to 0. 09-03 11:09:02.869 1398 1398 D Vibrator: off 09-03 11:09:02.935 10922 10942 D Benchmark: Waiting 100 ms for [ProcessPid(processName=perfetto, pid=24965)] to die 09-03 11:09:02.940 2760 2783 I deeptouch: I0000 00:00:1725354542.940874 2783 tf_lite_classifier.cc:413] Event stream classified as kNone 09-03 11:09:02.944 2577 2577 I FalsingManager: {belief=0.003747299413057602 confidence=1.0} 09-03 11:09:02.948 2577 2577 D KeyguardViewMediator: KeyguardViewMediator queue processing message: KEYGUARD_DONE_DRAWING 09-03 11:09:02.956 1647 2499 D InetDiagMessage: Destroyed 0 sockets, proto=IPPROTO_TCP, family=AF_INET, states=14 09-03 11:09:02.958 1647 2499 D InetDiagMessage: Destroyed 0 sockets, proto=IPPROTO_TCP, family=AF_INET6, states=14 09-03 11:09:02.958 1647 2499 D InetDiagMessage: Destroyed live tcp sockets for uids={10070} in 5ms 09-03 11:09:03.060 1398 1398 D Vibrator: perform 09-03 11:09:03.060 1398 1398 D Vibrator: on 09-03 11:09:03.061 1398 11316 D Vibrator: waitForComplete 09-03 11:09:03.077 10922 10942 D Benchmark: Waiting 100 ms for [ProcessPid(processName=perfetto, pid=24965)] to die 09-03 11:09:03.082 1398 11316 D Vibrator: waitForComplete: Get STOP! Set active to 0. 09-03 11:09:03.083 1398 1398 D Vibrator: off 09-03 11:09:03.129 2760 2783 I deeptouch: I0000 00:00:1725354543.128870 2783 tf_lite_classifier.cc:413] Event stream classified as kNone 09-03 11:09:03.158 2577 2577 I FalsingManager: {belief=1.9975743993994184E-5 confidence=0.9254681275299695} 09-03 11:09:03.194 701 701 D libnos_datagram: Calling App 0x03 with params 0x0002 09-03 11:09:03.214 10922 10942 D Benchmark: Waiting 100 ms for [ProcessPid(processName=perfetto, pid=24965)] to die 09-03 11:09:03.243 701 701 D libnos_datagram: App 0x03 returning 0x0 09-03 11:09:03.248 27764 27764 I ActiveUnlock: (REDACTED) onUnlockAttempt: successful=%s 09-03 11:09:03.249 2577 2678 D Bubbles : handleKeyguardOrDreamChange isUnlockedShade=false keyguardShowing=true dreamingOrInPreview=false 09-03 11:09:03.351 701 701 D libnos_datagram: Calling App 0x00 with params 0x0004 09-03 11:09:03.353 701 701 D libnos_datagram: App 0x00 returning 0x1 09-03 11:09:03.354 701 701 D libnos_datagram: Calling App 0x00 with params 0x0003 09-03 11:09:03.356 701 701 D libnos_datagram: App 0x00 returning 0x0 09-03 11:09:03.371 10922 10942 D Benchmark: Waiting 100 ms for [ProcessPid(processName=perfetto, pid=24965)] to die 09-03 11:09:03.373 2577 2577 D UdfpsController: hiding udfps overlay when mKeyguardUpdateMonitor.isFingerprintDetectionRunning()=true 09-03 11:09:03.373 2577 2577 W UdfpsDisplayMode: disable | already disabled 09-03 11:09:03.373 2577 2577 W UdfpsDisplayMode: disable | already disabled 09-03 11:09:03.373 2577 18396 D BiometricStatusRepositoryImpl: authenticationState updated: Stopped(biometricSourceType=FINGERPRINT, requestReason=NotRunning) 09-03 11:09:03.374 2577 2630 D DEUTrackerRepository: sending: type=FINGERPRINT , it=com.google.android.systemui.biometrics.DeviceEntryUnlockEvent$Stopped@fbfbb76 09-03 11:09:03.374 2577 2624 D DEUTrackerInteractor: receiving: type=FINGERPRINT, it=com.google.android.systemui.biometrics.DeviceEntryUnlockEvent$Stopped@fbfbb76 09-03 11:09:03.374 1340 1340 D FingerprintHal: fingerprint cancel 09-03 11:09:03.374 2577 2624 D FpsUnlockTracker: onAuthenticationStopped: current=STOPPED, previous=STARTED 09-03 11:09:03.374 1340 1340 D FpStateTracker: UpdateState: state_:0x0 09-03 11:09:03.375 1082 1082 D usf_sensor_hal: ambient light: Enter Batch. Period = 20000000, latency = 0. 09-03 11:09:03.377 1324 1324 D AOC : A3:MSG: usf_sensor.cc, 2085: USF: Received reconfig sampling request for TMD3719 Ambient Light. 09-03 11:09:03.378 1340 1340 D AlsMonitor: Sampling is stopped. 09-03 11:09:03.378 1340 1340 E [GF_HAL][FingerprintCore]: [notifyErrorInfo] err code : 5. 09-03 11:09:03.378 1340 1340 E FingerprintHal: [notify] data.error:5, onError(5, 0) 09-03 11:09:03.379 2577 18396 D BiometricStatusRepositoryImpl: authenticationState updated: Error(biometricSourceType=FINGERPRINT, errString=Fingerprint operation canceled, errCode=5, requestReason=DeviceEntryAuthentication) 09-03 11:09:03.380 1082 1082 D usf_sensor_hal: ambient light: Enter Batch. Period = 20000000, latency = 0. 09-03 11:09:03.380 2577 2629 D DEUTrackerRepository: sending: type=FINGERPRINT , it=com.google.android.systemui.biometrics.DeviceEntryUnlockEvent$Error@cf0f5e4 09-03 11:09:03.380 2577 2629 D DEUTrackerInteractor: receiving: type=FINGERPRINT, it=com.google.android.systemui.biometrics.DeviceEntryUnlockEvent$Error@cf0f5e4 09-03 11:09:03.380 2577 2629 D FpsUnlockTracker: onError: current=CANCELED, previous=STOPPED 09-03 11:09:03.380 2577 2577 D UdfpsTouchOverlayBinder: [com.android.systemui.biometrics.ui.view.UdfpsTouchOverlay{7f269d9 I.E...... ........ 0,0-1440,3120 #7f0a08bc app:id/udfps_touch_overlay}-detached]: update shouldHandleTouches=false 09-03 11:09:03.382 1082 1771 I sensor-suez: Vendor atom [id = 100243] reported. 09-03 11:09:03.382 2577 18393 D BiometricStatusRepositoryImpl: authenticationState updated: Stopped(biometricSourceType=FINGERPRINT, requestReason=NotRunning) 09-03 11:09:03.382 2577 2632 D DEUTrackerRepository: sending: type=FINGERPRINT , it=com.google.android.systemui.biometrics.DeviceEntryUnlockEvent$Stopped@8e41902 09-03 11:09:03.382 2577 2628 D DEUTrackerInteractor: receiving: type=FINGERPRINT, it=com.google.android.systemui.biometrics.DeviceEntryUnlockEvent$Stopped@8e41902 09-03 11:09:03.382 2577 2628 D FpsUnlockTracker: onAuthenticationStopped: current=STOPPED, previous=CANCELED 09-03 11:09:03.383 1340 1340 I android.hardware.biometrics.fingerprint-service.goodix: resetLockout 09-03 11:09:03.383 1340 1826 D FingerprintHal: fingerprint resetLockout 09-03 11:09:03.383 1340 1826 I FingerprintHal: authToken_to_GfAuthToken 09-03 11:09:03.384 1082 1771 I sensor-suez: Vendor atom [id = 100243] reported. 09-03 11:09:03.451 2577 2577 E KeyguardViewMediator: mHideAnimationFinishedRunnable#run 09-03 11:09:03.452 2577 2631 D KeyguardTransitionRepository: (Internal) Setting current transition info: TransitionInfo(ownerName=FromPrimaryBouncerTransitionInteractor, from=PRIMARY_BOUNCER, to=GONE, animated) 09-03 11:09:03.453 2577 2577 I KeyguardTransitionRepository: STARTED transition: TransitionStep(from=PRIMARY_BOUNCER, to=GONE, value=0.0, transitionState=STARTED, ownerName=FromPrimaryBouncerTransitionInteractor) 09-03 11:09:03.462 708 708 I BpBinder: onLastStrongRef automatically unlinking death recipients: 09-03 11:09:03.465 815 815 W powerhal-libperfmgr: Sess(3275), cannot resume, already active 09-03 11:09:03.470 708 708 I RefreshRateSelector: Display 4619827677550801152 policy changed 09-03 11:09:03.470 708 708 I RefreshRateSelector: Previous: {defaultModeId=1, allowGroupSwitching=false, primaryRanges={physical=[120.00 Hz, 120.00 Hz], render=[120.00 Hz, 120.00 Hz]}, appRequestRanges={physical=[120.00 Hz, 120.00 Hz], render=[120.00 Hz, 120.00 Hz]} idleScreenConfig=nullptr} 09-03 11:09:03.470 708 708 I RefreshRateSelector: Current: DisplayManagerPolicy{defaultModeId=1, allowGroupSwitching=false, primaryRanges={physical=[0.00 Hz, 120.00 Hz], render=[0.00 Hz, 120.00 Hz]}, appRequestRanges={physical=[0.00 Hz, 120.00 Hz], render=[0.00 Hz, 120.00 Hz]} idleScreenConfig=nullptr} 09-03 11:09:03.470 708 708 I RefreshRateSelector: 0 mode changes were performed under the previous policy 09-03 11:09:03.471 708 708 D DisplayModeController: setDesiredMode 4619827677550801152 {mode={fps=120.00 Hz, modePtr={id=1, vsyncRate=120.00 Hz, peakRefreshRate=120.00 Hz}}, emitEvent=true, force=false} 09-03 11:09:03.490 13143 13143 W RemoteViews: Could not find a RemoteViews fitting the current size: 395.42856x234.0 09-03 11:09:03.490 13143 13143 W RemoteViews: Could not find a RemoteViews fitting the current size: 395.42856x234.0 09-03 11:09:03.509 10922 10942 D Benchmark: Waiting 100 ms for [ProcessPid(processName=perfetto, pid=24965)] to die 09-03 11:09:03.516 709 7831 W gralloc4: Unable to set buffer name VRI[NovaLauncher]#269(BLAST Consumer)269: File name too long 09-03 11:09:03.529 2577 2577 W FrameTracker: Missing HWUI jank callback for vsyncId: 80324033, CUJ=J 09-03 11:09:03.529 709 7831 W gralloc4: Unable to set buffer name VRI[NovaLauncher]#269(BLAST Consumer)269: File name too long 09-03 11:09:03.529 2577 2577 D KeyguardViewMediator: handleStartKeyguardExitAnimation startTime=0 fadeoutDuration=0 09-03 11:09:03.530 2577 2577 D KeyguardUnlock: playCannedUnlockAnimation 09-03 11:09:03.530 2577 2577 D KeyguardUnlock: playCannedUnlockAnimation, surfaceBehindEntryAnimator#start 09-03 11:09:03.530 2577 2577 D KeyguardViewMediator: KeyguardViewMediator queue processing message: START_KEYGUARD_EXIT_ANIM 09-03 11:09:03.530 2577 2631 D DEUTrackerRepository: sending: type=null , it=com.google.android.systemui.biometrics.DeviceEntryUnlockEvent$ExitKeyguard@736ddac 09-03 11:09:03.530 2577 2631 D DEUTrackerInteractor: receiving: type=null, it=com.google.android.systemui.biometrics.DeviceEntryUnlockEvent$ExitKeyguard@736ddac 09-03 11:09:03.547 709 7831 W gralloc4: Unable to set buffer name VRI[NovaLauncher]#269(BLAST Consumer)269: File name too long 09-03 11:09:03.580 709 7831 W gralloc4: Unable to set buffer name VRI[NovaLauncher]#269(BLAST Consumer)269: File name too long 09-03 11:09:03.675 10922 10942 D Benchmark: Waiting 100 ms for [ProcessPid(processName=perfetto, pid=24965)] to die 09-03 11:09:03.841 10922 10942 D Benchmark: Waiting 100 ms for [ProcessPid(processName=perfetto, pid=24965)] to die 09-03 11:09:03.854 709 7831 W gralloc4: Unable to set buffer name VRI[NovaLauncher]#269(BLAST Consumer)269: File name too long 09-03 11:09:03.856 701 716 D libnos_datagram: Calling App 0x00 with params 0x0200 09-03 11:09:03.888 701 716 D libnos_datagram: App 0x00 returning 0x0 09-03 11:09:03.904 2577 2577 D KeyguardUnlock: surfaceBehindEntryAnimator#onAnimationEnd 09-03 11:09:03.905 2577 2577 D KeyguardViewMediator: exitKeyguardAndFinishSurfaceBehindRemoteAnimation 09-03 11:09:03.915 2577 2577 D KeyguardViewMediator: onKeyguardExitRemoteAnimationFinished#hideKeyguardViewAfterRemoteAnimation 09-03 11:09:03.915 2577 2631 E FromLockscreenTransitionInteractor: Ignoring startTransition: This interactor asked to transition from LOCKSCREEN -> GONE, but we last transitioned to GONE, not LOCKSCREEN. This should never happen - check currentTransitionInfoInternal or use filterRelevantKeyguardState before starting transitions. 09-03 11:09:03.915 27764 27764 I ActiveUnlock: onDeviceUnlocked [CONTEXT service_id=327 ] 09-03 11:09:03.916 2577 2678 D KeyguardViewMediator: updateActivityLockScreenState(false, false) 09-03 11:09:03.921 2577 2678 D Bubbles : handleKeyguardOrDreamChange isUnlockedShade=true keyguardShowing=false dreamingOrInPreview=false 09-03 11:09:03.923 2577 2577 W WindowOnBackDispatcher: sendCancelIfRunning: isInProgress=false callback=com.android.systemui.statusbar.phone.StatusBarKeyguardViewManager$2@56f39dc 09-03 11:09:03.924 2577 2577 D CentralSurfaces: !shouldBeKeyguard mStatusBarStateController.isKeyguardRequested() false keyguardForDozing false wakeAndUnlocking false isWakingAndOccluded false 09-03 11:09:03.924 2577 2577 D CentralSurfaces: hideKeyguardImpl false 09-03 11:09:03.924 2577 2577 D SbStateController: setState: requested state SHADE!= upcomingState: KEYGUARD. This usually means the status bar state transition was interrupted before the upcoming state could be applied. 09-03 11:09:03.930 2577 2577 I ScrimController: Skipping transition to: BOUNCER while mIsBouncerToGoneTransitionRunning 09-03 11:09:03.932 2577 2577 D DLIndicator: onStateChanged(0) 09-03 11:09:03.932 2577 2577 D DLIndicator: updateVisibility(), mDozing=false, mDocking=false, mTopIconShowing=false, mViewAttached=true 09-03 11:09:03.933 1082 1082 D usf_sensor_hal: ambient light: Enter Batch. Period = 250000000, latency = 0. 09-03 11:09:03.936 2577 2577 I ScrimController: Skipping transition to: BOUNCER while mIsBouncerToGoneTransitionRunning 09-03 11:09:03.936 2577 2577 I ScrimController: Skipping transition to: BOUNCER while mIsBouncerToGoneTransitionRunning 09-03 11:09:03.936 1324 1324 D AOC : A3:MSG: usf_sensor.cc, 2085: USF: Received reconfig sampling request for TMD3719 Ambient Light. 09-03 11:09:03.937 2577 2577 I ScrimController: Skipping transition to: UNLOCKED while mIsBouncerToGoneTransitionRunning 09-03 11:09:03.938 2577 2627 D DEUTrackerRepository: sending: type=null , it=com.google.android.systemui.biometrics.DeviceEntryUnlockEvent$Unlocked@cc3f29c 09-03 11:09:03.938 2577 2627 D DEUTrackerInteractor: receiving: type=null, it=com.google.android.systemui.biometrics.DeviceEntryUnlockEvent$Unlocked@cc3f29c 09-03 11:09:03.938 2577 2627 D FpsUnlockTracker: onUnlocked: current=UNLOCKED, previous=STOPPED, duration=0, lastExitKeyguardTimeStamp=0 09-03 11:09:03.938 2577 2627 D FaceUnlockTracker: onUnlocked: current=UNLOCKED, previous=UNLOCKED, duration=0, lastExitKeyguardTimeStamp=0 09-03 11:09:03.938 1082 1771 I sensor-suez: Vendor atom [id = 100243] reported. 09-03 11:09:03.941 2577 18393 D KeyguardService: setOccluded(false) 09-03 11:09:03.941 2577 18393 D KeyguardViewMediator: setOccluded(false) 09-03 11:09:03.944 2577 2577 D InsetsController: hide(ime(), fromIme=false) 09-03 11:09:03.944 2577 2577 I ImeTracker: com.android.systemui:5eaa8b95: onRequestHide at ORIGIN_CLIENT reason HIDE_SOFT_INPUT_BY_INSETS_API fromUser false 09-03 11:09:03.944 2577 2577 I ImeTracker: com.android.systemui:5eaa8b95: onCancelled at PHASE_CLIENT_APPLY_ANIMATION 09-03 11:09:03.946 2577 2577 D InsetsController: hide(ime(), fromIme=false) 09-03 11:09:03.946 2577 2577 I ImeTracker: com.android.systemui:d3644f64: onRequestHide at ORIGIN_CLIENT reason HIDE_SOFT_INPUT_BY_INSETS_API fromUser false 09-03 11:09:03.946 2577 2577 I ImeTracker: com.android.systemui:d3644f64: onCancelled at PHASE_CLIENT_APPLY_ANIMATION 09-03 11:09:03.955 2577 2577 D InsetsController: hide(ime(), fromIme=false) 09-03 11:09:03.956 2577 2577 I ImeTracker: com.android.systemui:ac505a34: onRequestHide at ORIGIN_CLIENT reason HIDE_SOFT_INPUT_BY_INSETS_API fromUser false 09-03 11:09:03.956 2577 2577 I ImeTracker: com.android.systemui:ac505a34: onCancelled at PHASE_CLIENT_APPLY_ANIMATION 09-03 11:09:03.962 2577 2577 D InsetsController: hide(ime(), fromIme=false) 09-03 11:09:03.963 2577 2577 I ImeTracker: com.android.systemui:dc465fd4: onRequestHide at ORIGIN_CLIENT reason HIDE_SOFT_INPUT_BY_INSETS_API fromUser false 09-03 11:09:03.963 2577 2577 I ImeTracker: com.android.systemui:dc465fd4: onCancelled at PHASE_CLIENT_APPLY_ANIMATION 09-03 11:09:03.966 2577 2577 D InsetsController: hide(ime(), fromIme=false) 09-03 11:09:03.967 2577 2577 I ImeTracker: com.android.systemui:9996c05d: onRequestHide at ORIGIN_CLIENT reason HIDE_SOFT_INPUT_BY_INSETS_API fromUser false 09-03 11:09:03.967 2577 2577 I ImeTracker: com.android.systemui:9996c05d: onCancelled at PHASE_CLIENT_APPLY_ANIMATION 09-03 11:09:03.970 2577 2577 D InsetsController: hide(ime(), fromIme=false) 09-03 11:09:03.970 2577 2577 I ImeTracker: com.android.systemui:c2861595: onRequestHide at ORIGIN_CLIENT reason HIDE_SOFT_INPUT_BY_INSETS_API fromUser false 09-03 11:09:03.970 2577 2577 I ImeTracker: com.android.systemui:c2861595: onCancelled at PHASE_CLIENT_APPLY_ANIMATION 09-03 11:09:03.973 2577 2577 D InsetsController: hide(ime(), fromIme=false) 09-03 11:09:03.973 2577 2577 I ImeTracker: com.android.systemui:7da43685: onRequestHide at ORIGIN_CLIENT reason HIDE_SOFT_INPUT_BY_INSETS_API fromUser false 09-03 11:09:03.973 2577 2577 I ImeTracker: com.android.systemui:7da43685: onCancelled at PHASE_CLIENT_APPLY_ANIMATION 09-03 11:09:03.975 2577 2577 D InsetsController: hide(ime(), fromIme=false) 09-03 11:09:03.976 2577 2577 I ImeTracker: com.android.systemui:4b4acbdf: onRequestHide at ORIGIN_CLIENT reason HIDE_SOFT_INPUT_BY_INSETS_API fromUser false 09-03 11:09:03.976 2577 2577 I ImeTracker: com.android.systemui:4b4acbdf: onCancelled at PHASE_CLIENT_APPLY_ANIMATION 09-03 11:09:03.979 2577 2577 D InsetsController: hide(ime(), fromIme=false) 09-03 11:09:03.980 2577 2577 I ImeTracker: com.android.systemui:538c6191: onRequestHide at ORIGIN_CLIENT reason HIDE_SOFT_INPUT_BY_INSETS_API fromUser false 09-03 11:09:03.980 2577 2577 I ImeTracker: com.android.systemui:538c6191: onCancelled at PHASE_CLIENT_APPLY_ANIMATION 09-03 11:09:03.985 10922 10942 D Benchmark: Waiting 100 ms for [ProcessPid(processName=perfetto, pid=24965)] to die 09-03 11:09:03.987 2577 2577 D InsetsController: hide(ime(), fromIme=false) 09-03 11:09:03.988 2577 2577 I ImeTracker: com.android.systemui:d0e1df99: onRequestHide at ORIGIN_CLIENT reason HIDE_SOFT_INPUT_BY_INSETS_API fromUser false 09-03 11:09:03.988 2577 2577 I ImeTracker: com.android.systemui:d0e1df99: onCancelled at PHASE_CLIENT_APPLY_ANIMATION 09-03 11:09:04.043 2577 2577 I ScrimController: Skipping transition to: UNLOCKED while mIsBouncerToGoneTransitionRunning 09-03 11:09:04.043 2577 2577 D ZenModeController: Added callback class com.android.systemui.qs.tiles.DndTile$3 09-03 11:09:04.043 2577 2577 D KeyguardViewMediator: handleSetOccluded(false) 09-03 11:09:04.043 2577 2577 D KeyguardViewMediator: KeyguardViewMediator queue processing message: SET_OCCLUDED 09-03 11:09:04.044 2577 2577 I KeyguardTransitionRepository: FINISHED transition: TransitionStep(from=PRIMARY_BOUNCER, to=GONE, value=1.0, transitionState=FINISHED, ownerName=FromPrimaryBouncerTransitionInteractor) 09-03 11:09:04.044 2577 2628 D ResourceTrimmer: Sending TRIM_MEMORY_UI_HIDDEN. 09-03 11:09:04.046 1647 1982 I ImeTracker: com.teslacoilsw.launcher:1a91ccb0: onRequestHide at ORIGIN_SERVER reason HIDE_SAME_WINDOW_FOCUSED_WITHOUT_EDITOR fromUser false 09-03 11:09:04.046 1647 1982 I ImeTracker: com.teslacoilsw.launcher:1a91ccb0: onCancelled at PHASE_SERVER_SHOULD_HIDE 09-03 11:09:04.047 2577 2577 W FrameTracker: Missing HWUI jank callback for vsyncId: 80324747, CUJ=J 09-03 11:09:04.047 2577 2577 W FrameTracker: Missing HWUI jank callback for vsyncId: 80324906, CUJ=J 09-03 11:09:04.047 2577 2577 W FrameTracker: Missing HWUI jank callback for vsyncId: 80326202, CUJ=J 09-03 11:09:04.047 2577 2577 W FrameTracker: Missing HWUI jank callback for vsyncId: 80326238, CUJ=J 09-03 11:09:04.050 3686 3686 I GoogleInputMethodService: GoogleInputMethodService.onStartInput():1982 onStartInput(EditorInfo{EditorInfo{packageName=com.teslacoilsw.launcher, inputType=0, inputTypeString=NULL, enableLearning=false, autoCorrection=false, autoComplete=false, imeOptions=0, privateImeOptions=null, actionName=UNSPECIFIED, actionLabel=null, initialSelStart=-1, initialSelEnd=-1, initialCapsMode=0, label=null, fieldId=0, fieldName=null, extras=null, hintText=null, hintLocales=[]}}, false) 09-03 11:09:04.050 3686 3686 I GoogleInputMethodService: GoogleInputMethodService.updateDeviceLockedStatus():2246 repeatCheckTimes = 1, locked = false 09-03 11:09:04.050 3686 3686 I AndroidIME: InputBundleManager.loadActiveInputBundleId():450 loadActiveInputBundleId: und-Latn-x-password, password 09-03 11:09:04.096 1647 2499 D InetDiagMessage: Destroyed 0 sockets, proto=IPPROTO_TCP, family=AF_INET, states=14 09-03 11:09:04.098 1647 2499 D InetDiagMessage: Destroyed 0 sockets, proto=IPPROTO_TCP, family=AF_INET6, states=14 09-03 11:09:04.098 1647 2499 D InetDiagMessage: Destroyed live tcp sockets for uids={10073} in 5ms 09-03 11:09:04.121 2577 2577 E com.android.keyguard.PinShapeNonHintingView: Trying to delete a non-existent char 09-03 11:09:04.121 2577 2577 E com.android.keyguard.PinShapeNonHintingView: Trying to delete a non-existent char 09-03 11:09:04.129 10922 10942 D Benchmark: Waiting 100 ms for [ProcessPid(processName=perfetto, pid=24965)] to die 09-03 11:09:04.142 1647 2499 D InetDiagMessage: Destroyed 0 sockets, proto=IPPROTO_TCP, family=AF_INET, states=14 09-03 11:09:04.144 1647 2499 D InetDiagMessage: Destroyed 0 sockets, proto=IPPROTO_TCP, family=AF_INET6, states=14 09-03 11:09:04.144 1647 2499 D InetDiagMessage: Destroyed live tcp sockets for uids={10131} in 4ms 09-03 11:09:04.149 2577 2628 D DEUTrackerInteractor: onCompletion: cause=kotlinx.coroutines.JobCancellationException: StandaloneCoroutine was cancelled; job=StandaloneCoroutine{Cancelling}@8780c8b 09-03 11:09:04.157 2577 2577 D DLObserver: remove listener: com.android.systemui.dock.DockManagerExtensionsKt$retrieveIsDocked$1$callback$1@40f426f 09-03 11:09:04.158 2577 2577 D DLObserver: remove listener: com.android.systemui.dock.DockManagerExtensionsKt$retrieveIsDocked$1$callback$1@1974b7c 09-03 11:09:04.159 2577 2577 D DLObserver: remove listener: com.android.systemui.dock.DockManagerExtensionsKt$retrieveIsDocked$1$callback$1@acaa45a 09-03 11:09:04.159 2577 2577 D DLObserver: remove listener: com.android.systemui.dock.DockManagerExtensionsKt$retrieveIsDocked$1$callback$1@8ee7381 09-03 11:09:04.161 2577 2577 D DLObserver: remove listener: com.android.systemui.dock.DockManagerExtensionsKt$retrieveIsDocked$1$callback$1@ccba368 09-03 11:09:04.161 2577 2577 D DLObserver: remove listener: com.android.systemui.dock.DockManagerExtensionsKt$retrieveIsDocked$1$callback$1@b7eac26 09-03 11:09:04.167 2577 2577 E com.android.keyguard.PinShapeNonHintingView: Trying to delete a non-existent char 09-03 11:09:04.167 2577 2577 E com.android.keyguard.PinShapeNonHintingView: Trying to delete a non-existent char 09-03 11:09:04.272 10922 10942 D Benchmark: Waiting 100 ms for [ProcessPid(processName=perfetto, pid=24965)] to die 09-03 11:09:04.424 27764 9041 I NearbySharing: Screen is unlocked 09-03 11:09:04.424 27764 8744 I NearbyUWB: (REDACTED) Screen unlocked: %s 09-03 11:09:04.425 27764 9041 I NearbySharing: Uwb feature is off. 09-03 11:09:04.425 27764 9041 I NearbySharing: Uwb feature is off. 09-03 11:09:04.427 27764 18504 I NearbyDiscovery: (REDACTED) FastPairHandler: Received action %s 09-03 11:09:04.428 27764 18504 I NearbyDiscovery: FastPairController: onSystemStateChanged: skip due to flag disabled [CONTEXT service_id=265 ] 09-03 11:09:04.432 24249 24249 I GsaVoiceInteractionSrv: O received Intent { act=android.intent.action.USER_PRESENT flg=0x24200010 } 09-03 11:09:04.434 10922 10942 D Benchmark: Waiting 100 ms for [ProcessPid(processName=perfetto, pid=24965)] to die 09-03 11:09:04.455 27764 10622 I Nearby : (REDACTED) Beginning attempt #%d for %s... 09-03 11:09:04.455 27764 9041 I NearbySharing: Uwb feature is off. 09-03 11:09:04.456 27764 9041 I NearbySharing: Stopping Fast Init advertisement because we're not scanning in the foreground. 09-03 11:09:04.457 27254 11363 I Dck : (REDACTED) disableDckSupport: %s 09-03 11:09:04.457 27254 11363 I Dck : (REDACTED) doesDeviceHaveSupportedWirelessCapabilities: wccSysProp:%d, wccMendel:%d, deviceWccOverride:%s 09-03 11:09:04.458 27254 11363 I Dck : Dck module condition - isDckModuleEligible: true [CONTEXT service_id=289 ] 09-03 11:09:04.458 27764 9041 I NearbySharing: Scanning has started in the BACKGROUND 09-03 11:09:04.458 27764 9041 I NearbySharing: Stopping Fast Init advertisement because we're not scanning in the foreground. 09-03 11:09:04.462 27764 10622 I NearbyMediums: BluetoothAdapter's name was not set by Nearby Connections, ignoring call to restore default scan mode. 09-03 11:09:04.463 27764 9041 I NearbySharing: Stopping advertising because no high power receive surface is registered and device is visible to HIDDEN. 09-03 11:09:04.464 27764 9041 I NearbySharing: Stopping FastInitiation scanning because visibility is hidden. 09-03 11:09:04.465 27764 10622 I NearbyMediums: BluetoothAdapter's name was not set by Nearby Connections, ignoring call to restore device name 09-03 11:09:04.466 27764 9041 I NearbySharing: Fast Initiation Scanning has stopped 09-03 11:09:04.467 27764 9041 I NearbySharing: Dismissing HUN as there's no device nearby. 09-03 11:09:04.474 27764 27790 I NearbyConnections: Client 103954842 requested setting downloads directory to Quick Share. 09-03 11:09:04.474 27764 27790 I NearbyConnections: Client 103954842 with package name 0p:com.google.android.gms.nearby.sharing and sdk version 243333000 has connected, downloads dir is set to Quick Share. 09-03 11:09:04.476 27254 17729 I Dck : (REDACTED) disableDckSupport: %s 09-03 11:09:04.476 27254 17729 I Dck : (REDACTED) doesDeviceHaveSupportedWirelessCapabilities: wccSysProp:%d, wccMendel:%d, deviceWccOverride:%s 09-03 11:09:04.476 27254 17729 I Dck : Dck module condition - isDckModuleEligible: true [CONTEXT service_id=289 ] 09-03 11:09:04.478 27764 27790 I NearbyConnections: Client 103954842 requested discovery to start. 09-03 11:09:04.479 27764 27764 I RCNIntentOperation: RCN is disabled on this network. 09-03 11:09:04.485 27764 11368 I Nearby : (REDACTED) Note nearby client %s for operation %s 09-03 11:09:04.486 27764 11368 I NearbyConnections: Get local endpoint id from random generation YH7Q for client 103954842 09-03 11:09:04.490 27764 11368 I NearbyMediums: NFC discovery successfully started. 09-03 11:09:04.491 27764 11368 I NearbyMediums: In ScanningCancellationFlag, service-id=NearbySharing has become the first scanner. The flag has been reset. 09-03 11:09:04.492 27764 9041 D BluetoothAdapter: isLeEnabled(): ON 09-03 11:09:04.493 2789 5718 I bt_shim_scanner: system/main/shim/le_scanning_manager.cc:148 RegisterScanner: in shim layer, UUID=f0a9d35a-9e78-4a1e-9b8e-377a8960cca6 09-03 11:09:04.494 27764 27981 D BluetoothLeScanner: onScannerRegistered() - status=0 scannerId=2 mScannerId=0 09-03 11:09:04.497 2789 3160 I bt_shim_scanner: system/main/shim/le_scanning_manager.cc:249 ScanFilterEnable: in shim layer 09-03 11:09:04.497 2789 3160 I bt_shim_scanner: system/main/shim/le_scanning_manager.cc:226 ScanFilterAdd: in shim layer 09-03 11:09:04.498 2789 3160 I bt_shim_scanner: system/main/shim/le_scanning_manager.cc:193 ScanFilterParamSetup: in shim layer, clientIf=2 09-03 11:09:04.498 2789 3160 I bt_shim_scanner: system/main/shim/le_scanning_manager.cc:226 ScanFilterAdd: in shim layer 09-03 11:09:04.498 2789 3160 I bt_shim_scanner: system/main/shim/le_scanning_manager.cc:193 ScanFilterParamSetup: in shim layer, clientIf=2 09-03 11:09:04.499 27764 11368 I NearbyMediums: Started BLE scanning, service-id=NearbySharing, is-extended-advert=true, power-level=1, scan-mode=3 09-03 11:09:04.502 27764 11368 I NearbyConnections: Listening to visibility changes for client 103954842 09-03 11:09:04.505 27764 8744 I NearbySharing: Successfully started LOW_POWER discovery over Nearby Connections 09-03 11:09:04.583 10922 10942 D Benchmark: Waiting 100 ms for [ProcessPid(processName=perfetto, pid=24965)] to die 09-03 11:09:04.724 10922 10942 D Benchmark: Waiting 100 ms for [ProcessPid(processName=perfetto, pid=24965)] to die 09-03 11:09:04.762 1647 2499 D InetDiagMessage: Destroyed 0 sockets, proto=IPPROTO_TCP, family=AF_INET, states=14 09-03 11:09:04.765 1647 2499 D InetDiagMessage: Destroyed 1 sockets, proto=IPPROTO_TCP, family=AF_INET6, states=14 09-03 11:09:04.765 1647 2499 D InetDiagMessage: Destroyed live tcp sockets for uids={10139} in 6ms 09-03 11:09:04.844 1109 1109 I WifiHAL : Creating message to get link statistics; iface = 47 09-03 11:09:04.864 10922 10942 D Benchmark: Waiting 100 ms for [ProcessPid(processName=perfetto, pid=24965)] to die 09-03 11:09:04.918 1647 2499 D ConnectivityService: NetReassign [no changes] [c 0] [a 1] [i 2] 09-03 11:09:05.032 10922 10942 D Benchmark: Waiting 100 ms for [ProcessPid(processName=perfetto, pid=24965)] to die 09-03 11:09:05.213 10922 10942 D Benchmark: Waiting 100 ms for [ProcessPid(processName=perfetto, pid=24965)] to die 09-03 11:09:05.373 1647 4219 D KeySyncTask: Using the public key in stored CertPath for syncing 09-03 11:09:05.394 10922 10942 D Benchmark: Waiting 100 ms for [ProcessPid(processName=perfetto, pid=24965)] to die 09-03 11:09:05.441 1647 4219 I PlatformKeyManager: Platform key generation 1001000 exists already. 09-03 11:09:05.486 1647 4219 D RecoverySnapshotLstnrs: Successfully notified listener. 09-03 11:09:05.490 27254 3955 I RecoverableKeyStoreGms: [FolsomSyncManager] Starting sync for event: NEW_SNAPSHOT 09-03 11:09:05.519 1647 4304 I RecoverableAppKeyStore: Get 0/10146/com.google.android.gms.auth.folsom/v1/1697514977052/6F83437670A769C1CCD4B3F916F7228F 09-03 11:09:05.525 1052 1079 D CHRE.HAL: Received a nanoapp message from 0x476f6f676c001022 endpoint 0x8082: Type 0x3 size 8 09-03 11:09:05.525 1082 1082 I suez-nanoapp-clients: Barometric peak detected: magnitude = 0.129787 hPa (bucket: 3), duration = 39.000000 ms (bucket: 3). 09-03 11:09:05.527 1082 1082 I suez-nanoapp-clients: Vendor atom [id = 100047] reported. 09-03 11:09:05.527 1052 1079 D CHRE.HAL: Received a nanoapp message from 0x476f6f676c001022 endpoint 0x8082: Type 0x3 size 8 09-03 11:09:05.527 1082 1082 I suez-nanoapp-clients: Barometric peak detected: magnitude = 0.129170 hPa (bucket: 3), duration = 40.000000 ms (bucket: 3). 09-03 11:09:05.530 1082 1082 I suez-nanoapp-clients: Vendor atom [id = 100047] reported. 09-03 11:09:05.530 10922 10942 D Benchmark: Waiting 100 ms for [ProcessPid(processName=perfetto, pid=24965)] to die 09-03 11:09:05.530 1052 1079 I CHRE : @ 330350.446: [ip] Peak detected: magnitude = 0.129787 hpa, duration = 38.999998 ms 09-03 11:09:05.530 1052 1079 I CHRE : @ 330350.446: [ip] Peak detected: magnitude = 0.129169 hpa, duration = 40.000000 ms 09-03 11:09:05.532 1647 4304 I RecoverableAppKeyStore: Get 0/10146/com.google.android.gms.auth.folsom/v1/1725067930244/90F50859274F153890F5CBF1D4BD7196 09-03 11:09:05.547 1647 4304 I RecoverableAppKeyStore: Get 0/10146/com.google.android.gms.auth.folsom/v1/1725067930244/90F50859274F153890F5CBF1D4BD7196 09-03 11:09:05.557 1647 4304 I RecoverableAppKeyStore: Get 0/10146/com.google.android.gms.auth.folsom/v1/1725067930244/90F50859274F153890F5CBF1D4BD7196 09-03 11:09:05.578 27254 3955 I GmsCoreXrpcWrapper: (REDACTED) Returning a channel provider with trafficStatsTag=%d trafficStatsUid=%d 09-03 11:09:05.669 10922 10942 D Benchmark: Waiting 100 ms for [ProcessPid(processName=perfetto, pid=24965)] to die 09-03 11:09:05.683 27254 27348 D TrafficStats: tagSocket(431) with statsTag=0x3c004800, statsUid=-1 09-03 11:09:05.844 10922 10942 D Benchmark: Waiting 100 ms for [ProcessPid(processName=perfetto, pid=24965)] to die 09-03 11:09:05.849 27254 27348 I cr_CronetUrlRequestContext: destroyNativeStreamLocked org.chromium.net.impl.CronetBidirectionalStream@4e948eb 09-03 11:09:05.862 27254 3955 W ChimeraUtils: Module com.google.android.gms.auth_folsom missing resource null(0) 09-03 11:09:05.869 27254 3955 W ChimeraUtils: Module com.google.android.gms.auth_folsom missing resource null(0) 09-03 11:09:05.892 27254 11407 D TrafficStats: tagSocket(434) with statsTag=0xffffffff, statsUid=-1 09-03 11:09:05.956 1039 3116 I bthal.activity: bt_power: LeAdvStat: 11:08:55:546 - 11:09:05:956, duration: 10409, le_adv_count: 89, total_le_adv_count: 22467, total_event_count: 122 09-03 11:09:05.981 10922 10942 D Benchmark: Waiting 100 ms for [ProcessPid(processName=perfetto, pid=24965)] to die 09-03 11:09:06.120 10922 10942 D Benchmark: Waiting 100 ms for [ProcessPid(processName=perfetto, pid=24965)] to die 09-03 11:09:06.158 27254 3955 I GmsCoreXrpcWrapper: (REDACTED) Returning a channel provider with trafficStatsTag=%d trafficStatsUid=%d 09-03 11:09:06.300 10922 10942 D Benchmark: Waiting 100 ms for [ProcessPid(processName=perfetto, pid=24965)] to die 09-03 11:09:06.325 27254 27348 I cr_CronetUrlRequestContext: destroyNativeStreamLocked org.chromium.net.impl.CronetBidirectionalStream@611228f 09-03 11:09:06.432 27254 3955 I GmsCoreXrpcWrapper: (REDACTED) Returning a channel provider with trafficStatsTag=%d trafficStatsUid=%d 09-03 11:09:06.481 10922 10942 D Benchmark: Waiting 100 ms for [ProcessPid(processName=perfetto, pid=24965)] to die 09-03 11:09:06.617 10922 10942 D Benchmark: Waiting 100 ms for [ProcessPid(processName=perfetto, pid=24965)] to die 09-03 11:09:06.657 27254 27348 I cr_CronetUrlRequestContext: destroyNativeStreamLocked org.chromium.net.impl.CronetBidirectionalStream@1edc180 09-03 11:09:06.659 27254 3955 I RecoverableKeyStoreGms: (REDACTED) [KeySyncController] Successfully synced with remote vault that has name '%s' 09-03 11:09:06.695 27254 3955 I RecoverableKeyStoreGms: [FolsomSyncManager] Starting sync for event: NEW_SNAPSHOT 09-03 11:09:06.699 1647 2485 I system_server: Explicit concurrent mark compact GC freed 85MB AllocSpace bytes, 81(1896KB) LOS objects, 52% free, 86MB/182MB, paused 5.262ms,21.333ms total 1.344s 09-03 11:09:06.718 1647 1712 W System : A resource failed to call HardwareBuffer.close. 09-03 11:09:06.723 1647 1712 W system_server: ApkAssets: Deleting an ApkAssets object ' and /data/app/~~s03k7Cg2eu_T-MCZVlNrhg==/com.google.android.inputmethod.latin-KKJFOY5Hrds0AAkZ0Skikg==/base.apk' with 1 weak references 09-03 11:09:06.724 1647 1712 W system_server: ApkAssets: Deleting an ApkAssets object ' and /data/app/~~s03k7Cg2eu_T-MCZVlNrhg==/com.google.android.inputmethod.latin-KKJFOY5Hrds0AAkZ0Skikg==/split_brella_feature_split.apk' with 1 weak references 09-03 11:09:06.724 1647 1712 W system_server: ApkAssets: Deleting an ApkAssets object ' and /data/app/~~s03k7Cg2eu_T-MCZVlNrhg==/com.google.android.inputmethod.latin-KKJFOY5Hrds0AAkZ0Skikg==/split_config.xxxhdpi.apk' with 1 weak references 09-03 11:09:06.724 1647 1712 W system_server: ApkAssets: Deleting an ApkAssets object ' and /data/app/~~s03k7Cg2eu_T-MCZVlNrhg==/com.google.android.inputmethod.latin-KKJFOY5Hrds0AAkZ0Skikg==/split_tenoranimation_feature_split.apk' with 1 weak references 09-03 11:09:06.725 1647 1712 W system_server: ApkAssets: Deleting an ApkAssets object ' and /data/app/~~jvCibntqquuCnbwV88xXaA==/com.google.android.tts-kbjzPq2xOQZUzzZQu_dnEQ==/base.apk' with 1 weak references 09-03 11:09:06.726 1647 1712 W system_server: ApkAssets: Deleting an ApkAssets object ' and /data/app/~~njXZCGnbl_DwDibWkhFCrQ==/com.google.android.apps.inputmethod.hindi-Rxa7-s-yPZKzRsOBi-6wMA==/base.apk' with 1 weak references 09-03 11:09:06.727 1647 1712 W system_server: ApkAssets: Deleting an ApkAssets object ' and /data/app/~~MDmcgusu2qy7C9AFoqwgBQ==/com.koushikdutta.vysor-Zi2iQjNj8uqN8tFrdbGwlQ==/base.apk' with 1 weak references 09-03 11:09:06.735 1647 1712 W system_server: ApkAssets: Deleting an ApkAssets object ' and /data/app/~~TjbtnlLr-2NfbmLWKPqoEw==/com.google.android.gms-xk8w54udhd6c3qYSf-f6SA==/base.apk' with 1 weak references 09-03 11:09:06.736 1647 1712 W system_server: ApkAssets: Deleting an ApkAssets object ' and /data/app/~~TjbtnlLr-2NfbmLWKPqoEw==/com.google.android.gms-xk8w54udhd6c3qYSf-f6SA==/split_CronetDynamite_installtime.apk' with 1 weak references 09-03 11:09:06.736 1647 1712 W system_server: ApkAssets: Deleting an ApkAssets object ' and /data/app/~~TjbtnlLr-2NfbmLWKPqoEw==/com.google.android.gms-xk8w54udhd6c3qYSf-f6SA==/split_DynamiteModulesA_installtime.apk' with 1 weak references 09-03 11:09:06.736 1647 1712 W system_server: ApkAssets: Deleting an ApkAssets object ' and /data/app/~~TjbtnlLr-2NfbmLWKPqoEw==/com.google.android.gms-xk8w54udhd6c3qYSf-f6SA==/split_DynamiteModulesC_installtime.apk' with 1 weak references 09-03 11:09:06.736 1647 1712 W system_server: ApkAssets: Deleting an ApkAssets object ' and /data/app/~~TjbtnlLr-2NfbmLWKPqoEw==/com.google.android.gms-xk8w54udhd6c3qYSf-f6SA==/split_GoogleCertificates_installtime.apk' with 1 weak references 09-03 11:09:06.736 1647 1712 W system_server: ApkAssets: Deleting an ApkAssets object ' and /data/app/~~TjbtnlLr-2NfbmLWKPqoEw==/com.google.android.gms-xk8w54udhd6c3qYSf-f6SA==/split_MapsDynamite_installtime.apk' with 1 weak references 09-03 11:09:06.736 1647 1712 W system_server: ApkAssets: Deleting an ApkAssets object ' and /data/app/~~TjbtnlLr-2NfbmLWKPqoEw==/com.google.android.gms-xk8w54udhd6c3qYSf-f6SA==/split_MeasurementDynamite_installtime.apk' with 1 weak references 09-03 11:09:06.737 1647 1712 W system_server: ApkAssets: Deleting an ApkAssets object ' and /data/app/~~TjbtnlLr-2NfbmLWKPqoEw==/com.google.android.gms-xk8w54udhd6c3qYSf-f6SA==/split_config.de.apk' with 1 weak references 09-03 11:09:06.737 1647 1712 W system_server: ApkAssets: Deleting an ApkAssets object ' and /data/app/~~TjbtnlLr-2NfbmLWKPqoEw==/com.google.android.gms-xk8w54udhd6c3qYSf-f6SA==/split_config.en.apk' with 1 weak references 09-03 11:09:06.737 1647 1712 W system_server: ApkAssets: Deleting an ApkAssets object ' and /data/app/~~TjbtnlLr-2NfbmLWKPqoEw==/com.google.android.gms-xk8w54udhd6c3qYSf-f6SA==/split_config.es.apk' with 1 weak references 09-03 11:09:06.737 1647 1712 W system_server: ApkAssets: Deleting an ApkAssets object ' and /data/app/~~TjbtnlLr-2NfbmLWKPqoEw==/com.google.android.gms-xk8w54udhd6c3qYSf-f6SA==/split_config.xxxhdpi.apk' with 1 weak references 09-03 11:09:06.753 27254 3955 I RecoverableKeyStoreGms: [FolsomSyncManager] Starting sync for event: NEW_SNAPSHOT 09-03 11:09:06.783 10922 10942 D Benchmark: Waiting 100 ms for [ProcessPid(processName=perfetto, pid=24965)] to die 09-03 11:09:06.923 10922 10942 D Benchmark: Waiting 100 ms for [ProcessPid(processName=perfetto, pid=24965)] to die 09-03 11:09:07.080 10922 10942 D Benchmark: Waiting 100 ms for [ProcessPid(processName=perfetto, pid=24965)] to die 09-03 11:09:07.188 1647 2499 D InetDiagMessage: Destroyed 0 sockets, proto=IPPROTO_TCP, family=AF_INET, states=14 09-03 11:09:07.191 1647 2499 D InetDiagMessage: Destroyed 0 sockets, proto=IPPROTO_TCP, family=AF_INET6, states=14 09-03 11:09:07.191 1647 2499 D InetDiagMessage: Destroyed live tcp sockets for uids={10145} in 5ms 09-03 11:09:07.229 10922 10942 D Benchmark: Waiting 100 ms for [ProcessPid(processName=perfetto, pid=24965)] to die 09-03 11:09:07.397 10922 10942 D Benchmark: Waiting 100 ms for [ProcessPid(processName=perfetto, pid=24965)] to die 09-03 11:09:07.533 10922 10942 D Benchmark: Waiting 100 ms for [ProcessPid(processName=perfetto, pid=24965)] to die 09-03 11:09:07.657 1647 2485 I system_server: Explicit concurrent mark compact GC freed 3924KB AllocSpace bytes, 2(72KB) LOS objects, 53% free, 82MB/178MB, paused 2.394ms,15.127ms total 896.725ms 09-03 11:09:07.669 10922 10942 D Benchmark: Waiting 100 ms for [ProcessPid(processName=perfetto, pid=24965)] to die 09-03 11:09:07.831 10922 10942 D Benchmark: Waiting 100 ms for [ProcessPid(processName=perfetto, pid=24965)] to die 09-03 11:09:07.918 1109 1109 I WifiHAL : Creating message to get link statistics; iface = 47 09-03 11:09:07.969 10922 10942 D Benchmark: Waiting 100 ms for [ProcessPid(processName=perfetto, pid=24965)] to die 09-03 11:09:08.004 4606 4606 W JobService: onNetworkChanged() not implemented in de.dwd.warnapp.gpspush.modern.BackgroundLocationJobService. Must override in a subclass. 09-03 11:09:08.010 3239 3344 D IwlanDataService: onCapabilitiesChanged: 151 [ Transports: WIFI Capabilities: NOT_METERED&INTERNET&NOT_RESTRICTED&TRUSTED&NOT_VPN&VALIDATED&NOT_ROAMING&FOREGROUND&NOT_CONGESTED&NOT_SUSPENDED&NOT_VCN_MANAGED&NOT_BANDWIDTH_CONSTRAINED LinkUpBandwidth>=2800Kbps LinkDnBandwidth>=33572Kbps TransportInfo: , Provider friendly name: , Requesting package name: "Fritz!Box 7590 AX"wpa2-pskMLO Information: , Is TID-To-Link negotiation supported by the AP: false, AP MLD Address: , AP MLO Link Id: , AP MLO Affiliated links: , Vendor Data: > SignalStrength: -62 OwnerUid: 1000 AdminUids: [1000] SSID: "Fritz!Box 7590 AX" UnderlyingNetworks: Null] 09-03 11:09:08.010 3239 3344 D IwlanDataService: Network 151 connected using transport WIFI 09-03 11:09:08.011 3239 3530 D IwlanNetworkService: onCapabilitiesChanged: 151 09-03 11:09:08.011 2931 4063 W SHANNON_IMS: 7888 [CONN] NetworkSpecifier and TransportInfo are not valid (DefaultNetworkCallback%updateSubscriptionId:89) 09-03 11:09:08.011 3462 3508 D DeviceStateHelper: Wifi freq: 2437 09-03 11:09:08.011 2931 4063 I SHANNON_IMS: 7891 [CONN] network:151 WIFI connected, subId: -1 [SLID:0] (DefaultNetworkCallback%onCapabilitiesChanged:71) 09-03 11:09:08.012 2903 4032 D SHANNON_RCS: 9714 [0][PROX][TELE] onCapabilitiesChanged, Network: 151, NetworkCapabilities: [ Transports: WIFI Capabilities: NOT_METERED&INTERNET&NOT_RESTRICTED&TRUSTED&NOT_VPN&VALIDATED&NOT_ROAMING&FOREGROUND&NOT_CONGESTED&NOT_SUSPENDED&NOT_VCN_MANAGED&NOT_BANDWIDTH_CONSTRAINED LinkUpBandwidth>=2800Kbps LinkDnBandwidth>=33572Kbps TransportInfo: , BSSID: 02:00:00:00:00:00, MAC: 02:00:00:00:00:00, IP: /192.168.188.71, Security type: 2, Supplicant state: COMPLETED, Wi-Fi standard: 11n, RSSI: -62, Link speed: 173Mbps, Tx Link speed: 173Mbps, Max Supported Tx Link speed: 144Mbps, Rx Link speed: 144Mbps, Max Supported Rx Link speed: 144Mbps, Frequency: 2437MHz, Net ID: -1, Metered hint: false, score: 60, isUsable: true, CarrierMerged: false, SubscriptionId: -1, IsPrimary: -1, Trusted: true, Restricted: false, Ephemeral: false, OEM paid: false, OEM private: false, OSU AP: false, FQDN: , Provider friendly name: , Requesting package name: MLO Information: , Is TID-To-Link negotiation supported by the AP: false, AP MLD Address: , AP MLO Link Id: , AP MLO Affiliated links: , Vendor Data: > SignalStrength: -62 UnderlyingNetworks: Null] (DefaultNetworkCallback%onCapabilitiesChanged:76) 09-03 11:09:08.018 24361 24453 I BugleRcsEngine: handleMessage processing message:[NOTIFY_UPTIME_IGNORE_STATE_CHANGED] with [non-null]:RcsEngineImpl reference [CONTEXT log_prefix="RcsEngineImpl[DUAL_REG]:[8d8dc52d-0216]>Handler" thread_id=71 ] 09-03 11:09:08.018 24361 24392 I BugleRcsEngine: Connected state: [1], networkType: [WIFI] [CONTEXT thread_id=60 ] 09-03 11:09:08.019 24361 24453 I BugleRcsEngine: Cannot use CS.apk as it's battery optimized [CONTEXT thread_id=71 ] 09-03 11:09:08.021 4427 5555 D WM-NetworkStateTracker: Network capabilities changed: [ Transports: WIFI Capabilities: NOT_METERED&INTERNET&NOT_RESTRICTED&TRUSTED&NOT_VPN&VALIDATED&NOT_ROAMING&FOREGROUND&NOT_CONGESTED&NOT_SUSPENDED&NOT_VCN_MANAGED&NOT_BANDWIDTH_CONSTRAINED LinkUpBandwidth>=2800Kbps LinkDnBandwidth>=33572Kbps TransportInfo: , BSSID: 02:00:00:00:00:00, MAC: 02:00:00:00:00:00, IP: /192.168.188.71, Security type: 2, Supplicant state: COMPLETED, Wi-Fi standard: 11n, RSSI: -62, Link speed: 173Mbps, Tx Link speed: 173Mbps, Max Supported Tx Link speed: 144Mbps, Rx Link speed: 144Mbps, Max Supported Rx Link speed: 144Mbps, Frequency: 2437MHz, Net ID: -1, Metered hint: false, score: 60, isUsable: true, CarrierMerged: false, SubscriptionId: -1, IsPrimary: -1, Trusted: true, Restricted: false, Ephemeral: false, OEM paid: false, OEM private: false, OSU AP: false, FQDN: , Provider friendly name: , Requesting package name: MLO Information: , Is TID-To-Link negotiation supported by the AP: false, AP MLD Address: , AP MLO Link Id: , AP MLO Affiliated links: , Vendor Data: > SignalStrength: -62 UnderlyingNetworks: Null] 09-03 11:09:08.021 27764 27981 I ChimeraSrvcProxy: NullBinder for android.net.action.RECOMMEND_NETWORKS triggering remote TransactionTooLargeException due to Service without Chimera impl 09-03 11:09:08.021 27764 27981 W .gms.persistent: Large reply transaction of 1056768 bytes, interface descriptor , code 1 09-03 11:09:08.023 3462 11216 I WifiSignalInfo: onCapabilitiesChanged: rssi:-62 09-03 11:09:08.023 27254 27254 D BoundBrokerSvc: onUnbind: Intent { act=com.google.android.gms.backup.GMS_MODULE_RESTORE dat=chimera-action:/... cmp=com.google.android.gms/.chimera.GmsInternalBoundBrokerService } 09-03 11:09:08.032 24361 24453 I BugleRcsEngine: Rcs is enabled from user settings: true [CONTEXT log_prefix="ProvisioningEngineDataRetriever" thread_id=71 ] 09-03 11:09:08.035 1647 2499 D ConnectivityService: NetReassign [no changes] [c 1] [a 0] [i 3] 09-03 11:09:08.116 10922 10942 D Benchmark: Waiting 100 ms for [ProcessPid(processName=perfetto, pid=24965)] to die 09-03 11:09:08.299 10922 10942 D Benchmark: Waiting 100 ms for [ProcessPid(processName=perfetto, pid=24965)] to die 09-03 11:09:08.438 10922 10942 D Benchmark: Waiting 100 ms for [ProcessPid(processName=perfetto, pid=24965)] to die 09-03 11:09:08.579 1843 1845 I pixel-thermal: usb_pwr_therm2:33.62 raw data: usb_pwr_therm2:33620 09-03 11:09:08.582 2577 8690 I ndroid.systemui: Explicit concurrent mark compact GC freed 44MB AllocSpace bytes, 14(512KB) LOS objects, 65% free, 51MB/147MB, paused 1.137ms,2.579ms total 334.696ms 09-03 11:09:08.583 1843 1845 I pixel-thermal: quiet_therm:30.726 raw data: quiet_therm:30726 09-03 11:09:08.584 1843 1845 I pixel-thermal: VIRTUAL-USB-THROTTLING:0 raw data: USB2-MINUS-QI:-5854 USB2-MINUS-USB:-267 VIRTUAL-USB-THROTTLING:0 qi_therm:39474 usb_pwr_therm:33887 usb_pwr_therm2:33620 09-03 11:09:08.584 1843 1845 I pixel-thermal: VIRTUAL-USB-UI:0 raw data: USB2-MINUS-QI:-5854 USB2-MINUS-USB:-267 VIRTUAL-USB-UI:0 usb_pwr_therm2:33620 09-03 11:09:08.584 1843 1845 I pixel-thermal: Power rails total power: 919.15 mW for 60067 ms 09-03 11:09:08.584 1843 1845 I pixel-thermal: Power rails [S1M_VDD_MIF: 97.59 mW] [S5M_VDD_INT: 73.37 mW] [S3M_VDD_CPUCL1: 84.21 mW] [S2M_VDD_CPUCL2: 170.44 mW] [VSYS_PWR_MODEM: 91.86 mW] [L2S_VDD_AOC_RET: 13.50 mW] 09-03 11:09:08.584 1843 1845 I pixel-thermal: Power rails [S10M_VDD_TPU: 13.63 mW] [VSYS_PWR_DISPLAY: 61.86 mW] [S9S_VDD_AOC: 15.21 mW] [S4S_VDD2H_MEM: 64.69 mW] [S2S_VDD_G3D: 24.98 mW] [S5S_VDDQ_MEM: 13.31 mW] 09-03 11:09:08.584 1843 1845 I pixel-thermal: Power rails [S10S_VDD2L: 9.63 mW] [S4M_VDD_CPUCL0: 174.36 mW] [VSYS_PWR_RFFE: 10.49 mW] [VSYS_PWR_MMWAVE: 0.00 mW] 09-03 11:09:08.599 10922 10942 D Benchmark: Waiting 100 ms for [ProcessPid(processName=perfetto, pid=24965)] to die 09-03 11:09:08.606 2577 2590 W System : A resource failed to call release. 09-03 11:09:08.610 2577 2590 W System : A resource failed to call release. 09-03 11:09:08.610 2577 2590 W System : A resource failed to call release. 09-03 11:09:08.613 2577 2590 W System : A resource failed to call release. 09-03 11:09:08.613 2577 2590 W ndroid.systemui: ApkAssets: Deleting an ApkAssets object ' and /data/app/~~7AGgwOWuzx84QkqE4qOFIw==/com.android.vending-OJdTtPf05pT5wYR4NIm-Jg==/base.apk' with 1 weak references 09-03 11:09:08.614 2577 2590 W ndroid.systemui: ApkAssets: Deleting an ApkAssets object ' and /data/app/~~7AGgwOWuzx84QkqE4qOFIw==/com.android.vending-OJdTtPf05pT5wYR4NIm-Jg==/split_config.arm64_v8a.apk' with 1 weak references 09-03 11:09:08.614 2577 2590 W ndroid.systemui: ApkAssets: Deleting an ApkAssets object ' and /data/app/~~7AGgwOWuzx84QkqE4qOFIw==/com.android.vending-OJdTtPf05pT5wYR4NIm-Jg==/split_config.de.apk' with 1 weak references 09-03 11:09:08.614 2577 2590 W ndroid.systemui: ApkAssets: Deleting an ApkAssets object ' and /data/app/~~7AGgwOWuzx84QkqE4qOFIw==/com.android.vending-OJdTtPf05pT5wYR4NIm-Jg==/split_config.en.apk' with 1 weak references 09-03 11:09:08.615 2577 2590 W ndroid.systemui: ApkAssets: Deleting an ApkAssets object ' and /data/app/~~7AGgwOWuzx84QkqE4qOFIw==/com.android.vending-OJdTtPf05pT5wYR4NIm-Jg==/split_config.es.apk' with 1 weak references 09-03 11:09:08.615 2577 2590 W ndroid.systemui: ApkAssets: Deleting an ApkAssets object ' and /data/app/~~7AGgwOWuzx84QkqE4qOFIw==/com.android.vending-OJdTtPf05pT5wYR4NIm-Jg==/split_phonesky_data_loader.apk' with 1 weak references 09-03 11:09:08.615 2577 2590 W ndroid.systemui: ApkAssets: Deleting an ApkAssets object ' and /data/app/~~7AGgwOWuzx84QkqE4qOFIw==/com.android.vending-OJdTtPf05pT5wYR4NIm-Jg==/split_phonesky_data_loader.config.arm64_v8a.apk' with 1 weak references 09-03 11:09:08.620 2577 2590 W System : A resource failed to call release. 09-03 11:09:08.620 2577 2590 W System : A resource failed to call release. 09-03 11:09:08.620 2577 2590 W System : A resource failed to call release. 09-03 11:09:08.737 10922 10942 D Benchmark: Waiting 100 ms for [ProcessPid(processName=perfetto, pid=24965)] to die 09-03 11:09:08.749 4184 30886 I MediaProvider: Deleted 1 0 0 0 0 0 0 items on external_primary due to com.example.baselineprofile 09-03 11:09:08.752 10922 10942 D Benchmark: Killing process com.example.composeplayground 09-03 11:09:08.753 10922 10942 D UiDevice: Executing shell command: am force-stop com.example.composeplayground 09-03 11:09:08.787 27764 10713 I NetworkScheduler.Stats: (REDACTED) Task %s/%s started execution. cause:%s exec_start_elapsed_seconds: %s 09-03 11:09:08.790 27764 10820 I NetworkScheduler.Stats: (REDACTED) Task %s/%s finished executing. cause:%s result: %s elapsed_millis: %s uptime_millis: %s exec_start_elapsed_seconds: %s 09-03 11:09:08.808 10922 10942 I System.out: stopserver 09-03 11:09:08.811 2948 2948 D CarrierSvcBindHelper: onHandleForceStop: [com.example.composeplayground] 09-03 11:09:08.812 2948 3201 D EuiccConnector: handleMessage: E msg.what=1 09-03 11:09:08.812 2948 3201 D EuiccConnector: processMsg: ConnectedState 09-03 11:09:08.812 2948 3201 D EuiccConnector: processMsg: DisconnectedState 09-03 11:09:08.814 2948 2948 D CarrierSvcBindHelper: No carrier app for: 0 09-03 11:09:08.814 2948 2948 D CarrierSvcBindHelper: No carrier app for: 1 09-03 11:09:08.816 4184 10897 W MediaProvider: isAppCloneUserPair for user 0: false 09-03 11:09:08.818 2948 3201 D EuiccConnector: handleMessage: X 09-03 11:09:08.849 27764 10713 I NetworkScheduler.Stats: (REDACTED) Task %s/%s started execution. cause:%s exec_start_elapsed_seconds: %s 09-03 11:09:08.850 4184 5455 W MediaProvider: Ignoring param:deletedata post U for external apps 09-03 11:09:08.851 4184 5455 I MediaProvider: Invalidating LocalCallingIdentity cache for package com.example.baselineprofile. Reason: owned_database_row_deleted:/storage/emulated/0/Android/media/com.example.baselineprofile/temporaryScript1827242763671452906.tmp 09-03 11:09:08.852 4184 5455 I MediaProvider: Deleted 1 0 0 0 0 0 0 items on external_primary due to com.android.shell 09-03 11:09:08.853 10922 10942 I PerfettoHttpServer: Perfetto trace processor shell server stopped (pid=11178). 09-03 11:09:08.854 10922 10942 E TestRunner: failed: startupCompilationBaselineProfiles(com.example.baselineprofile.StartupBenchmarks) 09-03 11:09:08.857 10922 10942 E TestRunner: ----- begin exception ----- 09-03 11:09:08.857 10922 10942 E TestRunner: java.lang.IllegalStateException: Failed to stop [ProcessPid(processName=perfetto, pid=24965)] 09-03 11:09:08.857 10922 10942 E TestRunner: at androidx.benchmark.Shell.terminateProcessesAndWait(Shell.kt:617) 09-03 11:09:08.857 10922 10942 E TestRunner: at androidx.benchmark.Shell.terminateProcessesAndWait(Shell.kt:587) 09-03 11:09:08.857 10922 10942 E TestRunner: at androidx.benchmark.perfetto.PerfettoHelper$Companion.stopAllPerfettoProcesses(PerfettoHelper.kt:470) 09-03 11:09:08.857 10922 10942 E TestRunner: at androidx.benchmark.perfetto.PerfettoHelper.startCollecting(PerfettoHelper.kt:85) 09-03 11:09:08.857 10922 10942 E TestRunner: at androidx.benchmark.perfetto.PerfettoCapture.start(PerfettoCapture.kt:69) 09-03 11:09:08.857 10922 10942 E TestRunner: at androidx.benchmark.perfetto.PerfettoCaptureWrapper.start(PerfettoCaptureWrapper.kt:76) 09-03 11:09:08.857 10922 10942 E TestRunner: at androidx.benchmark.perfetto.PerfettoCaptureWrapper.record(PerfettoCaptureWrapper.kt:131) 09-03 11:09:08.857 10922 10942 E TestRunner: at androidx.benchmark.perfetto.PerfettoCaptureWrapper.record$default(PerfettoCaptureWrapper.kt:95) 09-03 11:09:08.857 10922 10942 E TestRunner: at androidx.benchmark.macro.MacrobenchmarkPhaseKt.runPhase(MacrobenchmarkPhase.kt:105) 09-03 11:09:08.857 10922 10942 E TestRunner: at androidx.benchmark.macro.MacrobenchmarkKt$macrobenchmark$4.invoke(Macrobenchmark.kt:258) 09-03 11:09:08.857 10922 10942 E TestRunner: at androidx.benchmark.macro.MacrobenchmarkKt$macrobenchmark$4.invoke(Macrobenchmark.kt:255) 09-03 11:09:08.857 10922 10942 E TestRunner: at androidx.benchmark.perfetto.PerfettoTraceProcessor$Companion.runServer-VtjQ1oo(PerfettoTraceProcessor.kt:133) 09-03 11:09:08.857 10922 10942 E TestRunner: at androidx.benchmark.perfetto.PerfettoTraceProcessor$Companion.runServer(PerfettoTraceProcessor.kt:108) 09-03 11:09:08.857 10922 10942 E TestRunner: at androidx.benchmark.macro.MacrobenchmarkKt.macrobenchmark(Macrobenchmark.kt:255) 09-03 11:09:08.857 10922 10942 E TestRunner: at androidx.benchmark.macro.MacrobenchmarkKt.macrobenchmarkWithStartupMode(Macrobenchmark.kt:392) 09-03 11:09:08.857 10922 10942 E TestRunner: at androidx.benchmark.macro.junit4.MacrobenchmarkRule.measureRepeated(MacrobenchmarkRule.kt:108) 09-03 11:09:08.857 10922 10942 E TestRunner: at com.example.baselineprofile.StartupBenchmarks.benchmark(StartupBenchmarks.kt:52) 09-03 11:09:08.857 10922 10942 E TestRunner: at com.example.baselineprofile.StartupBenchmarks.startupCompilationBaselineProfiles(StartupBenchmarks.kt:48) 09-03 11:09:08.857 10922 10942 E TestRunner: at java.lang.reflect.Method.invoke(Native Method) 09-03 11:09:08.857 10922 10942 E TestRunner: at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:59) 09-03 11:09:08.857 10922 10942 E TestRunner: at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:12) 09-03 11:09:08.857 10922 10942 E TestRunner: at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:56) 09-03 11:09:08.857 10922 10942 E TestRunner: at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:17) 09-03 11:09:08.857 10922 10942 E TestRunner: at androidx.benchmark.macro.junit4.MacrobenchmarkRule$applyInternal$1.evaluate(MacrobenchmarkRule.kt:207) 09-03 11:09:08.857 10922 10942 E TestRunner: at androidx.test.rule.GrantPermissionRule$RequestPermissionStatement.evaluate(GrantPermissionRule.java:136) 09-03 11:09:08.857 10922 10942 E TestRunner: at org.junit.rules.RunRules.evaluate(RunRules.java:20) 09-03 11:09:08.857 10922 10942 E TestRunner: at org.junit.runners.ParentRunner$3.evaluate(ParentRunner.java:306) 09-03 11:09:08.857 10922 10942 E TestRunner: at org.junit.runners.BlockJUnit4ClassRunner$1.evaluate(BlockJUnit4ClassRunner.java:100) 09-03 11:09:08.857 10922 10942 E TestRunner: at org.junit.runners.ParentRunner.runLeaf(ParentRunner.java:366) 09-03 11:09:08.857 10922 10942 E TestRunner: at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:103) 09-03 11:09:08.857 10922 10942 E TestRunner: at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:63) 09-03 11:09:08.857 10922 10942 E TestRunner: at org.junit.runners.ParentRunner$4.run(ParentRunner.java:331) 09-03 11:09:08.857 10922 10942 E TestRunner: at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:79) 09-03 11:09:08.857 10922 10942 E TestRunner: at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:329) 09-03 11:09:08.857 10922 10942 E TestRunner: at org.junit.runners.ParentRunner.access$100(ParentRunner.java:66) 09-03 11:09:08.857 10922 10942 E TestRunner: at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:293) 09-03 11:09:08.857 10922 10942 E TestRunner: at org.junit.runners.ParentRunner$3.evaluate(ParentRunner.java:306) 09-03 11:09:08.857 10922 10942 E TestRunner: at org.junit.runners.ParentRunner.run(ParentRunner.java:413) 09-03 11:09:08.857 10922 10942 E TestRunner: at androidx.test.ext.junit.runners.AndroidJUnit4.run(AndroidJUnit4.java:162) 09-03 11:09:08.857 10922 10942 E TestRunner: at org.junit.runners.Suite.runChild(Suite.java:128) 09-03 11:09:08.857 10922 10942 E TestRunner: at org.junit.runners.Suite.runChild(Suite.java:27) 09-03 11:09:08.857 10922 10942 E TestRunner: at org.junit.runners.ParentRunner$4.run(ParentRunner.java:331) 09-03 11:09:08.857 10922 10942 E TestRunner: at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:79) 09-03 11:09:08.857 10922 10942 E TestRunner: at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:329) 09-03 11:09:08.857 10922 10942 E TestRunner: at org.junit.runners.ParentRunner.access$100(ParentRunner.java:66) 09-03 11:09:08.857 10922 10942 E TestRunner: at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:293) 09-03 11:09:08.857 10922 10942 E TestRunner: at org.junit.runners.ParentRunner$3.evaluate(ParentRunner.java:306) 09-03 11:09:08.857 10922 10942 E TestRunner: at org.junit.runners.ParentRunner.run(ParentRunner.java:413) 09-03 11:09:08.857 10922 10942 E TestRunner: at org.junit.runner.JUnitCore.run(JUnitCore.java:137) 09-03 11:09:08.857 10922 10942 E TestRunner: at org.junit.runner.JUnitCore.run(JUnitCore.java:115) 09-03 11:09:08.857 10922 10942 E TestRunner: at androidx.test.internal.runner.TestExecutor.execute(TestExecutor.java:68) 09-03 11:09:08.857 10922 10942 E TestRunner: at androidx.test.internal.runner.TestExecutor.execute(TestExecutor.java:59) 09-03 11:09:08.857 10922 10942 E TestRunner: at androidx.test.runner.AndroidJUnitRunner.onStart(AndroidJUnitRunner.java:463) 09-03 11:09:08.857 10922 10942 E TestRunner: at android.app.Instrumentation$InstrumentationThread.run(Instrumentation.java:2596) 09-03 11:09:08.857 10922 10942 E TestRunner: ----- end exception ----- 09-03 11:09:08.859 10922 10942 I TestRunner: finished: startupCompilationBaselineProfiles(com.example.baselineprofile.StartupBenchmarks) java.lang.IllegalStateException: Failed to stop [ProcessPid(processName=perfetto, pid=24965)] at androidx.benchmark.Shell.terminateProcessesAndWait(Shell.kt:617) at androidx.benchmark.Shell.terminateProcessesAndWait(Shell.kt:587) at androidx.benchmark.perfetto.PerfettoHelper$Companion.stopAllPerfettoProcesses(PerfettoHelper.kt:470) at androidx.benchmark.perfetto.PerfettoHelper.startCollecting(PerfettoHelper.kt:85) at androidx.benchmark.perfetto.PerfettoCapture.start(PerfettoCapture.kt:69) at androidx.benchmark.perfetto.PerfettoCaptureWrapper.start(PerfettoCaptureWrapper.kt:76) at androidx.benchmark.perfetto.PerfettoCaptureWrapper.record(PerfettoCaptureWrapper.kt:131) at androidx.benchmark.perfetto.PerfettoCaptureWrapper.record$default(PerfettoCaptureWrapper.kt:95) at androidx.benchmark.macro.MacrobenchmarkPhaseKt.runPhase(MacrobenchmarkPhase.kt:105) at androidx.benchmark.macro.MacrobenchmarkKt$macrobenchmark$4.invoke(Macrobenchmark.kt:258) at androidx.benchmark.macro.MacrobenchmarkKt$macrobenchmark$4.invoke(Macrobenchmark.kt:255) at androidx.benchmark.perfetto.PerfettoTraceProcessor$Companion.runServer-VtjQ1oo(PerfettoTraceProcessor.kt:133) at androidx.benchmark.perfetto.PerfettoTraceProcessor$Companion.runServer(PerfettoTraceProcessor.kt:108) at androidx.benchmark.macro.MacrobenchmarkKt.macrobenchmark(Macrobenchmark.kt:255) at androidx.benchmark.macro.MacrobenchmarkKt.macrobenchmarkWithStartupMode(Macrobenchmark.kt:392) at androidx.benchmark.macro.junit4.MacrobenchmarkRule.measureRepeated(MacrobenchmarkRule.kt:108) at com.example.baselineprofile.StartupBenchmarks.benchmark(StartupBenchmarks.kt:52) at com.example.baselineprofile.StartupBenchmarks.startupCompilationBaselineProfiles(StartupBenchmarks.kt:48)