QmVkJRne

102-18 15:17:12.243  3842  4149 E ActivityThread: Failed to find provider info for instantapp-dev-manager
202-18 15:17:12.255  3310  3519 I BroadcastQueue: Delay finish: com.droidlogic.mediacenter/.DMRBroadcastReceiver
302-18 15:17:12.264  4478  4478 D WeakRefService: TypeName Ethernet connected
402-18 15:17:12.471  3310  3414 W HdmiCecController: Failed to send <Give Device Power Status> src: 4, dst: 0 with errorCode=2
502-18 15:17:14.279  3083  3145 W Netd    : No subsystem found in netlink event
602-18 15:17:20.389  3083  3145 W Netd    : No subsystem found in netlink event
702-18 15:17:23.556  3310  3770 I chatty  : uid=1000(system) IpClient.eth0 expire 8 lines
802-18 15:17:24.506  3310  3414 I chatty  : uid=1000(system) Hdmi Control Io identical 6 lines
902-18 15:17:26.566  3310  3414 W HdmiCecController: Failed to send <Give Device Power Status> src: 4, dst: 0 with errorCode=2
1002-18 15:17:27.267  3310  3325 I ActivityManager: Waited long enough for: ServiceRecord{b89c81b u0 com.droidlogic.mediacenter/.WeakRefService}
1102-18 15:17:27.268  3310  3325 I BroadcastQueue: Resuming delayed broadcast
1202-18 15:17:27.316  4330  4330 I android_os_HwBinder: HwBinder: Starting thread pool for default::android.hidl.manager@1.0::IServiceManager
1302-18 15:17:27.320  4330  4508 I SysControlManager: system control service started vendor.amlogic.hardware.systemcontrol@1.0::ISystemControl default
1402-18 15:17:27.329  4330  4330 I android_os_HwBinder: HwBinder: Starting thread pool for default::vendor.amlogic.hardware.systemcontrol@1.1::ISystemControl
1502-18 15:17:27.346  4330  4330 D OTA     : getDevice:Local DiskgetType:type_nand
1602-18 15:17:27.356  3820  3820 I AlarmClock: AlarmInitReceiver android.intent.action.BOOT_COMPLETED
1702-18 15:17:27.376  3820  3841 I AlarmClock: Fixing alarm instances
1802-18 15:17:27.388  3820  3841 V AlarmClock: AlarmInitReceiver finished
1902-18 15:17:27.390  3444  3444 I SystemBroadcastReceiver: Boot has been completed
2002-18 15:17:27.390  3444  3444 I SystemBroadcastReceiver: toggleAppIcon() : FLAG_SYSTEM = true
2102-18 15:17:27.398  3444  3444 I SystemBroadcastReceiver: Killing my process: pid=3444
2202-18 15:17:27.398  3444  3444 I Process : Sending signal. PID: 3444 SIG: 9
2302-18 15:17:27.451  3310  3524 I ActivityManager: Process com.android.inputmethod.latin (pid 3444) has died: fore RCVR
2402-18 15:17:27.452  3310  3328 I chatty  : uid=1000(system) ActivityManager expire 11 lines
2502-18 15:17:27.453  3084  3084 I Zygote  : Process 3444 exited due to signal (9)
2602-18 15:17:27.471  3310  3327 I ActivityManager: Start proc 4511:com.android.managedprovisioning/u0a27 for broadcast com.android.managedprovisioning/.preprovisioning.BootReminder
2702-18 15:17:27.472  4511  4511 I Zygote  : seccomp disabled by setenforce 0
2802-18 15:17:27.476  4511  4511 W gedprovisionin: Using default instruction set features for ARM CPU variant (cortex-a9) using conservative defaults
2902-18 15:17:27.528  4511  4511 I gedprovisionin: The ClassLoaderContext is a special shared library.
3002-18 15:17:27.574  3310  3327 I ActivityManager: Start proc 4526:com.android.onetimeinitializer/u0a13 for broadcast com.android.onetimeinitializer/.OneTimeInitializerReceiver
3102-18 15:17:27.575  4526  4526 I Zygote  : seccomp disabled by setenforce 0
3202-18 15:17:27.580  4526  4526 W timeinitialize: Using default instruction set features for ARM CPU variant (cortex-a9) using conservative defaults
3302-18 15:17:27.635  4526  4526 I timeinitialize: The ClassLoaderContext is a special shared library.
3402-18 15:17:27.648  4526  4526 V OneTimeInitializerReceiver: OneTimeInitializerReceiver.onReceive
3502-18 15:17:27.674  3310  3327 I ActivityManager: Start proc 4544:com.android.packageinstaller/u0a11 for broadcast com.android.packageinstaller/.TemporaryFileManager
3602-18 15:17:27.675  4544  4544 I Zygote  : seccomp disabled by setenforce 0
3702-18 15:17:27.679  4544  4544 W ackageinstalle: Using default instruction set features for ARM CPU variant (cortex-a9) using conservative defaults
3802-18 15:17:27.723  4544  4544 I ackageinstalle: The ClassLoaderContext is a special shared library.
3902-18 15:17:27.741  3496  3514 D HdmiCecExtend: OneTouchPlayCallback, onComplete: 1
4002-18 15:17:27.789  3310  3327 I ActivityManager: Start proc 4559:com.android.providers.calendar/u0a17 for broadcast com.android.providers.calendar/.CalendarReceiver
4102-18 15:17:27.791  4559  4559 I Zygote  : seccomp disabled by setenforce 0
4202-18 15:17:27.795  4559  4559 W viders.calenda: Using default instruction set features for ARM CPU variant (cortex-a9) using conservative defaults
4302-18 15:17:27.842  4559  4559 I viders.calenda: The ClassLoaderContext is a special shared library.
4402-18 15:17:27.885  4559  4559 I CalendarProvider2: Created com.android.providers.calendar.CalendarAlarmManager@8ba56df(com.android.providers.calendar.CalendarProvider2@d491f2c)
4502-18 15:17:27.915  3310  3524 I BroadcastQueue: Delay finish: com.android.providers.media/.MediaScannerReceiver
4602-18 15:17:28.052  4307  4578 D vstest  : media
4702-18 15:17:28.175  3083  3145 W Netd    : No subsystem found in netlink event
4802-18 15:17:28.336  4307  4578 W MediaScanner: Error opening directory '/oem/media/', skipping: No such file or directory.
4902-18 15:17:28.357  4307  4578 V MediaProvider: pruneThumbnails
5002-18 15:17:28.374  4307  4578 V MediaProvider: /pruneDeadThumbnailFiles...
5102-18 15:17:28.376  3310  4510 I BroadcastQueue: Resuming delayed broadcast
5202-18 15:17:28.405  3310  3327 I ActivityManager: Start proc 4579:com.android.settings/1000 for broadcast com.android.settings/.fuelgauge.batterytip.AnomalyConfigReceiver
5302-18 15:17:28.406  4579  4579 I Zygote  : seccomp disabled by setenforce 0
5402-18 15:17:28.411  4579  4579 I chatty  : uid=1000(system) com.android.settings identical 4 lines
5502-18 15:17:28.516  4025  4025 I Thread-3: type=1400 audit(0.0:122): avc: denied { open } for path="/proc/stat" dev="proc" ino=4026531973 scontext=u:r:untrusted_app_27:s0:c512,c768 tcontext=u:object_r:proc_stat:s0 tclass=file permissive=1
5602-18 15:17:28.516  4025  4025 I Thread-3: type=1400 audit(0.0:123): avc: denied { getattr } for path="/proc/stat" dev="proc" ino=4026531973 scontext=u:r:untrusted_app_27:s0:c512,c768 tcontext=u:object_r:proc_stat:s0 tclass=file permissive=1
5702-18 15:17:28.641  3553  3553 W ContextImpl: Calling a method in the system process without a qualified user: android.app.ContextImpl.startService:1531 android.content.ContextWrapper.startService:664 android.content.ContextWrapper.startService:664 com.android.tv.settings.users.UserSwitchListenerService$BootReceiver.onReceive:55 android.app.ActivityThread.handleReceiver:3380
5802-18 15:17:28.647  3310  4510 I BroadcastQueue: Delay finish: com.android.tv.settings/.users.UserSwitchListenerService$BootReceiver
5902-18 15:17:32.550  4511  4525 I gedprovisionin: Waiting for a blocking GC ProfileSaver
6002-18 15:17:32.565  4511  4525 I gedprovisionin: WaitForGcToComplete blocked ProfileSaver on HeapTrim for 15.179ms
6102-18 15:17:32.679  4526  4541 I timeinitialize: Waiting for a blocking GC ProfileSaver
6202-18 15:17:32.688  4526  4541 I timeinitialize: WaitForGcToComplete blocked ProfileSaver on HeapTrim for 8.513ms
6302-18 15:17:32.766  4544  4558 I ackageinstalle: Waiting for a blocking GC ProfileSaver
6402-18 15:17:32.894  4559  4574 I viders.calenda: Waiting for a blocking GC ProfileSaver
6502-18 15:17:37.919  3083  3145 W Netd    : No subsystem found in netlink event
6602-18 15:17:40.064  4370  4370 I chatty  : uid=1000(system) x.cpe:WorkService expire 3 lines
6702-18 15:17:41.954  3197  3245 E storaged: getDiskStats failed with result NOT_SUPPORTED and size 0
6802-18 15:17:43.648  3310  3325 I ActivityManager: Waited long enough for: ServiceRecord{9f221ef u0 com.android.tv.settings/.users.UserSwitchListenerService}
6902-18 15:17:43.648  3310  3325 I BroadcastQueue: Resuming delayed broadcast
7002-18 15:17:43.676  3310  3327 I ActivityManager: Start proc 4600:com.android.vending/u0a20 for broadcast com.android.vending/com.google.android.finsky.boothandler.BootCompletedReceiver
7102-18 15:17:43.680  4600  4600 I Zygote  : seccomp disabled by setenforce 0
7202-18 15:17:43.688  4600  4600 I chatty  : uid=10020(com.android.vending) identical 59 lines
7302-18 15:17:44.278  4600  4622 I chatty  : uid=10020(com.android.vending) expire 3 lines
7402-18 15:17:44.299  4600  4624 I chatty  : uid=10020(com.android.vending) bgExecutor #0 expire 2 lines
7502-18 15:17:44.440  4600  4618 I chatty  : uid=10020(com.android.vending) LightweightExec expire 1 line
7602-18 15:17:44.495  4600  4620 I chatty  : uid=10020(com.android.vending) LightweightExec expire 6 lines
7702-18 15:17:44.512  4600  4634 I chatty  : uid=10020(com.android.vending) bgExecutor #2 expire 1 line
7802-18 15:17:44.531  3310  3322 D ConnectivityService: requestNetwork for uid/pid:10020/4600 NetworkRequest [ TRACK_DEFAULT id=8, [ Capabilities: INTERNET&NOT_RESTRICTED&TRUSTED Unwanted:  Uid: 10020] ]
7902-18 15:17:44.532  3310  3419 I chatty  : uid=1000(system) EthernetService expire 3 lines
8002-18 15:17:44.532  3310  3394 I chatty  : uid=1000(system) WifiStateMachin expire 6 lines
8102-18 15:17:44.561  4600  4619 I chatty  : uid=10020(com.android.vending) LightweightExec expire 6 lines
8202-18 15:17:44.625  4600  4630 I chatty  : uid=10020(com.android.vending) bgExecutor #1 expire 3 lines
8302-18 15:17:44.782  3310  3327 I ActivityManager: Start proc 4663:com.droidlogic.BluetoothRemote/1000 for broadcast com.droidlogic.BluetoothRemote/.BootCompletedReceiver
8402-18 15:17:44.785  4663  4663 I Zygote  : seccomp disabled by setenforce 0
8502-18 15:17:44.792  4663  4663 W BluetoothRemot: Using default instruction set features for ARM CPU variant (cortex-a9) using conservative defaults
8602-18 15:17:44.858  4663  4663 D libnativeloader: classloader namespace configured for unbundled vendor apk. library_path=/vendor/app/BluetoothRemote/lib/arm:/vendor/app/BluetoothRemote/BluetoothRemote.apk!/lib/armeabi-v7a:/vendor/lib
8702-18 15:17:44.865  3083  3145 W Netd    : No subsystem found in netlink event
8802-18 15:17:44.882  4663  4663 D BootCompletedReceiver: BootCompletedReceiver in read version
8902-18 15:17:44.883  4663  4663 W ContextImpl: Calling a method in the system process without a qualified user: android.app.ContextImpl.startService:1531 android.content.ContextWrapper.startService:664 android.content.ContextWrapper.startService:664 com.droidlogic.BluetoothRemote.BootCompletedReceiver.onReceive:13 android.app.ActivityThread.handleReceiver:3380
9002-18 15:17:44.887  4600  4640 I chatty  : uid=10020(com.android.vending) bgExecutor #3 expire 6 lines
9102-18 15:17:44.888  4663  4663 I ReadVersionService: ReadVersionService-onCreate
9202-18 15:17:44.889  4663  4663 I ReadVersionService: ReadVersionService-onStartCommand
9302-18 15:17:44.890  3310  4510 I BroadcastQueue: Delay finish: com.droidlogic.BluetoothRemote/.BootCompletedReceiver
9402-18 15:17:44.892  4663  4663 D ReadVersionService: bluetooth is turned on
9502-18 15:17:44.895  4663  4663 D ReadVersionService: NOT FOUND REMOTE
9602-18 15:17:44.895  4663  4663 I ReadVersionService: ReadVersionService-onStart
9702-18 15:17:44.946  4600  4685 I chatty  : uid=10020(com.android.vending) expire 2 lines
9802-18 15:17:45.043  4600  4689 I chatty  : uid=10020(com.android.vending) InstallQueueUsi expire 1 line
9902-18 15:17:45.540  3105  3105 I chatty  : uid=1000(system) memtrack@1.0-se expire 2 lines
10002-18 15:17:45.540  3105  3105 I memtrack@1.0-se: type=1400 audit(0.0:129): avc: denied { getattr } for path="/sys/kernel/debug/mali0/ctx/3454_3/mem_profile" dev="debugfs" ino=18899 scontext=u:r:hal_memtrack_default:s0 tcontext=u:object_r:debugfs:s0 tclass=file permissive=1
10102-18 15:17:48.815  4600  4615 I chatty  : uid=10020(com.android.vending) Profile Saver expire 1 line
10202-18 15:17:49.894  4663  4682 I BluetoothRemot: Waiting for a blocking GC ProfileSaver
10302-18 15:17:52.329  3083  3145 W Netd    : No subsystem found in netlink event
10402-18 15:17:54.539  4600  4669 I chatty  : uid=10020(com.android.vending) expire 1 line
10502-18 15:17:54.686  4600  4630 I Finsky  : [153] abfm.a(10): Do not start WearSupportService due to Wear service optimization
10602-18 15:17:54.686  4600  4624 I Finsky  : [147] ebd.run(8): Component class com.google.android.finsky.wear.WearSupportService disabled via PackageManager.
10702-18 15:17:54.687  4600  4624 I Finsky  : [147] ebd.run(8): Component class com.google.android.finsky.wear.WearChangeListenerService disabled via PackageManager.
10802-18 15:17:54.886  4600  4669 I Finsky  : [180] rgo.c(8): Already at the latest configurations for experiment package com.google.android.finsky.regular.
10902-18 15:17:54.887  4600  4684 W android.vendin: Long monitor contention with owner AsyncTask #1 (4669) at java.lang.Class dalvik.system.BaseDexClassLoader.findClass(java.lang.String)(BaseDexClassLoader.java:131) waiters=0 in int rgo.c() for 341ms
11002-18 15:17:54.902  4600  4684 I Finsky  : [183] rgo.c(1): Triggered update for experiment package com.google.android.finsky.stable.
11102-18 15:17:54.909  4600  4640 I Finsky  : [159] qcq.a(2): Showing notification: [ID=play protect default on, Tag=null, Title=Google Play Protect is turned on, Message=This feature protects your device from harmful apps, returnCode=-1, uiElementType=926]
11202-18 15:17:54.909  4600  4640 I Finsky  : [159] zws.f(6): Done restoring notifications
11302-18 15:17:54.925  3196  3270 V AmlogicNuPlayerFactoryInit:  create AmNuPlayer
11402-18 15:17:54.925  3196  3270 I MessageMonitor: NU-AmNuPlayer MessageMonitor mDebugLevel 255, poll message us 30000000
11502-18 15:17:54.925  3196  3270 D NU-AmNuPlayerDriver: AmNuPlayerDriver(0xa56ae280) created, clientPid(3454)
11602-18 15:17:54.925  3196  3270 I NU-AmNuPlayerDriver:
11702-18 15:17:54.925  3196  3270 I NU-AmNuPlayerDriver: --------------------------------
11802-18 15:17:54.925  3196  3270 I NU-AmNuPlayerDriver: ARCH = arm
11902-18 15:17:54.925  3196  3270 I NU-AmNuPlayerDriver: branch name:   * p-amlogic
12002-18 15:17:54.925  3196  3270 I NU-AmNuPlayerDriver: git version:   808acfd38883f62eb1dad5c5b01ae7c4311735d0
12102-18 15:17:54.925  3196  3270 I NU-AmNuPlayerDriver: last changed:  Date: Thu Jan 31 14:47:37 2019 +0800
12202-18 15:17:54.925  3196  3270 I NU-AmNuPlayerDriver: build-time:     Tue Apr 9 19:19:47 CST 2019
12302-18 15:17:54.925  3196  3270 I NU-AmNuPlayerDriver: build-name:     zhao.yi
12402-18 15:17:54.925  3196  3270 I NU-AmNuPlayerDriver: uncommitted-file-num:0
12502-18 15:17:54.925  3196  3270 I NU-AmNuPlayerDriver: --------------------------------
12602-18 15:17:54.935  3196  3270 I NU-AmNuPlayer: setDataSourceAsync fd 7/0/16905
12702-18 15:17:54.935  3196  3270 I NU-AmNuPlayer: file size too small, check M3U8
12802-18 15:17:54.936  3196  3270 I MessageMonitor: NU-GenericSource MessageMonitor mDebugLevel 255, poll message us 30000000
12902-18 15:17:54.936  3196  3270 I NU-GenericSource: [resetDataSource 186]
13002-18 15:17:54.936  3196  3270 I NU-GenericSource: [resetDataSource 186]
13102-18 15:17:54.938  3196  4705 I NU-GenericSource: patch:
13202-18 15:17:54.945  3196  4705 D NU-GenericSource: FileSource remote
13302-18 15:17:54.947  3196  4705 I NU-GenericSource: >>>[initFromDataSource 258]
13402-18 15:17:54.950  3188  3188 I AmFFmpegExtractor: checkInList: isw:0, m:application/ogg, t:1, i:86021 t:0
13502-18 15:17:54.951  3188  3188 I AmFFmpegExtractor: checkInList: isw:1, m:application/ogg, t:1, i:86021 t:0
13602-18 15:17:54.951  3188  3188 E AmFFmpegExtractor: codec_ctx->properties：0
13702-18 15:17:54.951  3188  3188 I AmFFmpegExtractor: checkSupportInList ret 1
13802-18 15:17:54.957  3188  3188 I AmFFmpegExtractor: checkInList: isw:0, m:application/ogg, t:1, i:86021 t:0
13902-18 15:17:54.957  3188  3188 I AmFFmpegExtractor: checkInList: isw:1, m:application/ogg, t:1, i:86021 t:0
14002-18 15:17:54.957  3188  3188 E AmFFmpegExtractor: codec_ctx->properties：0
14102-18 15:17:54.957  3188  3188 I AmFFmpegExtractor: checkSupportInList ret 0
14202-18 15:17:54.957  3188  3188 E AmFFmpegExtractor: vendor do not support
14302-18 15:17:54.962  3196  4705 I NU-GenericSource: >>>MediaExtractor::Create [initFromDataSource 294]
14402-18 15:17:54.966  3196  4705 I AmAnotherPacketSource: (audio/vorbis)IsUdp:0 mLimitiSize:2097152 mTotalCount:1000
14502-18 15:17:54.968  3196  4705 I NU-GenericSource: notifyPrepared 785
14602-18 15:17:54.968  3196  4703 I NU-AmNuPlayer: notifyPrepareCompleted
14702-18 15:17:54.968  3196  4703 D NU-AmNuPlayerDriver: notifyListener_l(0xa56ae280), (1, 0, 0, -1), loop setting(0, 0)
14802-18 15:17:54.976  3196  4703 I AmAVUtils: set profile 1668446539 level:0
14902-18 15:17:54.981  3310  4510 I chatty  : uid=1000(system) Binder:3310_A expire 1 line
15002-18 15:17:55.021  4600  4600 I Finsky  : [2] ulg.run(5): Detected restoreservicev2://recovery not needed, will not run
15102-18 15:17:55.033  4600  4684 I Finsky  : [183] rgo.c(8): Already at the latest configurations for experiment package com.google.android.finsky.stable.
15202-18 15:17:55.199  4600  4709 I Finsky  : [200] akhc.a(2): registerListener
15302-18 15:17:55.200  4600  4709 I Finsky  : [200] joc.a(2): getDownloads(-)
15402-18 15:17:55.203  4600  4709 I Finsky  : [200] ekx.run(7): Initialized AssetModuleDownloader.
15502-18 15:17:55.204  4600  4712 I Finsky  : [203] akhk.a(7): Initiate binding to the service.
15602-18 15:17:55.222  3310  3327 I ActivityManager: Start proc 4713:com.android.vending:download_service/u0a20 for service com.android.vending/com.google.android.finsky.downloadservice.DownloadService
15702-18 15:17:55.223  4713  4713 I Zygote  : seccomp disabled by setenforce 0
15802-18 15:17:55.227  4713  4713 W ownload_servic: Using default instruction set features for ARM CPU variant (cortex-a9) using conservative defaults
15902-18 15:17:55.263  4713  4713 I ownload_servic: The ClassLoaderContext is a special shared library.
16002-18 15:17:55.289  4713  4713 I Finsky:download_service: [2] ebz.aG(7): Process created at version: 18.6.33-all [0] [PR] 292279539
16102-18 15:17:55.330  3310  3322 D ConnectivityService: requestNetwork for uid/pid:10020/4713 NetworkRequest [ TRACK_DEFAULT id=10, [ Capabilities: INTERNET&NOT_RESTRICTED&TRUSTED Unwanted:  Uid: 10020] ]
16202-18 15:17:55.339  4600  4600 I Finsky  : [2] akhq.onServiceConnected(2): ServiceConnectionImpl.onServiceConnected(ComponentInfo{com.android.vending/com.google.android.finsky.downloadservice.DownloadService})
16302-18 15:17:55.340  4600  4712 I Finsky  : [203] akho.a(3): linkToDeath
16402-18 15:17:55.340  4713  4730 I Finsky:download_service: [148] jhm.onAvailable(3): Network is available [NetworkState{connected=true, wifi=false, metered=false, roaming=false}].
16502-18 15:17:55.343  4713  4726 I Finsky:download_service: [144] jiq.a(12): Starting getDownloads().
16602-18 15:17:55.396  4600  4712 I Finsky  : [203] akhl.a(2): Unbind from service.
16702-18 15:17:55.401  4600  4614 I Finsky  : [137] jnv.d(6): onGetDownloads()
16802-18 15:17:55.484  3196  3270 D NU-AmNuPlayerDriver: start(0xa56ae280), state is 4, eos is 0
16902-18 15:17:55.484  3196  4703 I NU-GenericSource: start
17002-18 15:17:55.484  3454  3454 V MediaRouter: onRestoreRoute() : route=RouteInfo{ name=HDMI, description=null, status=null, category=RouteCategory{ name=System types=ROUTE_TYPE_LIVE_AUDIO ROUTE_TYPE_LIVE_VIDEO  groupable=false }, supportedTypes=ROUTE_TYPE_LIVE_AUDIO ROUTE_TYPE_LIVE_VIDEO , presentationDisplay=null }
17102-18 15:17:55.484  3454  3454 V MediaRouter: Selecting route: RouteInfo{ name=HDMI, description=null, status=null, category=RouteCategory{ name=System types=ROUTE_TYPE_LIVE_AUDIO ROUTE_TYPE_LIVE_VIDEO  groupable=false }, supportedTypes=ROUTE_TYPE_LIVE_AUDIO ROUTE_TYPE_LIVE_VIDEO , presentationDisplay=null }
17202-18 15:17:55.484  3196  4703 I AmAVUtils: set profile 1668446539 level:0
17302-18 15:17:55.484  3196  4703 I NU-AmNuPlayer: AmNuPlayer::Source::getFormat frame-rate = failed
17402-18 15:17:55.484  3427  3885 E bt_btif : register_notification_rsp: Avrcp device is not connected, handle: 0x0
17502-18 15:17:55.484  3427  3885 E bt_btif : register_notification_rsp: Avrcp device is not connected, handle: 0x0
17602-18 15:17:55.486  3196  4703 I MessageMonitor: NU-NuPlayerRenderer MessageMonitor mDebugLevel 255, poll message us 30000000
17702-18 15:17:55.487  3090  4426 I audio_hw_primary: ms12_enable :0
17802-18 15:17:55.487  3130  3130 I hash_map_utils: key: 'dolby_ms12_enable' value: ''
17902-18 15:17:55.487  3196  4703 I NU-NuPlayerRenderer: dolby_ms12_enable:0
18002-18 15:17:55.488  3196  4703 I AmAVUtils: set profile 1668446539 level:0
18102-18 15:17:55.489  3196  4703 I AmAVUtils: set profile 1668446539 level:0
18202-18 15:17:55.489  3196  4703 I NU-AmNuPlayer: AmNuPlayer::Source::getFormat frame-rate = failed
18302-18 15:17:55.490  3196  4703 I MessageMonitor: NU-NuPlayerDecoder-audN MessageMonitor mDebugLevel 255, poll message us 30000000
18402-18 15:17:55.493  3196  4737 I OMXClient: IOmx service obtained
18502-18 15:17:55.493  3199  3258 I OMXMaster: makeComponentInstance(OMX.google.vorbis.decoder) in omx@1.0-service process
18602-18 15:17:55.497  3196  4736 I NU-NuPlayerDecoder: sampleRate:48000
18702-18 15:17:55.498  3196  4737 I AmAVUtils: AmAVUtils::getComponentRole isEncoder :0 mime:audio/vorbis
18802-18 15:17:55.498  3196  4737 V AVUtils : AVUtils::getComponentRole
18902-18 15:17:55.498  3196  4737 I AmAVUtils: AmAVUtils::isAudioExtendFormat input = audio/vorbis
19002-18 15:17:55.498  3199  3258 E OMXNodeInstance: setConfig(0xabf1cba0:google.vorbis.decoder, ConfigPriority(0x6f800002)) ERROR: Undefined(0x80001001)
19102-18 15:17:55.498  3196  4737 I ACodec  : codec does not support config priority (err -2147483648)
19202-18 15:17:55.498  3199  3258 E OMXNodeInstance: getConfig(0xabf1cba0:google.vorbis.decoder, ConfigAndroidVendorExtension(0x6f100004)) ERROR: Undefined(0x80001001)
19302-18 15:17:55.499  3196  4737 I MediaCodec: MediaCodec will operate in async mode
19402-18 15:17:55.502  3089  3089 W AshmemAllocator: ashmem_create_region(32768) returning hidl_memory(0xad496170, 32768)
19502-18 15:17:55.524  3089  3089 I chatty  : uid=1000(system) allocator@1.0-s identical 6 lines
19602-18 15:17:55.524  3089  3089 W AshmemAllocator: ashmem_create_region(32768) returning hidl_memory(0xad496170, 32768)
19702-18 15:17:55.532  3196  4735 V NU-NuPlayerRenderer: openAudioSink: offloadOnly(0) offloadingAudio(0)
19802-18 15:17:55.532  3196  4735 V NU-NuPlayerRenderer: openAudioSink: open AudioSink in NON-offload mode
19902-18 15:17:55.532  3130  3130 I hash_map_utils: key: 'hdmi_format' value: ''
20002-18 15:17:55.533  3196  4735 I NU-NuPlayerRenderer: digital_format:5
20102-18 15:17:55.534  3130  3130 D AudioFlinger: Client defaulted notificationFrames to 12288 for frameCount 24576
20202-18 15:17:55.536  3090  4426 I audio_hw_primary: ++adev_create_audio_patch
20302-18 15:17:55.536  3090  4426 I audio_hw_primary: +adev_create_audio_patch num_sources [1] , num_sinks [1],src_config->type=2,sink_config->type=1,aml_dev->patch_src=9
20402-18 15:17:55.536  3090  4426 I audio_hw_primary: --register_audio_patch: after registering new patch, patch sets will be:
20502-18 15:17:55.536  3090  4426 I audio_hw_primary: adev_create_audio_patch(), patch new handle for AF: 0xa0fc428c
20602-18 15:17:55.536  3090  4426 I audio_hw_primary: aml_audio_output_routing: switch from OUTPORT_SPEAKER to OUTPORT_HDMI
20702-18 15:17:55.536  3090  4426 E audio_route: unable to find path 'speaker_off'
20802-18 15:17:55.536  3090  4426 E audio_hw_primary: aml_audio_output_routing: outport = 2 unsupport
20902-18 15:17:55.536  3090  4426 I audio_hw_primary: adev_create_audio_patch: mix->device patch: outport(2)
21002-18 15:17:55.537  3196  4735 I NU-NuPlayerRenderer: PTS: AV sync info:AV SYNCED
21102-18 15:17:55.537  3196  4735 I NU-NuPlayerRenderer: PTS:   SystemTimeStamp:-1
21202-18 15:17:55.537  3196  4735 I NU-NuPlayerRenderer: PTS:   Last AudioTimeStamp:-1
21302-18 15:17:55.537  3196  4735 I NU-NuPlayerRenderer: PTS:   Last VideoTimeStamp:0
21402-18 15:17:55.537  3196  4735 I NU-NuPlayerRenderer: PTS:   AV diff:-1 ,Ajump:0,Vjump:0
21502-18 15:17:55.537  3196  4735 I NU-NuPlayerRenderer: PTS:   mAjumpedNum:0, mAudioJumped till now=146879923
21602-18 15:17:55.537  3196  4735 I NU-NuPlayerRenderer: PTS:   mVjumpedNum:0, mVideoJumped till now=146879923
21702-18 15:17:55.537  3196  4735 I NU-NuPlayerRenderer: PTS:   mTotalAudioJumpedTimeUs:0
21802-18 15:17:55.537  3196  4735 I NU-NuPlayerRenderer: PTS:   mLastVideoUs:-1
21902-18 15:17:55.537  3196  4735 I NU-NuPlayerRenderer: PTS:   mLastAudioUs:-1
22002-18 15:17:55.538  3090  4221 I audio-subMixingFactory: ++usecase_change_validate_l_sm: dev usecase masks = 0, out usecase_masks = 0, out usecase STREAM_PCM_NORMAL
22102-18 15:17:55.538  3090  4221 I audio-subMixingFactory: ++++usecase_change_validate_l_sm(),continuous_audio_mode 0 dev usecase masks = 0, out usecase_masks = 0, out usecase STREAM_PCM_NORMAL
22202-18 15:17:55.538  3090  4221 I audio-subMixingFactory: usecase_change_validate_l_sm(), mixer_aux_buffer_write_sm !
22302-18 15:17:55.538  3090  4221 I audio-subMixingFactory: ----usecase_change_validate_l_sm(), continuous_audio_mode 0 dev usecase masks = 0x1, out usecase_masks = 0x1, out usecase STREAM_PCM_NORMAL
22402-18 15:17:55.538  3090  4221 I audio-subMixingFactory: rawtopcm_flag disable !!!
22502-18 15:17:55.538  3090  4221 I aml_audio_port: get_input_port_index(), samplerate 48000
22602-18 15:17:55.538  3090  4221 D aml_audio_port: new_input_port(), index:0, rbuf size:8192, direct_on:0
22702-18 15:17:55.538  3090  4221 D aml_audio_port: new_input_port(), fmt 0x1, rate 48000
22802-18 15:17:55.538  3090  4221 I amlaudioMixer: ++init_mixer_input_port port index 0, size 512 frames
22902-18 15:17:55.538  3090  4221 I amlaudioMixer: init_mixer_input_port(), port->initial_frames: 3025920
23002-18 15:17:55.538  3090  4221 I aml_audio_port: get_input_port_index(), samplerate 48000
23102-18 15:17:55.538  3090  4221 I audio-subMixingFactory: mixer_aux_buffer_write_sm(), primary 0xa1ed8000 port index = 0
23202-18 15:17:55.538  3090  4221 I audio-subMixingFactory: padding_bytes 16384
23302-18 15:17:55.538  3090  4221 I amlaudioMixer: port index 0 is active now
23402-18 15:17:55.538  3090  4221 I audio-subMixingFactory: padding_bytes 14336
23502-18 15:17:55.538  3090  4221 I audio-subMixingFactory: padding_bytes 12288
23602-18 15:17:55.538  3090  4221 I audio-subMixingFactory: padding_bytes 10240
23702-18 15:17:55.538  3090  4221 I audio-subMixingFactory: padding_bytes 8192
23802-18 15:17:55.538  3090  4221 I audio-subMixingFactory: padding_bytes 6144
23902-18 15:17:55.538  3196  4703 I NU-AmNuPlayer: media rendering started
24002-18 15:17:55.538  3196  4703 D NU-AmNuPlayerDriver: notifyListener_l(0xa56ae280), (6, 0, 0, -1), loop setting(0, 0)
24102-18 15:17:55.538  3196  3270 I NU-AmNuPlayer: [getCurrentPosition]
24202-18 15:17:55.538  3196  3270 I NU-AmNuPlayer: [getStrCurrentAudioCodec]
24302-18 15:17:55.539  3196  3270 I AmAVUtils: set profile 1668446539 level:0
24402-18 15:17:55.539  3196  3270 I NU-AmNuPlayer: AmNuPlayer::Source::getFormat frame-rate = failed
24502-18 15:17:55.539  3196  3270 I NU-AmNuPlayer: getcurrent:NOT_DTSHD
24602-18 15:17:55.539  3196  3270 I NU-AmNuPlayerDriver: [getCurrentPosition] position : 0 msec
24702-18 15:17:55.541  3090  4221 I audio-subMixingFactory: padding_bytes 4096
24802-18 15:17:55.541  3090  4221 I audio-subMixingFactory: padding_bytes 2048
24902-18 15:17:55.552  3090  4221 W amlaudioMixer: mixer_get_presentation_position(), not valid now
25002-18 15:17:55.552  3090  4221 W audio-subMixingFactory: out_get_presentation_position_port(), pts not valid yet
25102-18 15:17:55.552  3196  4735 D AudioTrack: correcting timestamp time for pause, currentTimeNanos: 92794987543 < limitNs: 146835958527 < mStartNs: 146877958527
25202-18 15:17:55.568  3196  4735 I chatty  : uid=1013(media) NuPlayerRendere identical 57 lines
25302-18 15:17:55.568  3196  4735 D AudioTrack: correcting timestamp time for pause, currentTimeNanos: 92794987543 < limitNs: 146835958527 < mStartNs: 146877958527
25402-18 15:17:55.598  3130  3293 D AudioFlinger: mixer(0xa3c03fc0) throttle end: throttle time(10)
25502-18 15:17:55.728  4025  4025 I Thread-3: type=1400 audit(0.0:130): avc: denied { open } for path="/proc/stat" dev="proc" ino=4026531973 scontext=u:r:untrusted_app_27:s0:c512,c768 tcontext=u:object_r:proc_stat:s0 tclass=file permissive=1
25602-18 15:17:55.744  4025  4025 I Thread-3: type=1400 audit(0.0:131): avc: denied { getattr } for path="/proc/stat" dev="proc" ino=4026531973 scontext=u:r:untrusted_app_27:s0:c512,c768 tcontext=u:object_r:proc_stat:s0 tclass=file permissive=1
25702-18 15:17:55.824  3196  4703 D NU-AmNuPlayerDriver: notifyListener_l(0xa56ae280), (211, 0, 0, 20), loop setting(0, 0)
25802-18 15:17:56.089  3196  4703 D NU-AmNuPlayerDriver: notifyListener_l(0xa56ae280), (211, 0, 0, 20), loop setting(0, 0)
25902-18 15:17:56.652  3196  4736 E NU-GenericSource: hasBufferAvailable return -1011
26002-18 15:17:56.652  3196  4736 E NU-GenericSource: hasBufferAvailable return -1011
26102-18 15:17:56.903  3196  4736 I NU-NuPlayerDecoder: [audio] saw output EOS
26202-18 15:17:56.903  3196  4735 I NU-NuPlayerRenderer: audio have queued eos, no need to check buffer discontinue
26302-18 15:17:56.903  3196  4735 D AudioTrack: stop() called with 82980 frames delivered
26402-18 15:17:56.923  3310  3322 I BroadcastQueue: Resuming delayed broadcast
26502-18 15:17:56.924  4663  4663 D ReadVersionService: Handler().postDelayed
26602-18 15:17:56.924  4663  4663 I ReadVersionService: ReadVersionService-onDestroy
26702-18 15:17:56.928  3310  3322 I ActivityManager: Killing 3969:android.process.acore/u0a5 (adj 906): empty #17
26802-18 15:17:56.953  3310  3327 I ActivityManager: Start proc 4742:com.google.android.configupdater/u0a4 for broadcast com.google.android.configupdater/.MainReceiver
26902-18 15:17:56.956  4742  4742 I Zygote  : seccomp disabled by setenforce 0
27002-18 15:17:56.962  4742  4742 W d.configupdate: Using default instruction set features for ARM CPU variant (cortex-a9) using conservative defaults
27102-18 15:17:56.983  3084  3084 I Zygote  : Process 3969 exited due to signal (9)
27202-18 15:17:57.015  4742  4742 I d.configupdate: The ClassLoaderContext is a special shared library.
27302-18 15:17:57.086  4742  4742 E ConfigUpdater: ignoring update request
27402-18 15:17:57.126  4742  4742 I chatty  : uid=10004(com.google.android.configupdater) identical 8 lines
27502-18 15:17:57.133  4742  4742 E ConfigUpdater: ignoring update request
27602-18 15:17:57.146  3310  3322 I ActivityManager: Killing 4260:com.android.keychain/1000 (adj 906): empty #17
27702-18 15:17:57.185  3842  4781 I chatty  : uid=10007 com.google.android.gms.persistent expire 10 lines
27802-18 15:17:57.196  4785  4785 I Zygote  : seccomp disabled by setenforce 0
27902-18 15:17:57.202  3084  3084 I Zygote  : Process 4260 exited due to signal (9)
28002-18 15:17:57.204  4785  4785 W timeinitialize: Using default instruction set features for ARM CPU variant (cortex-a9) using conservative defaults
28102-18 15:17:57.205  3310  3327 I ActivityManager: Start proc 4785:com.google.android.onetimeinitializer/u0a16 for broadcast com.google.android.onetimeinitializer/.OneTimeInitializerReceiver
28202-18 15:17:57.212  4069  4782 I chatty  : uid=10007(com.google.android.gms) expire 21 lines
28302-18 15:17:57.234  3310  3328 I chatty  : uid=1000(system) ActivityManager expire 14 lines
28402-18 15:17:57.318  3842  3854 I chatty  : uid=10007(com.google.android.gms) HeapTaskDaemon expire 15 lines
28502-18 15:17:57.326  4785  4785 V OneTimeInitializerReceiver: OneTimeInitializerReceiver.onReceive
28602-18 15:17:57.330  4785  4802 W timeinitialize: Accessing hidden method Landroid/app/admin/DevicePolicyManager;->getDeviceOwnerComponentOnCallingUser()Landroid/content/ComponentName; (dark greylist, linking)
28702-18 15:17:57.330  4785  4802 W timeinitialize: Accessing hidden method Landroid/app/admin/DevicePolicyManager;->getDeviceOwnerComponentOnCallingUser()Landroid/content/ComponentName; (dark greylist, linking)
28802-18 15:17:57.334  4785  4802 V OneTimeInitializer: OneTimeInitializer.initialize
28902-18 15:17:57.343  4785  4802 V OneTimeInitializer: Already run, doing nothing.
29002-18 15:17:57.374  3196  4703 D NU-AmNuPlayerDriver: notifyListener_l(0xa56ae280), (2, 0, 0, -1), loop setting(0, 0)
29102-18 15:17:57.376  3196  4735 I NU-NuPlayerRenderer: now paused audio queue has 0 entries, video has 0 entries
29202-18 15:17:57.376  3196  4703 D NU-AmNuPlayerDriver: notifyListener_l(0xa56ae280), (211, 0, 0, 20), loop setting(0, 0)
29302-18 15:17:57.376  4804  4804 I Zygote  : seccomp disabled by setenforce 0
29402-18 15:17:57.377  3310  3519 I chatty  : uid=1000(system) Binder:3310_4 expire 1 line
29502-18 15:17:57.378  3427  3885 I Avrcp   : Skipping update due to invalid playback state
29602-18 15:17:57.378  3427  3885 E bt_btif : register_notification_rsp: Avrcp device is not connected, handle: 0x0
29702-18 15:17:57.378  3427  3885 E bt_btif : register_notification_rsp: Avrcp device is not connected, handle: 0x0
29802-18 15:17:57.384  4804  4804 W id.partnersetu: Using default instruction set features for ARM CPU variant (cortex-a9) using conservative defaults
29902-18 15:17:57.387  3310  3327 I ActivityManager: Start proc 4804:com.google.android.partnersetup/u0a6 for broadcast com.google.android.partnersetup/.GooglePartnerSetup
30002-18 15:17:57.465  4804  4804 I id.partnersetu: The ClassLoaderContext is a special shared library.
30102-18 15:17:57.570  4804  4804 D vstest  : com.google.settings
30202-18 15:17:57.602  4823  4823 I Zygote  : seccomp disabled by setenforce 0
30302-18 15:17:57.602  3310  3327 I ActivityManager: Start proc 4823:com.google.process.gapps/u0a7 for content provider com.google.android.gsf/.settings.GoogleSettingsProvider
30402-18 15:17:57.607  4823  4823 I chatty  : uid=10007(com.google.android.gms) com.google.process.gapps identical 3 lines
30502-18 15:17:57.781  4804  4804 D vstest  : com.google.settings
30602-18 15:17:57.790  4804  4804 I chatty  : uid=10006(com.google.android.partnersetup) identical 1 line
30702-18 15:17:57.805  4804  4804 D vstest  : com.google.settings
30802-18 15:17:57.924  4844  4844 I Zygote  : seccomp disabled by setenforce 0
30902-18 15:17:57.927  3310  3327 I ActivityManager: Start proc 4844:com.google.process.gapps/u0a43 for broadcast com.google.android.syncadapters.contacts/.ContactsSyncAdapterBroadcastReceiver
31002-18 15:17:57.932  4844  4844 W e.process.gapp: Using default instruction set features for ARM CPU variant (cortex-a9) using conservative defaults
31102-18 15:17:57.978  4844  4844 I e.process.gapp: The ClassLoaderContext is a special shared library.
31202-18 15:17:58.036  4844  4844 I GoogleHttpClient: GMS http client unavailable, use old client
31302-18 15:17:58.057  3310  3519 I BroadcastQueue: Delay finish: com.google.android.syncadapters.contacts/.ContactsSyncAdapterBroadcastReceiver
31402-18 15:17:58.072  3310  3523 I BroadcastQueue: Resuming delayed broadcast
31502-18 15:17:58.073  3310  4510 I ActivityManager: Killing 4280:android.ext.services/u0a3 (adj 906): empty #17
31602-18 15:17:58.076  3310  4510 I ActivityManager: Finished processing BOOT_COMPLETED for u0
31702-18 15:17:58.076  3310  4510 W BroadcastQueue: Background execution not allowed: receiving Intent { act=android.intent.action.DROPBOX_ENTRY_ADDED flg=0x10 (has extras) } to com.google.android.gms/.stats.service.DropBoxEntryAddedReceiver
31802-18 15:17:58.077  3310  3325 W BroadcastQueue: Background execution not allowed: receiving Intent { act=android.intent.action.DROPBOX_ENTRY_ADDED flg=0x10 (has extras) } to com.google.android.gms/.chimera.GmsIntentOperationService$PersistentTrustedReceiver
31902-18 15:17:58.077  3310  3325 W BroadcastQueue: Background execution not allowed: receiving Intent { act=android.intent.action.DROPBOX_ENTRY_ADDED flg=0x10 (has extras) } to com.google.android.gms/.stats.service.DropBoxEntryAddedReceiver
32002-18 15:17:58.077  3310  3325 W BroadcastQueue: Background execution not allowed: receiving Intent { act=android.intent.action.DROPBOX_ENTRY_ADDED flg=0x10 (has extras) } to com.google.android.gms/.chimera.GmsIntentOperationService$PersistentTrustedReceiver
32102-18 15:17:58.077  3310  3325 W BroadcastQueue: Background execution not allowed: receiving Intent { act=android.intent.action.DROPBOX_ENTRY_ADDED flg=0x10 (has extras) } to com.google.android.gms/.stats.service.DropBoxEntryAddedReceiver
32202-18 15:17:58.078  3310  3325 W BroadcastQueue: Background execution not allowed: receiving Intent { act=android.intent.action.DROPBOX_ENTRY_ADDED flg=0x10 (has extras) } to com.google.android.gms/.chimera.GmsIntentOperationService$PersistentTrustedReceiver
32302-18 15:17:58.078  3310  3325 W BroadcastQueue: Background execution not allowed: receiving Intent { act=android.intent.action.DROPBOX_ENTRY_ADDED flg=0x10 (has extras) } to com.google.android.gms/.stats.service.DropBoxEntryAddedReceiver
32402-18 15:17:58.078  3310  3325 W BroadcastQueue: Background execution not allowed: receiving Intent { act=android.intent.action.DROPBOX_ENTRY_ADDED flg=0x10 (has extras) } to com.google.android.gms/.chimera.GmsIntentOperationService$PersistentTrustedReceiver
32502-18 15:17:58.078  3310  3325 W BroadcastQueue: Background execution not allowed: receiving Intent { act=android.intent.action.DROPBOX_ENTRY_ADDED flg=0x10 (has extras) } to com.google.android.gms/.stats.service.DropBoxEntryAddedReceiver
32602-18 15:17:58.078  3310  3325 W BroadcastQueue: Background execution not allowed: receiving Intent { act=android.intent.action.DROPBOX_ENTRY_ADDED flg=0x10 (has extras) } to com.google.android.gms/.chimera.GmsIntentOperationService$PersistentTrustedReceiver
32702-18 15:17:58.078  3310  3325 W BroadcastQueue: Background execution not allowed: receiving Intent { act=android.intent.action.DROPBOX_ENTRY_ADDED flg=0x10 (has extras) } to com.google.android.gms/.stats.service.DropBoxEntryAddedReceiver
32802-18 15:17:58.078  3310  3325 W BroadcastQueue: Background execution not allowed: receiving Intent { act=android.intent.action.DROPBOX_ENTRY_ADDED flg=0x10 (has extras) } to com.google.android.gms/.chimera.GmsIntentOperationService$PersistentTrustedReceiver
32902-18 15:17:58.078  3310  3325 W BroadcastQueue: Background execution not allowed: receiving Intent { act=android.bluetooth.adapter.action.STATE_CHANGED flg=0x4000010 (has extras) } to com.google.android.gms/.chimera.GmsIntentOperationService$PersistentTrustedReceiver
33002-18 15:17:58.102  3310  3523 W BroadcastQueue: Background execution not allowed: receiving Intent { act=android.net.wifi.STATE_CHANGE flg=0x4000010 (has extras) } to cm.aptoidetv.pt/.remoteinstall.WifiListener
33102-18 15:17:58.111  3084  3084 I Zygote  : Process 4280 exited due to signal (9)
33202-18 15:17:58.118  3310  3519 W BroadcastQueue: Background execution not allowed: receiving Intent { act=android.intent.action.DROPBOX_ENTRY_ADDED flg=0x10 (has extras) } to com.google.android.gms/.stats.service.DropBoxEntryAddedReceiver
33302-18 15:17:58.119  3310  3325 W BroadcastQueue: Background execution not allowed: receiving Intent { act=android.intent.action.DROPBOX_ENTRY_ADDED flg=0x10 (has extras) } to com.google.android.gms/.chimera.GmsIntentOperationService$PersistentTrustedReceiver
33402-18 15:17:58.120  3310  3325 W BroadcastQueue: Background execution not allowed: receiving Intent { act=android.intent.action.DROPBOX_ENTRY_ADDED flg=0x10 (has extras) } to com.google.android.gms/.stats.service.DropBoxEntryAddedReceiver
33502-18 15:17:58.121  3310  3325 W BroadcastQueue: Background execution not allowed: receiving Intent { act=android.intent.action.DROPBOX_ENTRY_ADDED flg=0x10 (has extras) } to com.google.android.gms/.chimera.GmsIntentOperationService$PersistentTrustedReceiver
33602-18 15:17:58.121  3310  3325 W BroadcastQueue: Background execution not allowed: receiving Intent { act=android.intent.action.DROPBOX_ENTRY_ADDED flg=0x10 (has extras) } to com.google.android.gms/.stats.service.DropBoxEntryAddedReceiver
33702-18 15:17:58.121  3310  3325 W BroadcastQueue: Background execution not allowed: receiving Intent { act=android.intent.action.DROPBOX_ENTRY_ADDED flg=0x10 (has extras) } to com.google.android.gms/.chimera.GmsIntentOperationService$PersistentTrustedReceiver
33802-18 15:17:58.775  4069  4778 I chatty  : uid=10007(com.google.android.gms) expire 6 lines
33902-18 15:17:58.778  4069  4780 I chatty  : uid=10007(com.google.android.gms) expire 10 lines
34002-18 15:17:58.780  4069  4868 I chatty  : uid=10007(com.google.android.gms) expire 17 lines
34102-18 15:17:58.826  3310  3310 V SettingsProvider: Notifying for 0: content://settings/global/Phenotype_boot_count
34202-18 15:17:58.877  3842  4872 I chatty  : uid=10007 com.google.android.gms.persistent expire 1 line
34302-18 15:17:58.923  3842  4201 I chatty  : uid=10007(com.google.android.gms) Binder:3842_5 expire 2 lines
34402-18 15:17:58.993  4069  4885 I chatty  : uid=10007(com.google.android.gms) expire 19 lines
34502-18 15:17:59.055  3083  3145 W Netd    : No subsystem found in netlink event
34602-18 15:17:59.130  4069  4896 I chatty  : uid=10007(com.google.android.gms) expire 5 lines
34702-18 15:17:59.197  3310  4875 I chatty  : uid=1000(system) Binder:3310_B expire 4 lines
34802-18 15:17:59.340  4906  4906 I Zygote  : seccomp disabled by setenforce 0
34902-18 15:17:59.342  3310  3327 W ActivityManager: Slow operation: 55ms so far, now at startProcess: returned from zygote!
35002-18 15:17:59.344  4906  4906 I chatty  : uid=10007 com.google.android.gms.ui identical 14 lines
35102-18 15:17:59.345  3310  3327 W ActivityManager: Slow operation: 59ms so far, now at startProcess: done updating battery stats
35202-18 15:17:59.346  3310  3327 W ActivityManager: Slow operation: 59ms so far, now at startProcess: building log message
35302-18 15:17:59.346  3310  3327 I ActivityManager: Start proc 4906:com.google.android.gms.ui/u0a7 for service com.google.android.gms/.chimera.UiIntentOperationService
35402-18 15:17:59.346  3310  3327 W ActivityManager: Slow operation: 59ms so far, now at startProcess: starting to update pids map
35502-18 15:17:59.346  3310  3327 W ActivityManager: Slow operation: 59ms so far, now at startProcess: done updating pids map
35602-18 15:17:59.625  3842  4779 I chatty  : uid=10007 com.google.android.gms.persistent expire 4 lines
35702-18 15:17:59.722  3310  3523 D BackupTransportManager: Transport com.google.android.gms/.backup.BackupTransportService updated its attributes
35802-18 15:17:59.821  4069  4080 I chatty  : uid=10007(com.google.android.gms) HeapTaskDaemon expire 13 lines
35902-18 15:17:59.894  4069  4930 I chatty  : uid=10007(com.google.android.gms) lowpool[3] expire 12 lines
36002-18 15:18:00.063  4069  4948 I chatty  : uid=10007(com.google.android.gms) highpool[2] expire 25 lines
36102-18 15:18:00.181  4600  4612 I Finsky  : [135] bis.a(10): Received content filters request from sharedUserId=com.google.uid.shared:10007
36202-18 15:18:00.194  4069  4950 I chatty  : uid=10007(com.google.android.gms) AdWorker(Defaul expire 1 line
36302-18 15:18:00.271  3090  4426 D audio-subMixingFactory: out_standby_subMixingPCM: out_stream(0xa1ed8000) usecase: STREAM_PCM_NORMAL
36402-18 15:18:00.271  3090  4426 I audio-subMixingFactory: ++usecase_change_validate_l_sm(), dev usecase masks = 0x1, is_standby = 1, out usecase STREAM_PCM_NORMAL
36502-18 15:18:00.271  3090  4426 I audio-subMixingFactory: --usecase_change_validate_l_sm(), dev usecase masks = 0, is_standby = 1, out usecase STREAM_PCM_NORMAL
36602-18 15:18:00.271  3090  4426 I amlaudioMixer: ++delete_mixer_input_port port ID: 0
36702-18 15:18:00.314  4713  4727 I ownload_servic: Waiting for a blocking GC ProfileSaver
36802-18 15:18:00.396  4713  4727 I ownload_servic: Waiting for a blocking GC ProfileSaver
36902-18 15:18:00.397  4713  4727 I ownload_servic: WaitForGcToComplete blocked ProfileSaver on HeapTrim for 82.480ms
37002-18 15:18:00.700  3310  3327 I ActivityManager: Start proc 4967:com.google.android.gms.unstable/u0a7 for service com.google.android.gms/.droidguard.DroidGuardService
37102-18 15:18:00.706  4967  4967 I Zygote  : seccomp disabled by setenforce 0
37202-18 15:18:00.708  4069  4926 I chatty  : uid=10007(com.google.android.gms) expire 24 lines
37302-18 15:18:00.712  4967  4967 I chatty  : uid=10007 com.google.android.gms.unstable expire 20 lines
37402-18 15:18:00.869  3842  3842 I chatty  : uid=10007 com.google.android.gms.persistent expire 18 lines
37502-18 15:18:01.295  3310  3524 W BroadcastQueue: Background execution not allowed: receiving Intent { act=android.intent.action.DROPBOX_ENTRY_ADDED flg=0x10 (has extras) } to com.google.android.gms/.stats.service.DropBoxEntryAddedReceiver
37602-18 15:18:01.296  3310  3325 W BroadcastQueue: Background execution not allowed: receiving Intent { act=android.intent.action.DROPBOX_ENTRY_ADDED flg=0x10 (has extras) } to com.google.android.gms/.chimera.GmsIntentOperationService$PersistentTrustedReceiver
37702-18 15:18:01.301  3310  3519 D ConnectivityService: requestNetwork for uid/pid:10007/4069 NetworkRequest [ TRACK_DEFAULT id=11, [ Capabilities: INTERNET&NOT_RESTRICTED&TRUSTED Unwanted:  Uid: 10007] ]
37802-18 15:18:01.363  3310  3380 W ActivityManager: Unable to start service Intent { act=com.google.android.gms.drive.ApiService.RESET_AFTER_BOOT flg=0x4 cmp=com.google.android.gms/.drive.api.ApiService (has extras) } U=0: not found
37902-18 15:18:01.506  3842  4149 I chatty  : uid=10007 com.google.android.gms.persistent expire 1 line
38002-18 15:18:01.876  4069  4997 I chatty  : uid=10007(com.google.android.gms) expire 1 line
38102-18 15:18:01.963  4069  4886 I chatty  : uid=10007(com.google.android.gms) expire 3 lines
38202-18 15:18:02.059  4742  4757 I d.configupdate: Waiting for a blocking GC ProfileSaver
38302-18 15:18:02.081  4742  4757 I d.configupdate: WaitForGcToComplete blocked ProfileSaver on HeapTrim for 21.969ms
38402-18 15:18:02.097  4600  4880 I Finsky  : [205] abnp.a(58): Module info request for [# aqzx@3929f8a9, # aqzx@f679b911] modules from package com.google.android.gms v=20104053 hasAccount:false authEnabled:false
38502-18 15:18:02.153  4967  5018 I chatty  : uid=10007 com.google.android.gms.unstable expire 10 lines
38602-18 15:18:02.346  4785  4801 I timeinitialize: Waiting for a blocking GC ProfileSaver
38702-18 15:18:02.379  4785  4801 I timeinitialize: WaitForGcToComplete blocked ProfileSaver on HeapTrim for 33.304ms
38802-18 15:18:02.380  4069  4069 I chatty  : uid=10007(com.google.android.gms) expire 5 lines
38902-18 15:18:02.390  4069  5041 I chatty  : uid=10007(com.google.android.gms) expire 5 lines
39002-18 15:18:02.405  3186  3186 I keystore: del USRPKEY_unstable.825349ac930c2370b39f30e7d789963a.13 10007
39102-18 15:18:02.405  3186  3186 I keystore: del USRCERT_unstable.825349ac930c2370b39f30e7d789963a.13 10007
39202-18 15:18:02.406  3186  3186 I keystore: del CACERT_unstable.825349ac930c2370b39f30e7d789963a.13 10007
39302-18 15:18:02.407  3019  3019 I chatty  : uid=1000(system) keymaster@3.0-s expire 52 lines
39402-18 15:18:02.515  3310  3327 I ActivityManager: Start proc 5047:android.process.acore/u0a5 for content provider com.android.providers.contacts/.ContactsProvider2
39502-18 15:18:02.515  5047  5047 I Zygote  : seccomp disabled by setenforce 0
39602-18 15:18:02.529  5047  5047 W d.process.acor: Using default instruction set features for ARM CPU variant (cortex-a9) using conservative defaults
39702-18 15:18:02.551  3310  5032 W ActivityManager: Unable to start service Intent { act=com.google.android.gms.wearable.BIND pkg=com.google.android.gms } U=0: not found
39802-18 15:18:02.555  3310  5032 W ActivityManager: Unbind failed: could not find connection for android.os.BinderProxy@c696d3e
39902-18 15:18:02.556  4069  4140 I chatty  : uid=10007(com.google.android.gms) GoogleApiHandle expire 2 lines
40002-18 15:18:02.621  5047  5047 I d.process.acor: The ClassLoaderContext is a special shared library.
40102-18 15:18:02.705  4069  5044 I chatty  : uid=10007(com.google.android.gms) expire 1 line
40202-18 15:18:02.722  4069  5042 I chatty  : uid=10007(com.google.android.gms) expire 5 lines
40302-18 15:18:02.746  3842  5024 I chatty  : uid=10007 com.google.android.gms.persistent expire 6 lines
40402-18 15:18:02.783  5047  5047 I ContactsPerf: VoicemailContentProvider.onCreate start
40502-18 15:18:02.814  5047  5047 I ContactsPerf: VoicemailContentProvider.onCreate finish
40602-18 15:18:02.828  5047  5069 D ContactsDatabaseHelper: WAL enabled for contacts2.db: false
40702-18 15:18:02.832  5047  5047 I d.process.acor: The ClassLoaderContext is a special shared library.
40802-18 15:18:03.027  4844  4859 I e.process.gapp: Waiting for a blocking GC ProfileSaver
40902-18 15:18:03.044  5047  5069 I ContactLocale: AddressBook Labels [[en_US]]: […, A, B, C, D, E, F, G, H, I, J, K, L, M, N, O, P, Q, R, S, T, U, V, W, X, Y, Z, Α, Β, Γ, Δ, Ε, Ζ, Η, Θ, Ι, Κ, Λ, Μ, Ν, Ξ, Ο, Π, Ρ, Σ, Τ, Υ, Φ, Χ, Ψ, Ω, …, А, Б, В, Г, Д, Ђ, Е, Є, Ж, З, И, І, Й, Ј, К, Л, Љ, М, Н, Њ, О, П, Р, С, Т, Ћ, У, Ф, Х, Ц, Ч, Џ, Ш, Щ, Ю, Я, …, א, ב, ג, ד, ה, ו, ז, ח, ט, י, כ, ל, מ, נ, ס, ע, פ, צ, ק, ר, ש, ת, …, ا, ب, ت, ث, ج, ح, خ, د, ذ, ر, ز, س, ش, ص, ض, ط, ظ, ع, غ, ف, ق, ك, ل, م, ن, ه, و, ي, …, ก, ข, ฃ, ค, ฅ, ฆ, ง, จ, ฉ, ช, ซ, ฌ, ญ, ฎ, ฏ, ฐ, ฑ, ฒ, ณ, ด, ต, ถ, ท, ธ, น, บ, ป, ผ, ฝ, พ, ฟ, ภ, ม, ย, ร, ฤ, ล, ฦ, ว, ศ, ษ, ส, ห, ฬ, อ, ฮ, …, ㄱ, ㄴ, ㄷ, ㄹ, ㅁ, ㅂ, ㅅ, ㅇ, ㅈ, ㅊ, ㅋ, ㅌ, ㅍ, ㅎ, …, あ, か, さ, た, な, は, ま, や, ら, わ, #, …]
41002-18 15:18:03.072  4844  4859 I e.process.gapp: WaitForGcToComplete blocked ProfileSaver on HeapTrim for 44.529ms
41102-18 15:18:03.095  5047  5061 I d.process.acor: Background concurrent copying GC freed 5863(367KB) AllocSpace objects, 2(40KB) LOS objects, 27% free, 1342KB/1854KB, paused 16.155ms total 162.046ms
41202-18 15:18:03.108  5047  5069 D ContactsDatabaseHelper: WAL enabled for profile.db: false
41302-18 15:18:03.346  4600  4611 I android.vendin: Background concurrent copying GC freed 15751(1022KB) AllocSpace objects, 4(144KB) LOS objects, 25% free, 3MB/4MB, paused 120us total 199.303ms
41402-18 15:18:03.347  4600  4608 W JavaBinder: BinderProxy is being destroyed but the application did not call unlinkToDeath to unlink all of its death recipients beforehand.  Releasing leaked death recipient: akhj
41502-18 15:18:03.408  4967  5090 I chatty  : uid=10007(com.google.android.gms) Binder:4967_5 expire 7 lines
41602-18 15:18:03.472  4600  4600 I Finsky  : [2] abna.a(7): Received response for moduleDelivery with status=1
41702-18 15:18:03.623  3019  3019 D AmlKeymaster: Device received begin
41802-18 15:18:03.624  3019  3019 D AmlKeymaster: Sending cmd: 4 with 400 byte request
41902-18 15:18:03.624  3019  3019 E AmlKeymaster: id: 87736088, ctx: 0xb25a3088, cmd: 4
42002-18 15:18:03.629  4069  5088 I chatty  : uid=10007(com.google.android.gms) Binder:4069_C expire 2 lines
42102-18 15:18:03.639  3019  3019 D AmlKeymaster: Received 24 byte response
42202-18 15:18:03.646  3019  3019 D AmlKeymaster: Device received update
42302-18 15:18:03.646  3019  3019 D AmlKeymaster: Sending cmd: 8 with 121 byte request
42402-18 15:18:03.646  3019  3019 E AmlKeymaster: id: 87736088, ctx: 0xb25a3088, cmd: 8
42502-18 15:18:03.646  3019  3019 D AmlKeymaster: Received 24 byte response
42602-18 15:18:03.650  3019  3019 D AmlKeymaster: Device received add_rng_entropy
42702-18 15:18:03.650  3019  3019 D AmlKeymaster: Sending cmd: 32 with 36 byte request
42802-18 15:18:03.650  3019  3019 E AmlKeymaster: id: 87736088, ctx: 0xb25a3088, cmd: 32
42902-18 15:18:03.651  3019  3019 D AmlKeymaster: Received 4 byte response
43002-18 15:18:03.654  3019  3019 D AmlKeymaster: Device received finish
43102-18 15:18:03.654  3019  3019 D AmlKeymaster: Sending cmd: 12 with 109 byte request
43202-18 15:18:03.654  3019  3019 E AmlKeymaster: id: 87736088, ctx: 0xb25a3088, cmd: 12
43302-18 15:18:03.658  3019  3019 D AmlKeymaster: Received 91 byte response
43402-18 15:18:03.753  3842  4144 I chatty  : uid=10007(com.google.android.gms) GoogleApiHandle expire 20 lines
43502-18 15:18:03.989  3842  4878 I chatty  : uid=10007(com.google.android.gms) Binder:3842_7 expire 1 line
43602-18 15:18:04.159  3842  5104 I chatty  : uid=10007 com.google.android.gms.persistent expire 2 lines
43702-18 15:18:04.265  3842  4143 I chatty  : uid=10007(com.google.android.gms) lowpool[0] expire 4 lines
43802-18 15:18:04.497  4906  4923 I chatty  : uid=10007(com.google.android.gms) Profile Saver expire 2 lines
43902-18 15:18:04.797  3842  5043 I chatty  : uid=10007 com.google.android.gms.persistent expire 1 line
44002-18 15:18:04.875  4069  4884 I chatty  : uid=10007(com.google.android.gms) lowpool[0] expire 44 lines
44102-18 15:18:04.876  3310  3952 I chatty  : uid=1000(system) Binder:3310_9 expire 1 line
44202-18 15:18:05.447  4069  4925 I chatty  : uid=10007(com.google.android.gms) lowpool[1] expire 46 lines
44302-18 15:18:05.653  3842  5130 I chatty  : uid=10007(com.google.android.gms) FlpThread expire 2 lines
44402-18 15:18:05.815  3842  3852 I chatty  : uid=10007(com.google.android.gms) FinalizerDaemon expire 1 line
44502-18 15:18:05.838  3842  4151 I chatty  : uid=10007(com.google.android.gms) lowpool[3] expire 4 lines
44602-18 15:18:05.842  3842  5129 I chatty  : uid=10007(com.google.android.gms) GeofencerStateM expire 1 line
44702-18 15:18:05.919  3310  5087 W BroadcastQueue: Background execution not allowed: receiving Intent { act=android.intent.action.DROPBOX_ENTRY_ADDED flg=0x10 (has extras) } to com.google.android.gms/.stats.service.DropBoxEntryAddedReceiver
44802-18 15:18:05.922  3310  3325 W BroadcastQueue: Background execution not allowed: receiving Intent { act=android.intent.action.DROPBOX_ENTRY_ADDED flg=0x10 (has extras) } to com.google.android.gms/.chimera.GmsIntentOperationService$PersistentTrustedReceiver
44902-18 15:18:06.086  3310  3322 W BroadcastQueue: Background execution not allowed: receiving Intent { act=android.intent.action.DROPBOX_ENTRY_ADDED flg=0x10 (has extras) } to com.google.android.gms/.stats.service.DropBoxEntryAddedReceiver
45002-18 15:18:06.087  3310  3325 W BroadcastQueue: Background execution not allowed: receiving Intent { act=android.intent.action.DROPBOX_ENTRY_ADDED flg=0x10 (has extras) } to com.google.android.gms/.chimera.GmsIntentOperationService$PersistentTrustedReceiver
45102-18 15:18:06.305  3310  5023 W BroadcastQueue: Background execution not allowed: receiving Intent { act=android.intent.action.DROPBOX_ENTRY_ADDED flg=0x10 (has extras) } to com.google.android.gms/.stats.service.DropBoxEntryAddedReceiver
45202-18 15:18:06.307  3310  3325 W BroadcastQueue: Background execution not allowed: receiving Intent { act=android.intent.action.DROPBOX_ENTRY_ADDED flg=0x10 (has extras) } to com.google.android.gms/.chimera.GmsIntentOperationService$PersistentTrustedReceiver
45302-18 15:18:06.310  3842  5139 I chatty  : uid=10007(com.google.android.gms) GoogleLocationS expire 2 lines
45402-18 15:18:06.443  3310  3325 W BroadcastQueue: Permission Denial: broadcasting Intent { act=com.google.android.checkin.CHECKIN_COMPLETE flg=0x1000010 (has extras) } from com.google.android.gms (pid=4069, uid=10007) is not exported from uid 10020 due to receiver com.android.vending/com.google.android.finsky.checkin.CheckinReceiver
45502-18 15:18:06.596  3842  4988 I chatty  : uid=10007 com.google.android.gms.persistent expire 10 lines
45602-18 15:18:06.878  3842  4305 I chatty  : uid=10007(com.google.android.gms) netscheduler-qu expire 1 line
45702-18 15:18:07.318  3310  3325 W BroadcastQueue: Background execution not allowed: receiving Intent { act=com.google.android.chimera.MODULE_CONFIGURATION_CHANGED flg=0x10 (has extras) } to com.google.android.gms/.chimera.GmsIntentOperationService$PersistentInternalReceiver
45802-18 15:18:07.796  5154  5154 I chatty  : uid=10007(com.google.android.gms) expire 5 lines
45902-18 15:18:08.164  3842  4150 I chatty  : uid=10007(com.google.android.gms) highpool[1] expire 7 lines
46002-18 15:18:08.367  3842  4799 I chatty  : uid=10007(com.google.android.gms) highpool[2] expire 2 lines
46102-18 15:18:08.466  4967  4983 I chatty  : uid=10007(com.google.android.gms) Binder:4967_4 expire 5 lines
46202-18 15:18:08.784  3083  3145 W Netd    : No subsystem found in netlink event
46302-18 15:18:09.051  4069  4899 I chatty  : uid=10007(com.google.android.gms) Thread-23 expire 6 lines
46402-18 15:18:09.312  4069  5046 I chatty  : uid=10007(com.google.android.gms) Binder:4069_A expire 16 lines
46502-18 15:18:10.327  3310  3325 W BroadcastQueue: Background execution not allowed: receiving Intent { act=com.google.android.chimera.MODULE_CONFIGURATION_CHANGED flg=0x10 (has extras) } to com.google.android.gms/.chimera.GmsIntentOperationService$PersistentInternalReceiver
46602-18 15:18:10.473  4069  4782 I chatty  : uid=10007(com.google.android.gms) expire 7 lines
46702-18 15:18:11.351  3842  3854 I chatty  : uid=10007(com.google.android.gms) HeapTaskDaemon expire 2 lines
46802-18 15:18:11.555  5178  5178 I chatty  : uid=10007(com.google.android.gms) expire 5 lines
46902-18 15:18:12.018  3427  3427 D Avrcp   : isBrowsableListUpdated: false
47002-18 15:18:12.018  3427  3427 V NewAvrcpMediaPlayerList: mPackageChangedBroadcastReceiver: action: android.intent.action.PACKAGE_CHANGED
47102-18 15:18:12.018  3427  3427 D NewAvrcpMediaPlayerList: Name of package changed: com.google.android.gms
47202-18 15:18:12.025  3310  3384 I chatty  : uid=1000(system) InputReader expire 1 line
47302-18 15:18:12.125  3310  3321 W ActivityManager: Unable to start service Intent { act=com.google.android.gms.icing.APP_INDEXING_SERVICE pkg=com.google.android.gms } U=0: not found
47402-18 15:18:12.129  3310  3952 W ActivityManager: Unbind failed: could not find connection for android.os.BinderProxy@9a269ad
47502-18 15:18:12.185  3310  3324 I chatty  : uid=1000(system) android.bg expire 6 lines
47602-18 15:18:12.189  3310  3324 W VoiceInteractionManagerService: no available voice interaction services found for user 0
47702-18 15:18:12.193  3310  3324 W LocationProviderProxy-network: Odd, no component found for service com.android.location.service.v3.NetworkLocationProvider
47802-18 15:18:12.202  3310  3324 W GeocoderProxy: Odd, no component found for service com.android.location.service.GeocodeProvider
47902-18 15:18:12.203  3310  3324 W GeofenceProxy: Odd, no component found for service com.android.location.service.GeofenceProvider
48002-18 15:18:12.204  3310  3324 W ActivityRecognitionProxy: Odd, no component found for service com.android.location.service.ActivityRecognitionProvider
48102-18 15:18:12.242  3310  3320 I chatty  : uid=1000(system) HeapTaskDaemon expire 2 lines
48202-18 15:18:12.535  3310  3325 W BroadcastQueue: Background execution not allowed: receiving Intent { act=com.google.android.chimera.MODULE_CONFIGURATION_CHANGED flg=0x10 (has extras) } to com.google.android.gms/.chimera.GmsIntentOperationService$PersistentInternalReceiver
48302-18 15:18:12.748  4069  4080 I chatty  : uid=10007(com.google.android.gms) HeapTaskDaemon expire 1 line
48402-18 15:18:13.111  3310  3321 I ActivityManager: Killing 4354:x.cpe/1000 (adj 906): empty #17
48502-18 15:18:13.167  3084  3084 I Zygote  : Process 4354 exited due to signal (9)
48602-18 15:18:13.201  3842  4986 I chatty  : uid=10007(com.google.android.gms) lowpool[4] expire 8 lines
48702-18 15:18:13.400  3105  3105 I memtrack@1.0-se: type=1400 audit(0.0:144): avc: denied { read } for name="mem_profile" dev="debugfs" ino=19438 scontext=u:r:hal_memtrack_default:s0 tcontext=u:object_r:debugfs:s0 tclass=file permissive=1
48802-18 15:18:13.400  3105  3105 I memtrack@1.0-se: type=1400 audit(0.0:145): avc: denied { open } for path="/sys/kernel/debug/mali0/ctx/4025_4/mem_profile" dev="debugfs" ino=19438 scontext=u:r:hal_memtrack_default:s0 tcontext=u:object_r:debugfs:s0 tclass=file permissive=1
48902-18 15:18:13.400  3105  3105 I memtrack@1.0-se: type=1400 audit(0.0:146): avc: denied { getattr } for path="/sys/kernel/debug/mali0/ctx/4025_4/mem_profile" dev="debugfs" ino=19438 scontext=u:r:hal_memtrack_default:s0 tcontext=u:object_r:debugfs:s0 tclass=file permissive=1
49002-18 15:18:14.395  4069  5054 I chatty  : uid=10007(com.google.android.gms) Binder:4069_B expire 4 lines
49102-18 15:18:14.442  4600  4600 I Finsky  : [2] JobSchedulerEngine$PhoneskyJobSchedulerJobService.onStartJob(5): SCH: onJobSchedulerWakeup with jobId 9006
49202-18 15:18:14.444  4600  4600 I Finsky  : [2] tnz.a(24): Scheduling fallback job with id: 9034, and delay: 43200000 ms
49302-18 15:18:14.447  4600  4600 I Finsky  : [2] tnz.a(5): Scheduling fallback in 64799998 (absolute: 64965786)
49402-18 15:18:14.469  4600  4600 I Finsky  : [2] toi.handleMessage(10): SCH: DeviceState: DeviceState{currentTime=1582039094464, isCharging=true, isIdle=false, netAny=true, netNotRoaming=true, netUnmetered=true, batteryPercent=42.0}
49502-18 15:18:14.478  4600  4628 I Finsky  : [151] tqw.a(7): SCH: Jobs in database: 1-1337 3-11 21-333333333 24-77777777 26-1414141414 29-29
49602-18 15:18:14.509  4600  4600 I Finsky  : [2] toj.a(20): SCH: Running job: 29-29
49702-18 15:18:14.519  4600  4600 I Finsky  : [2] toi.handleMessage(63): SCH: RunningQueue size: 1, PendingQueue size: 0
49802-18 15:18:14.520  4600  4600 I Finsky  : [2] toi.handleMessage(66): SCH: Running queue: 29-29
49902-18 15:18:14.522  4600  5189 I Finsky  : [209] zhm.a(1): ProcessRecoveryLogsUtil: No files in recovery directory
50002-18 15:18:14.535  4600  5189 I Finsky  : [209] tpt.a(13): SCH: jobFinished: 29-29. TimeElapsed: 25ms.
50102-18 15:18:14.538  4600  4600 I Finsky  : [2] toj.a(35): SCH: Job 29-29 finished. Not rescheduling.
50202-18 15:18:14.540  4600  4600 I Finsky  : [2] toi.handleMessage(63): SCH: RunningQueue size: 0, PendingQueue size: 0
50302-18 15:18:14.541  4600  4600 I Finsky  : [2] toi.handleMessage(29): Executor finished
50402-18 15:18:14.589  4600  4600 I Finsky  : [2] tqw.a(7): SCH: Jobs in database: 1-1337 3-11 21-333333333 24-77777777 26-1414141414
50502-18 15:18:14.606  4600  4600 I Finsky  : [2] tmt.a(58): SCH: ConstraintMapping: 24-77777777,  -> L: 0ms, D: 78968729ms, C: false, I: true, N: 0
50602-18 15:18:14.607  4600  4600 I Finsky  : [2] tmt.a(58): SCH: ConstraintMapping: 1-1337, 21-333333333,  -> L: 90151ms, D: 56547731ms, C: false, I: false, N: 1
50702-18 15:18:14.608  4600  4600 I Finsky  : [2] tmt.a(58): SCH: ConstraintMapping: 3-11,  -> L: 0ms, D: 86370464ms, C: true, I: true, N: 2
50802-18 15:18:14.608  4600  4600 I Finsky  : [2] tmt.a(58): SCH: ConstraintMapping: 26-1414141414,  -> L: 40397928ms, D: 41297928ms, C: false, I: false, N: 0
50902-18 15:18:14.612  4600  4600 I Finsky  : [2] tpg.a(37): Cancelling existing jobscheduler jobs: 9001 9000 9002
51002-18 15:18:14.612  4600  4600 I Finsky  : [2] tpg.a(14): Throttling wakeup for job 9003 (expected to run in 0 ms) due to recent wakeup
51102-18 15:18:14.613  4600  4600 I Finsky  : [2] tpg.a(30): Scheduling job Id: 9003, L: 30000, D: 78968729, C: false, I: true, N: 0
51202-18 15:18:14.615  4600  4600 I Finsky  : [2] tpg.a(30): Scheduling job Id: 9004, L: 90151, D: 56547731, C: false, I: false, N: 1
51302-18 15:18:14.617  4600  4600 I Finsky  : [2] tpg.a(14): Throttling wakeup for job 9005 (expected to run in 0 ms) due to recent wakeup
51402-18 15:18:14.618  4600  4600 I Finsky  : [2] tpg.a(30): Scheduling job Id: 9005, L: 30000, D: 86370464, C: true, I: true, N: 2
51502-18 15:18:14.620  4600  4600 I Finsky  : [2] tpg.a(30): Scheduling job Id: 9007, L: 40397928, D: 41297928, C: false, I: false, N: 0
51602-18 15:18:16.077  3083  3145 W Netd    : No subsystem found in netlink event
51702-18 15:18:20.833  3842  3842 I chatty  : uid=10007 com.google.android.gms.persistent expire 1 line
51802-18 15:18:21.122  3310  3524 I ActivityManager: Killing 4461:com.droidlogic.tv.settings/1000 (adj 906): empty #17
51902-18 15:18:21.158  3084  3084 I Zygote  : Process 4461 exited due to signal (9)
52002-18 15:18:23.832  3310  3324 I ActivityManager: Killing 3820:com.android.deskclock/u0a46 (adj 906): empty #17
52102-18 15:18:23.871  3084  3084 I Zygote  : Process 3820 exited due to signal (9)
52202-18 15:18:23.882  3310  3328 I chatty  : uid=1000(system) ActivityManager expire 6 lines
52302-18 15:18:24.925  3196  4702 I MessageMonitor: G[0]: [NU-AmNuPlayer] Last Message 15 is ['mckN':0], cost 35 Us
52402-18 15:18:24.937  3196  4704 I MessageMonitor: G[0]: [NU-GenericSource] Last Message 25 is [0x00000009:0], cost 633 Us
52502-18 15:18:25.163  3083  3145 W Netd    : No subsystem found in netlink event
52602-18 15:18:25.488  3196  4734 I MessageMonitor: G[0]: [NU-NuPlayerRenderer] Last Message 182 is ['paus':0], cost 19 Us
52702-18 15:18:25.490  3196  4738 I MessageMonitor: G[0]: [NU-NuPlayerDecoder-audN] Last Message 296 is ['rndr':0], cost 20 Us
52802-18 15:18:25.490  3196  4738 I MessageMonitor: [NU-NuPlayerDecoder-audN] DQ pts(1718666), In pts(0) [0/4], Out pts(1728749),Render pts(-1[0]), Drop pts(-1[0]) [0/4]
52902-18 15:18:27.790  3310  5190 I ActivityManager: Killing 4511:com.android.managedprovisioning/u0a27 (adj 906): empty #17
53002-18 15:18:27.843  3084  3084 I Zygote  : Process 4511 exited due to signal (9)
53102-18 15:18:29.643  3842  4144 I chatty  : uid=10007(com.google.android.gms) GoogleApiHandle expire 12 lines
53202-18 15:18:34.253  3083  3145 W Netd    : No subsystem found in netlink event
53302-18 15:18:39.926  3310  3320 I chatty  : uid=1000(system) HeapTaskDaemon expire 4 lines
53402-18 15:18:40.031  4370  4444 I chatty  : uid=1000(system) Com Server expire 3 lines
53502-18 15:18:40.076  4370  4370 I chatty  : uid=1000(system) x.cpe:WorkService expire 2 lines
53602-18 15:18:40.076  4370  4370 I x.cpe:WorkService: type=1400 audit(0.0:149): avc: denied { getattr } for comm=436F6D20536572766572 path="/proc/stat" dev="proc" ino=4026531973 scontext=u:r:system_app:s0 tcontext=u:object_r:proc_stat:s0 tclass=file permissive=1
53702-18 15:18:41.955  3197  3245 E storaged: getDiskStats failed with result NOT_SUPPORTED and size 0
53802-18 15:18:43.945  4600  4617 I Finsky  : [140] kay.run(3): Stats for Executor: BlockingExecutor kcu@f76ff10[Running, pool size = 2, active threads = 0, queued tasks = 0, completed tasks = 12]
53902-18 15:18:43.947  4600  4617 I Finsky  : [140] kay.run(3): Stats for Executor: LightweightExecutor kcu@dc1aa09[Running, pool size = 3, active threads = 0, queued tasks = 0, completed tasks = 70]
54002-18 15:18:43.967  3083  3145 W Netd    : No subsystem found in netlink event
54102-18 15:18:44.318  4600  4617 I Finsky  : [140] kay.run(3): Stats for Executor: bgExecutor kcu@93a540e[Running, pool size = 4, active threads = 0, queued tasks = 0, completed tasks = 90]
54202-18 15:18:44.807  3310  3948 I ActivityManager: Killing 4526:com.android.onetimeinitializer/u0a13 (adj 906): empty #17
54302-18 15:18:44.854  3084  3084 I Zygote  : Process 4526 exited due to signal (9)
54402-18 15:18:44.858  3310  3328 I chatty  : uid=1000(system) ActivityManager expire 2 lines
54502-18 15:18:44.882  3842  4144 I chatty  : uid=10007(com.google.android.gms) GoogleApiHandle expire 12 lines
54602-18 15:18:50.016  3083  3145 W Netd    : No subsystem found in netlink event
54702-18 15:18:54.926  3196  4702 I MessageMonitor: G[1]: [NU-AmNuPlayer] Last Message 15 is ['mckN':0], cost 35 Us
54802-18 15:18:54.937  3196  4704 I MessageMonitor: G[1]: [NU-GenericSource] Last Message 25 is [0x00000009:0], cost 633 Us
54902-18 15:18:55.489  3196  4734 I MessageMonitor: G[1]: [NU-NuPlayerRenderer] Last Message 182 is ['paus':0], cost 19 Us
55002-18 15:18:55.491  3196  4738 I MessageMonitor: G[1]: [NU-NuPlayerDecoder-audN] Last Message 296 is ['rndr':0], cost 20 Us
55102-18 15:18:55.491  3196  4738 I MessageMonitor: [NU-NuPlayerDecoder-audN] DQ pts(1718666), In pts(0) [0/4], Out pts(1728749),Render pts(-1[0]), Drop pts(-1[0]) [0/4]
55202-18 15:18:56.493  3083  3805 W DnsTlsSocket: SSL_connect error 5, errno=110
55302-18 15:18:57.199  3083  3145 W Netd    : No subsystem found in netlink event
55402-18 15:19:00.116  4069  5046 I chatty  : uid=10007(com.google.android.gms) Binder:4069_A expire 11 lines
55502-18 15:19:00.143  3842  4144 I chatty  : uid=10007(com.google.android.gms) GoogleApiHandle expire 12 lines
55602-18 15:19:07.163  3083  3145 W Netd    : No subsystem found in netlink event
55702-18 15:19:15.243  3083  3145 W Netd    : No subsystem found in netlink event
55802-18 15:19:15.419  3842  4144 I chatty  : uid=10007(com.google.android.gms) GoogleApiHandle expire 10 lines
55902-18 15:19:24.926  3196  4702 I MessageMonitor: G[2]: [NU-AmNuPlayer] Last Message 15 is ['mckN':0], cost 35 Us
56002-18 15:19:24.938  3196  4704 I MessageMonitor: G[2]: [NU-GenericSource] Last Message 25 is [0x00000009:0], cost 633 Us
56102-18 15:19:25.158  3083  3145 W Netd    : No subsystem found in netlink event
56202-18 15:19:25.489  3196  4734 I MessageMonitor: G[2]: [NU-NuPlayerRenderer] Last Message 182 is ['paus':0], cost 19 Us
56302-18 15:19:25.491  3196  4738 I MessageMonitor: G[2]: [NU-NuPlayerDecoder-audN] Last Message 296 is ['rndr':0], cost 20 Us
56402-18 15:19:25.491  3196  4738 I MessageMonitor: [NU-NuPlayerDecoder-audN] DQ pts(1718666), In pts(0) [0/4], Out pts(1728749),Render pts(-1[0]), Drop pts(-1[0]) [0/4]
56502-18 15:19:25.638  4069  5046 I chatty  : uid=10007(com.google.android.gms) Binder:4069_A expire 7 lines
56602-18 15:19:25.665  3842  4144 I chatty  : uid=10007(com.google.android.gms) GoogleApiHandle expire 10 lines
56702-18 15:19:32.894  3083  3145 W Netd    : No subsystem found in netlink event
56802-18 15:19:40.864  3842  4144 I chatty  : uid=10007(com.google.android.gms) GoogleApiHandle expire 6 lines
56902-18 15:19:41.890  3083  3145 W Netd    : No subsystem found in netlink event
57002-18 15:19:41.956  3197  3245 E storaged: getDiskStats failed with result NOT_SUPPORTED and size 0
57102-18 15:19:44.801  4600  4600 I Finsky  : [2] JobSchedulerEngine$PhoneskyJobSchedulerJobService.onStartJob(5): SCH: onJobSchedulerWakeup with jobId 9004
57202-18 15:19:44.808  4600  4600 I Finsky  : [2] tnz.a(24): Scheduling fallback job with id: 9034, and delay: 43200000 ms
57302-18 15:19:44.813  4600  4600 I Finsky  : [2] tnz.a(5): Scheduling fallback in 64799999 (absolute: 65056154)
57402-18 15:19:44.844  4600  4600 I Finsky  : [2] toi.handleMessage(10): SCH: DeviceState: DeviceState{currentTime=1582039184836, isCharging=true, isIdle=false, netAny=true, netNotRoaming=true, netUnmetered=true, batteryPercent=42.0}
57502-18 15:19:44.864  4600  5199 I Finsky  : [211] tqw.a(7): SCH: Jobs in database: 1-1337 3-11 21-333333333 24-77777777 26-1414141414
57602-18 15:19:44.886  4600  4600 I Finsky  : [2] toj.a(20): SCH: Running job: 21-333333333
57702-18 15:19:44.891  4600  4600 W Finsky  : [2] drd.a(21): For unauth, use getDfeApiNonAuthenticated() instead!
57802-18 15:19:44.892  4600  4600 I Finsky  : [2] drd.a(24): getDfeApiOrNonauthenticated is returning unauth api
57902-18 15:19:44.895  4600  4600 I Finsky  : [2] toj.a(35): SCH: Job 21-333333333 finished. Not rescheduling.
58002-18 15:19:44.896  4600  4600 I Finsky  : [2] toi.handleMessage(63): SCH: RunningQueue size: 0, PendingQueue size: 0
58102-18 15:19:44.897  4600  4600 I Finsky  : [2] toi.handleMessage(29): Executor finished
58202-18 15:19:44.920  4600  4600 I Finsky  : [2] tqw.a(7): SCH: Jobs in database: 1-1337 3-11 24-77777777 26-1414141414
58302-18 15:19:44.937  4600  4600 I Finsky  : [2] tmt.a(58): SCH: ConstraintMapping: 24-77777777,  -> L: 0ms, D: 78878398ms, C: false, I: true, N: 0
58402-18 15:19:44.938  4600  4600 I Finsky  : [2] tmt.a(58): SCH: ConstraintMapping: 1-1337,  -> L: 13257399ms, D: 56457399ms, C: false, I: false, N: 1
58502-18 15:19:44.939  4600  4600 I Finsky  : [2] tmt.a(58): SCH: ConstraintMapping: 3-11,  -> L: 0ms, D: 86280130ms, C: true, I: true, N: 2
58602-18 15:19:44.940  4600  4600 I Finsky  : [2] tmt.a(58): SCH: ConstraintMapping: 26-1414141414,  -> L: 40307597ms, D: 41207597ms, C: false, I: false, N: 0
58702-18 15:19:44.945  4600  4600 I Finsky  : [2] tpg.a(37): Cancelling existing jobscheduler jobs: 9007 9005 9003
58802-18 15:19:44.945  4600  4600 I Finsky  : [2] tpg.a(14): Throttling wakeup for job 9000 (expected to run in 0 ms) due to recent wakeup
58902-18 15:19:44.946  4600  4600 I Finsky  : [2] tpg.a(30): Scheduling job Id: 9000, L: 30000, D: 78878398, C: false, I: true, N: 0
59002-18 15:19:44.947  4600  4600 I Finsky  : [2] tpg.a(30): Scheduling job Id: 9001, L: 13257399, D: 56457399, C: false, I: false, N: 1
59102-18 15:19:44.949  4600  4600 I Finsky  : [2] tpg.a(14): Throttling wakeup for job 9002 (expected to run in 0 ms) due to recent wakeup
59202-18 15:19:44.950  4600  4600 I Finsky  : [2] tpg.a(30): Scheduling job Id: 9002, L: 30000, D: 86280130, C: true, I: true, N: 2
59302-18 15:19:44.951  4600  4600 I Finsky  : [2] tpg.a(30): Scheduling job Id: 9006, L: 40307597, D: 41207597, C: false, I: false, N: 0
59402-18 15:19:45.919  4069  5107 I chatty  : uid=10007(com.google.android.gms) Binder:4069_D expire 2 lines
59502-18 15:19:45.994  3842  4144 I chatty  : uid=10007(com.google.android.gms) GoogleApiHandle expire 4 lines
59602-18 15:19:47.244  3083  3145 W Netd    : No subsystem found in netlink event
59702-18 15:19:51.006  4069  5107 I chatty  : uid=10007(com.google.android.gms) Binder:4069_D expire 2 lines
59802-18 15:19:51.084  3842  4144 E JavaBinder: !!! FAILED BINDER TRANSACTION !!!  (parcel size = 308)
59902-18 15:19:51.085  3842  4144 W GmsClient: IGmsServiceBroker.getService failed
60002-18 15:19:51.085  3842  4144 W GmsClient: android.os.DeadObjectException: Transaction failed on small parcel; remote process probably died
60102-18 15:19:51.085  3842  4144 W GmsClient:     at android.os.BinderProxy.transactNative(Native Method)
60202-18 15:19:51.085  3842  4144 W GmsClient:     at android.os.BinderProxy.transact(Binder.java:1127)
60302-18 15:19:51.085  3842  4144 W GmsClient:     at qjl.a(:com.google.android.gms@20104053@20.1.04 (110304-288960190):4)
60402-18 15:19:51.085  3842  4144 W GmsClient:     at qhl.a(:com.google.android.gms@20104053@20.1.04 (110304-288960190):81)
60502-18 15:19:51.085  3842  4144 W GmsClient:     at pug.run(:com.google.android.gms@20104053@20.1.04 (110304-288960190):6)
60602-18 15:19:51.085  3842  4144 W GmsClient:     at android.os.Handler.handleCallback(Handler.java:873)
60702-18 15:19:51.085  3842  4144 W GmsClient:     at android.os.Handler.dispatchMessage(Handler.java:99)
60802-18 15:19:51.085  3842  4144 W GmsClient:     at aatx.a(:com.google.android.gms@20104053@20.1.04 (110304-288960190):2)
60902-18 15:19:51.085  3842  4144 W GmsClient:     at aatx.dispatchMessage(:com.google.android.gms@20104053@20.1.04 (110304-288960190):15)
61002-18 15:19:51.085  3842  4144 W GmsClient:     at android.os.Looper.loop(Looper.java:193)
61102-18 15:19:51.085  3842  4144 W GmsClient:     at android.os.HandlerThread.run(HandlerThread.java:65)
61202-18 15:19:54.926  3196  4702 I MessageMonitor: G[3]: [NU-AmNuPlayer] Last Message 15 is ['mckN':0], cost 35 Us
61302-18 15:19:54.938  3196  4704 I MessageMonitor: G[3]: [NU-GenericSource] Last Message 25 is [0x00000009:0], cost 633 Us
61402-18 15:19:55.130  3310  3320 I system_server: Background concurrent copying GC freed 42940(1752KB) AllocSpace objects, 0(0B) LOS objects, 19% free, 7MB/9MB, paused 190us total 126.072ms
61502-18 15:19:55.489  3196  4734 I MessageMonitor: G[3]: [NU-NuPlayerRenderer] Last Message 182 is ['paus':0], cost 19 Us
61602-18 15:19:55.491  3196  4738 I MessageMonitor: G[3]: [NU-NuPlayerDecoder-audN] Last Message 296 is ['rndr':0], cost 20 Us
61702-18 15:19:55.491  3196  4738 I MessageMonitor: [NU-NuPlayerDecoder-audN] DQ pts(1718666), In pts(0) [0/4], Out pts(1728749),Render pts(-1[0]), Drop pts(-1[0]) [0/4]
61802-18 15:19:56.083  3083  3145 W Netd    : No subsystem found in netlink event
61902-18 15:19:56.088  4069  5107 I ChimeraSrvcProxy: NullBinder for com.google.android.mdh.service.START triggering remote TransactionTooLargeException due to Service without Chimera impl
62002-18 15:19:56.115  3842  4144 E JavaBinder: !!! FAILED BINDER TRANSACTION !!!  (parcel size = 916)
62102-18 15:19:56.115  3842  4144 W GmsClient: IGmsServiceBroker.getService failed
62202-18 15:19:56.115  3842  4144 W GmsClient: android.os.DeadObjectException: Transaction failed on small parcel; remote process probably died
62302-18 15:19:56.115  3842  4144 W GmsClient:     at android.os.BinderProxy.transactNative(Native Method)
62402-18 15:19:56.115  3842  4144 W GmsClient:     at android.os.BinderProxy.transact(Binder.java:1127)
62502-18 15:19:56.115  3842  4144 W GmsClient:     at qjl.a(:com.google.android.gms@20104053@20.1.04 (110304-288960190):4)
62602-18 15:19:56.115  3842  4144 W GmsClient:     at qhl.a(:com.google.android.gms@20104053@20.1.04 (110304-288960190):81)
62702-18 15:19:56.115  3842  4144 W GmsClient:     at pug.run(:com.google.android.gms@20104053@20.1.04 (110304-288960190):6)
62802-18 15:19:56.115  3842  4144 W GmsClient:     at android.os.Handler.handleCallback(Handler.java:873)
62902-18 15:19:56.115  3842  4144 W GmsClient:     at android.os.Handler.dispatchMessage(Handler.java:99)
63002-18 15:19:56.115  3842  4144 W GmsClient:     at aatx.a(:com.google.android.gms@20104053@20.1.04 (110304-288960190):2)
63102-18 15:19:56.115  3842  4144 W GmsClient:     at aatx.dispatchMessage(:com.google.android.gms@20104053@20.1.04 (110304-288960190):15)
63202-18 15:19:56.115  3842  4144 W GmsClient:     at android.os.Looper.loop(Looper.java:193)
63302-18 15:19:56.115  3842  4144 W GmsClient:     at android.os.HandlerThread.run(HandlerThread.java:65)
63402-18 15:19:56.141  4069  5107 I ChimeraSrvcProxy: NullBinder for com.google.android.mdh.service.listener.START triggering remote TransactionTooLargeException due to Service without Chimera impl
63502-18 15:19:56.168  3842  4144 E JavaBinder: !!! FAILED BINDER TRANSACTION !!!  (parcel size = 308)
63602-18 15:19:56.169  3842  4144 W GmsClient: IGmsServiceBroker.getService failed
63702-18 15:19:56.169  3842  4144 W GmsClient: android.os.DeadObjectException: Transaction failed on small parcel; remote process probably died
63802-18 15:19:56.169  3842  4144 W GmsClient:     at android.os.BinderProxy.transactNative(Native Method)
63902-18 15:19:56.169  3842  4144 W GmsClient:     at android.os.BinderProxy.transact(Binder.java:1127)
64002-18 15:19:56.169  3842  4144 W GmsClient:     at qjl.a(:com.google.android.gms@20104053@20.1.04 (110304-288960190):4)
64102-18 15:19:56.169  3842  4144 W GmsClient:     at qhl.a(:com.google.android.gms@20104053@20.1.04 (110304-288960190):81)
64202-18 15:19:56.169  3842  4144 W GmsClient:     at pug.run(:com.google.android.gms@20104053@20.1.04 (110304-288960190):6)
64302-18 15:19:56.169  3842  4144 W GmsClient:     at android.os.Handler.handleCallback(Handler.java:873)
64402-18 15:19:56.169  3842  4144 W GmsClient:     at android.os.Handler.dispatchMessage(Handler.java:99)
64502-18 15:19:56.169  3842  4144 W GmsClient:     at aatx.a(:com.google.android.gms@20104053@20.1.04 (110304-288960190):2)
64602-18 15:19:56.169  3842  4144 W GmsClient:     at aatx.dispatchMessage(:com.google.android.gms@20104053@20.1.04 (110304-288960190):15)
64702-18 15:19:56.169  3842  4144 W GmsClient:     at android.os.Looper.loop(Looper.java:193)
64802-18 15:19:56.169  3842  4144 W GmsClient:     at android.os.HandlerThread.run(HandlerThread.java:65)
64902-18 15:20:01.184  4069  5107 I ChimeraSrvcProxy: NullBinder for com.google.android.mdh.service.START triggering remote TransactionTooLargeException due to Service without Chimera impl
65002-18 15:20:01.211  3842  4144 E JavaBinder: !!! FAILED BINDER TRANSACTION !!!  (parcel size = 916)
65102-18 15:20:01.211  3842  4144 W GmsClient: IGmsServiceBroker.getService failed
65202-18 15:20:01.211  3842  4144 W GmsClient: android.os.DeadObjectException: Transaction failed on small parcel; remote process probably died
65302-18 15:20:01.211  3842  4144 W GmsClient:     at android.os.BinderProxy.transactNative(Native Method)
65402-18 15:20:01.211  3842  4144 W GmsClient:     at android.os.BinderProxy.transact(Binder.java:1127)
65502-18 15:20:01.211  3842  4144 W GmsClient:     at qjl.a(:com.google.android.gms@20104053@20.1.04 (110304-288960190):4)
65602-18 15:20:01.211  3842  4144 W GmsClient:     at qhl.a(:com.google.android.gms@20104053@20.1.04 (110304-288960190):81)
65702-18 15:20:01.211  3842  4144 W GmsClient:     at pug.run(:com.google.android.gms@20104053@20.1.04 (110304-288960190):6)
65802-18 15:20:01.211  3842  4144 W GmsClient:     at android.os.Handler.handleCallback(Handler.java:873)
65902-18 15:20:01.211  3842  4144 W GmsClient:     at android.os.Handler.dispatchMessage(Handler.java:99)
66002-18 15:20:01.211  3842  4144 W GmsClient:     at aatx.a(:com.google.android.gms@20104053@20.1.04 (110304-288960190):2)
66102-18 15:20:01.211  3842  4144 W GmsClient:     at aatx.dispatchMessage(:com.google.android.gms@20104053@20.1.04 (110304-288960190):15)
66202-18 15:20:01.211  3842  4144 W GmsClient:     at android.os.Looper.loop(Looper.java:193)
66302-18 15:20:01.211  3842  4144 W GmsClient:     at android.os.HandlerThread.run(HandlerThread.java:65)
66402-18 15:20:01.239  4069  5107 I ChimeraSrvcProxy: NullBinder for com.google.android.mdh.service.listener.START triggering remote TransactionTooLargeException due to Service without Chimera impl
66502-18 15:20:01.266  3842  4144 E JavaBinder: !!! FAILED BINDER TRANSACTION !!!  (parcel size = 308)
66602-18 15:20:01.266  3842  4144 W GmsClient: IGmsServiceBroker.getService failed
66702-18 15:20:01.266  3842  4144 W GmsClient: android.os.DeadObjectException: Transaction failed on small parcel; remote process probably died
66802-18 15:20:01.266  3842  4144 W GmsClient:     at android.os.BinderProxy.transactNative(Native Method)
66902-18 15:20:01.266  3842  4144 W GmsClient:     at android.os.BinderProxy.transact(Binder.java:1127)
67002-18 15:20:01.266  3842  4144 W GmsClient:     at qjl.a(:com.google.android.gms@20104053@20.1.04 (110304-288960190):4)
67102-18 15:20:01.266  3842  4144 W GmsClient:     at qhl.a(:com.google.android.gms@20104053@20.1.04 (110304-288960190):81)
67202-18 15:20:01.266  3842  4144 W GmsClient:     at pug.run(:com.google.android.gms@20104053@20.1.04 (110304-288960190):6)
67302-18 15:20:01.266  3842  4144 W GmsClient:     at android.os.Handler.handleCallback(Handler.java:873)
67402-18 15:20:01.266  3842  4144 W GmsClient:     at android.os.Handler.dispatchMessage(Handler.java:99)
67502-18 15:20:01.266  3842  4144 W GmsClient:     at aatx.a(:com.google.android.gms@20104053@20.1.04 (110304-288960190):2)
67602-18 15:20:01.266  3842  4144 W GmsClient:     at aatx.dispatchMessage(:com.google.android.gms@20104053@20.1.04 (110304-288960190):15)
67702-18 15:20:01.266  3842  4144 W GmsClient:     at android.os.Looper.loop(Looper.java:193)
67802-18 15:20:01.266  3842  4144 W GmsClient:     at android.os.HandlerThread.run(HandlerThread.java:65)
67902-18 15:20:05.181  3083  3145 W Netd    : No subsystem found in netlink event
68002-18 15:20:06.277  3310  3593 I ActivityManager: Killing 4544:com.android.packageinstaller/u0a11 (adj 906): empty #17
68102-18 15:20:06.280  3310  3328 W libprocessgroup: kill(-4544, 9) failed: No such process
68202-18 15:20:06.313  3084  3084 I Zygote  : Process 4544 exited due to signal (9)
68302-18 15:20:06.326  3310  3328 W libprocessgroup: kill(-4544, 9) failed: No such process
68402-18 15:20:06.326  3310  3328 I libprocessgroup: Successfully killed process cgroup uid 10011 pid 4544 in 45ms
68502-18 15:20:10.107  4370  5200 I Wingbu  :  DateTransUtils-getZeroClockTimestamp()  获取当日00:00:00的时间戳,东八区则为 早上八点 :1581984000000
68602-18 15:20:10.109  4370  5200 I Wingbu  :  DateTransUtils-getZeroClockTimestamp()  获取当日00:00:00的时间戳,东八区则为 早上八点 :1581984000000
68702-18 15:20:13.425  3310  3320 I system_server: Background concurrent copying GC freed 41036(1763KB) AllocSpace objects, 0(0B) LOS objects, 19% free, 7MB/9MB, paused 154us total 119.473ms
68802-18 15:20:15.011  4069  4884 I TelephonySpam: TelephonySpamChimeraService - Running Telephony Spam Chimera Service
68902-18 15:20:15.012  4069  4884 I TelephonySpam: TelephonySpamChimeraService - Cleaning SIP Header local table of old entries
69002-18 15:20:15.014  4069  4884 I TelephonySpam: SpamListSync - Call spam module disabled. Skipping cleaning cache sip header table.
69102-18 15:20:15.014  4069  4884 I TelephonySpam: TelephonySpamChimeraService - Syncing Call Spam List
69202-18 15:20:15.015  4069  4884 I TelephonySpam: SpamListSync - SpamListSyncChimeraService.syncSpamList called with tag: telephonyspam.SpamListSyncOneOffTask, extras: Bundle[{SpamList Type=0, Action=1.0}]
69302-18 15:20:15.015  4069  4884 I TelephonySpam: SpamListSync - Call spam module disabled. Skipping spam list syncing.
69402-18 15:20:15.235  3083  3145 W Netd    : No subsystem found in netlink event
69502-18 15:20:15.557  3842  4148 W Conscrypt: Could not set socket write timeout: java.net.SocketException: Socket closed
69602-18 15:20:15.558  3842  4148 W Conscrypt:     at com.google.android.gms.org.conscrypt.Platform.setSocketWriteTimeout(:com.google.android.gms@20104053@20.1.04 (110304-288960190):2)
69702-18 15:20:15.558  3842  4148 W Conscrypt:     at com.google.android.gms.org.conscrypt.ConscryptFileDescriptorSocket.setSoWriteTimeout(Unknown Source:3)
69802-18 15:20:15.591  3842  4148 W Conscrypt: Could not set socket write timeout: java.net.SocketException: Socket closed
69902-18 15:20:15.591  3842  4148 W Conscrypt:     at com.google.android.gms.org.conscrypt.Platform.setSocketWriteTimeout(:com.google.android.gms@20104053@20.1.04 (110304-288960190):2)
70002-18 15:20:15.591  3842  4148 W Conscrypt:     at com.google.android.gms.org.conscrypt.ConscryptFileDescriptorSocket.setSoWriteTimeout(Unknown Source:3)
70102-18 15:20:15.875  3842  4148 W Conscrypt: Could not set socket write timeout: java.net.SocketException: Socket closed
70202-18 15:20:15.876  3842  4148 W Conscrypt:     at com.google.android.gms.org.conscrypt.Platform.setSocketWriteTimeout(:com.google.android.gms@20104053@20.1.04 (110304-288960190):2)
70302-18 15:20:15.876  3842  4148 W Conscrypt:     at com.google.android.gms.org.conscrypt.ConscryptFileDescriptorSocket.setSoWriteTimeout(Unknown Source:3)
70402-18 15:20:15.907  3842  4148 W Conscrypt: Could not set socket write timeout: java.net.SocketException: Socket closed
70502-18 15:20:15.908  3842  4148 W Conscrypt:     at com.google.android.gms.org.conscrypt.Platform.setSocketWriteTimeout(:com.google.android.gms@20104053@20.1.04 (110304-288960190):2)
70602-18 15:20:15.908  3842  4148 W Conscrypt:     at com.google.android.gms.org.conscrypt.ConscryptFileDescriptorSocket.setSoWriteTimeout(Unknown Source:3)
70702-18 15:20:24.462  3083  3145 W Netd    : No subsystem found in netlink event
70802-18 15:20:24.926  3196  4702 I MessageMonitor: G[4]: [NU-AmNuPlayer] Last Message 15 is ['mckN':0], cost 35 Us
70902-18 15:20:24.938  3196  4704 I MessageMonitor: G[4]: [NU-GenericSource] Last Message 25 is [0x00000009:0], cost 633 Us
71002-18 15:20:25.490  3196  4734 I MessageMonitor: G[4]: [NU-NuPlayerRenderer] Last Message 182 is ['paus':0], cost 19 Us
71102-18 15:20:25.491  3196  4738 I MessageMonitor: G[4]: [NU-NuPlayerDecoder-audN] Last Message 296 is ['rndr':0], cost 20 Us
71202-18 15:20:25.491  3196  4738 I MessageMonitor: [NU-NuPlayerDecoder-audN] DQ pts(1718666), In pts(0) [0/4], Out pts(1728749),Render pts(-1[0]), Drop pts(-1[0]) [0/4]
71302-18 15:20:29.356  3310  3320 I system_server: Background concurrent copying GC freed 44433(1760KB) AllocSpace objects, 0(0B) LOS objects, 19% free, 7MB/9MB, paused 149us total 118.998ms
71402-18 15:20:30.453  3083  3145 W Netd    : No subsystem found in netlink event
71502-18 15:20:38.784  3083  3145 W Netd    : No subsystem found in netlink event
71602-18 15:20:40.057  4370  4444 D AppLog  : storage:41217664-9172-527a-b3d5-edabb50a7d69 : Internal shared storage : mounted
71702-18 15:20:40.057  4370  4444 D AppLog  : getFreeBytes:10 GB
71802-18 15:20:40.057  4370  4444 D AppLog  : getTotalBytes:16 GB
71902-18 15:20:41.957  3197  3245 E storaged: getDiskStats failed with result NOT_SUPPORTED and size 0
72002-18 15:20:45.637  4370  4444 W System.err: org.xmlpull.v1.XmlPullParserException: Unexpected token (position:TEXT Invalid session@1:16 in java.io.BufferedReader@cbd3ea5)
72102-18 15:20:45.639  4370  4444 W System.err:    at org.kxml2.io.KXmlParser.next(KXmlParser.java:432)
72202-18 15:20:45.639  4370  4444 W System.err:    at org.kxml2.io.KXmlParser.nextToken(KXmlParser.java:317)
72302-18 15:20:45.639  4370  4444 W System.err:    at org.kxml2.kdom.Document.parse(Unknown Source:5)
72402-18 15:20:45.639  4370  4444 W System.err:    at x.cpe.admindm.com.TR69Session.doSoapRequest(TR69Session.java:409)
72502-18 15:20:45.639  4370  4444 W System.err:    at x.cpe.admindm.com.TR69Session.doSoapRequest(TR69Session.java:284)
72602-18 15:20:45.639  4370  4444 W System.err:    at x.cpe.admindm.com.TR69Session.run(TR69Session.java:133)
72702-18 15:20:45.639  4370  4444 W System.err:    at x.cpe.admindm.com.Com.run(Com.java:103)
72802-18 15:20:45.639  4370  4444 W System.err:    at java.lang.Thread.run(Thread.java:764)
72902-18 15:20:47.255  3083  3145 W Netd    : No subsystem found in netlink event
73002-18 15:20:47.469  3310  3320 I system_server: Background concurrent copying GC freed 40886(1799KB) AllocSpace objects, 0(0B) LOS objects, 19% free, 7MB/9MB, paused 152us total 120.265ms
73102-18 15:20:52.703  3083  3145 W Netd    : No subsystem found in netlink event
73202-18 15:20:54.927  3196  4702 I MessageMonitor: G[5]: [NU-AmNuPlayer] Last Message 15 is ['mckN':0], cost 35 Us
73302-18 15:20:54.939  3196  4704 I MessageMonitor: G[5]: [NU-GenericSource] Last Message 25 is [0x00000009:0], cost 633 Us
73402-18 15:20:55.490  3196  4734 I MessageMonitor: G[5]: [NU-NuPlayerRenderer] Last Message 182 is ['paus':0], cost 19 Us
73502-18 15:20:55.491  3196  4738 I MessageMonitor: G[5]: [NU-NuPlayerDecoder-audN] Last Message 296 is ['rndr':0], cost 20 Us
73602-18 15:20:55.492  3196  4738 I MessageMonitor: [NU-NuPlayerDecoder-audN] DQ pts(1718666), In pts(0) [0/4], Out pts(1728749),Render pts(-1[0]), Drop pts(-1[0]) [0/4]
73702-18 15:21:01.878  3083  3145 W Netd    : No subsystem found in netlink event
73802-18 15:21:08.066  3310  3320 I system_server: Background concurrent copying GC freed 43906(1815KB) AllocSpace objects, 0(0B) LOS objects, 19% free, 7MB/9MB, paused 208us total 130.583ms
73902-18 15:21:11.224  3083  3145 W Netd    : No subsystem found in netlink event
74002-18 15:21:17.017  3083  3145 W Netd    : No subsystem found in netlink event
74102-18 15:21:24.927  3196  4702 I MessageMonitor: G[6]: [NU-AmNuPlayer] Last Message 15 is ['mckN':0], cost 35 Us
74202-18 15:21:24.939  3196  4704 I MessageMonitor: G[6]: [NU-GenericSource] Last Message 25 is [0x00000009:0], cost 633 Us
74302-18 15:21:25.490  3196  4734 I MessageMonitor: G[6]: [NU-NuPlayerRenderer] Last Message 182 is ['paus':0], cost 19 Us
74402-18 15:21:25.492  3196  4738 I MessageMonitor: G[6]: [NU-NuPlayerDecoder-audN] Last Message 296 is ['rndr':0], cost 20 Us
74502-18 15:21:25.492  3196  4738 I MessageMonitor: [NU-NuPlayerDecoder-audN] DQ pts(1718666), In pts(0) [0/4], Out pts(1728749),Render pts(-1[0]), Drop pts(-1[0]) [0/4]
74602-18 15:21:25.603  3083  3145 W Netd    : No subsystem found in netlink event
74702-18 15:21:28.358  3310  3320 I system_server: Background concurrent copying GC freed 44184(1752KB) AllocSpace objects, 0(0B) LOS objects, 19% free, 7MB/9MB, paused 195us total 137.497ms
74802-18 15:21:34.608  3083  3145 W Netd    : No subsystem found in netlink event
74902-18 15:21:40.296  3083  3145 W Netd    : No subsystem found in netlink event
75002-18 15:21:41.958  3197  3245 E storaged: getDiskStats failed with result NOT_SUPPORTED and size 0
75102-18 15:21:48.325  3310  3320 I system_server: Background concurrent copying GC freed 44338(1769KB) AllocSpace objects, 0(0B) LOS objects, 19% free, 7MB/9MB, paused 198us total 134.869ms
75202-18 15:21:49.475  3083  3145 W Netd    : No subsystem found in netlink event
75302-18 15:21:54.927  3196  4702 I MessageMonitor: G[7]: [NU-AmNuPlayer] Last Message 15 is ['mckN':0], cost 35 Us
75402-18 15:21:54.939  3196  4704 I MessageMonitor: G[7]: [NU-GenericSource] Last Message 25 is [0x00000009:0], cost 633 Us
75502-18 15:21:55.490  3196  4734 I MessageMonitor: G[7]: [NU-NuPlayerRenderer] Last Message 182 is ['paus':0], cost 19 Us
75602-18 15:21:55.492  3196  4738 I MessageMonitor: G[7]: [NU-NuPlayerDecoder-audN] Last Message 296 is ['rndr':0], cost 20 Us
75702-18 15:21:55.492  3196  4738 I MessageMonitor: [NU-NuPlayerDecoder-audN] DQ pts(1718666), In pts(0) [0/4], Out pts(1728749),Render pts(-1[0]), Drop pts(-1[0]) [0/4]
75802-18 15:21:55.846  3083  3145 W Netd    : No subsystem found in netlink event
75902-18 15:22:02.440  3083  3145 W Netd    : No subsystem found in netlink event
76002-18 15:22:08.349  3310  3320 I system_server: Background concurrent copying GC freed 43475(1766KB) AllocSpace objects, 0(0B) LOS objects, 19% free, 7MB/9MB, paused 166us total 142.575ms
76102-18 15:22:11.100  3083  3145 W Netd    : No subsystem found in netlink event
76202-18 15:22:16.399  3083  3145 W Netd    : No subsystem found in netlink event
76302-18 15:22:24.927  3196  4702 I MessageMonitor: G[8]: [NU-AmNuPlayer] Last Message 15 is ['mckN':0], cost 35 Us
76402-18 15:22:24.939  3196  4704 I MessageMonitor: G[8]: [NU-GenericSource] Last Message 25 is [0x00000009:0], cost 633 Us
76502-18 15:22:25.490  3196  4734 I MessageMonitor: G[8]: [NU-NuPlayerRenderer] Last Message 182 is ['paus':0], cost 19 Us
76602-18 15:22:25.492  3196  4738 I MessageMonitor: G[8]: [NU-NuPlayerDecoder-audN] Last Message 296 is ['rndr':0], cost 20 Us
76702-18 15:22:25.492  3196  4738 I MessageMonitor: [NU-NuPlayerDecoder-audN] DQ pts(1718666), In pts(0) [0/4], Out pts(1728749),Render pts(-1[0]), Drop pts(-1[0]) [0/4]
76802-18 15:22:25.643  3083  3145 W Netd    : No subsystem found in netlink event
76902-18 15:22:28.844  3310  3320 I system_server: Background concurrent copying GC freed 44971(1785KB) AllocSpace objects, 0(0B) LOS objects, 19% free, 7MB/9MB, paused 169us total 125.080ms
77002-18 15:22:33.556  3083  3145 W Netd    : No subsystem found in netlink event
77102-18 15:22:40.035  4370  4444 D AppLog  : storage:41217664-9172-527a-b3d5-edabb50a7d69 : Internal shared storage : mounted
77202-18 15:22:40.035  4370  4444 D AppLog  : getFreeBytes:10 GB
77302-18 15:22:40.035  4370  4444 D AppLog  : getTotalBytes:16 GB
77402-18 15:22:41.959  3197  3245 E storaged: getDiskStats failed with result NOT_SUPPORTED and size 0
77502-18 15:22:42.883  3083  3145 W Netd    : No subsystem found in netlink event
77602-18 15:22:44.637  4600  4632 I PlayCommon: [155] akew.c(29): Preparing logs for uploading
77702-18 15:22:44.640  4600  4632 I PlayCommon: [155] akew.c(133): Connecting to server for timestamp: https://play.googleapis.com/play/log/timestamp
77802-18 15:22:44.726  4600  4632 W PlayCommon: [155] akew.a(1): No account for auth token provided
77902-18 15:22:44.727  4600  4632 I PlayCommon: [155] akew.c(164): Connecting to server: https://play.googleapis.com/play/log?format=raw&proto_v2=true
78002-18 15:22:44.814  4600  4632 I PlayCommon: [155] akew.c(194): Successfully uploaded logs.
78102-18 15:22:46.656  3105  3105 I memtrack@1.0-se: type=1400 audit(0.0:150): avc: denied { read } for name="mem_profile" dev="debugfs" ino=18899 scontext=u:r:hal_memtrack_default:s0 tcontext=u:object_r:debugfs:s0 tclass=file permissive=1
78202-18 15:22:46.656  3105  3105 I memtrack@1.0-se: type=1400 audit(0.0:151): avc: denied { open } for path="/sys/kernel/debug/mali0/ctx/3454_3/mem_profile" dev="debugfs" ino=18899 scontext=u:r:hal_memtrack_default:s0 tcontext=u:object_r:debugfs:s0 tclass=file permissive=1
78302-18 15:22:46.656  3105  3105 I memtrack@1.0-se: type=1400 audit(0.0:152): avc: denied { getattr } for path="/sys/kernel/debug/mali0/ctx/3454_3/mem_profile" dev="debugfs" ino=18899 scontext=u:r:hal_memtrack_default:s0 tcontext=u:object_r:debugfs:s0 tclass=file permissive=1
78402-18 15:22:49.753  3310  3320 I system_server: Background concurrent copying GC freed 43880(1772KB) AllocSpace objects, 0(0B) LOS objects, 19% free, 7MB/9MB, paused 194us total 140.150ms
78502-18 15:22:52.109  3083  3145 W Netd    : No subsystem found in netlink event
78602-18 15:22:54.927  3196  4702 I MessageMonitor: G[9]: [NU-AmNuPlayer] Last Message 15 is ['mckN':0], cost 35 Us
78702-18 15:22:54.939  3196  4704 I MessageMonitor: G[9]: [NU-GenericSource] Last Message 25 is [0x00000009:0], cost 633 Us
78802-18 15:22:55.491  3196  4734 I MessageMonitor: G[9]: [NU-NuPlayerRenderer] Last Message 182 is ['paus':0], cost 19 Us
78902-18 15:22:55.492  3196  4738 I MessageMonitor: G[9]: [NU-NuPlayerDecoder-audN] Last Message 296 is ['rndr':0], cost 20 Us
79002-18 15:22:55.492  3196  4738 I MessageMonitor: [NU-NuPlayerDecoder-audN] DQ pts(1718666), In pts(0) [0/4], Out pts(1728749),Render pts(-1[0]), Drop pts(-1[0]) [0/4]
79102-18 15:22:57.182  3083  3145 W Netd    : No subsystem found in netlink event
79202-18 15:23:04.995  3083  3145 W Netd    : No subsystem found in netlink event
79302-18 15:23:06.349  3083  3805 W DnsTlsSocket: SSL_connect error 5, errno=110
79402-18 15:23:10.083  3310  3320 I system_server: Background concurrent copying GC freed 43077(1768KB) AllocSpace objects, 0(0B) LOS objects, 19% free, 7MB/9MB, paused 150us total 131.653ms
79502-18 15:23:10.267  4370  5223 I Wingbu  :  DateTransUtils-getZeroClockTimestamp()  获取当日00:00:00的时间戳,东八区则为 早上八点 :1581984000000
79602-18 15:23:10.269  4370  5223 I Wingbu  :  DateTransUtils-getZeroClockTimestamp()  获取当日00:00:00的时间戳,东八区则为 早上八点 :1581984000000
79702-18 15:23:10.358  3083  3145 W Netd    : No subsystem found in netlink event
79802-18 15:23:13.512  4025  4025 I Thread-3: type=1400 audit(0.0:153): avc: denied { open } for path="/proc/stat" dev="proc" ino=4026531973 scontext=u:r:untrusted_app_27:s0:c512,c768 tcontext=u:object_r:proc_stat:s0 tclass=file permissive=1
79902-18 15:23:16.156  3893  3893 D RemoteIME: keycode: 22, realAction: false
80002-18 15:23:16.160  4025  4107 V Kodi    : CAndroidKey: key down (dev:4; src:769; code: 22; repeat: 0; flags: 0x8; alt: no; shift: no; sym: no)
80102-18 15:23:16.182  4025  4210 V Kodi    : Debug Print: Trying to open: samplerate: 44100, channelMask: 12, encoding: 4
80202-18 15:23:16.187  3130  4507 D AudioFlinger: Client defaulted notificationFrames to 1258 for frameCount 3776
80302-18 15:23:16.193  4025  4210 V Kodi    : Debug Print: CAESinkAUDIOTRACK::Initializing with: m_sampleRate: 44100 format: AE_FMT_FLOAT (AE) method: PCM stream-type: PCM-STREAM min_buffer_size: 30208 m_frames: 1888 m_frameSize: 8 channels: 2
80402-18 15:23:16.199  3427  3885 E bt_btif : register_notification_rsp: Avrcp device is not connected, handle: 0x0
80502-18 15:23:16.199  3427  3885 I chatty  : uid=1002(bluetooth) BluetoothAvrcpH identical 4 lines
80602-18 15:23:16.199  3427  3885 E bt_btif : register_notification_rsp: Avrcp device is not connected, handle: 0x0
80702-18 15:23:16.201  3090  4221 I audio-subMixingFactory: ++usecase_change_validate_l_sm: dev usecase masks = 0, out usecase_masks = 0, out usecase STREAM_PCM_NORMAL
80802-18 15:23:16.201  3090  4221 I audio-subMixingFactory: ++++usecase_change_validate_l_sm(),continuous_audio_mode 0 dev usecase masks = 0, out usecase_masks = 0, out usecase STREAM_PCM_NORMAL
80902-18 15:23:16.201  3090  4221 I audio-subMixingFactory: usecase_change_validate_l_sm(), mixer_aux_buffer_write_sm !
81002-18 15:23:16.201  3090  4221 I audio-subMixingFactory: ----usecase_change_validate_l_sm(), continuous_audio_mode 0 dev usecase masks = 0x1, out usecase_masks = 0x1, out usecase STREAM_PCM_NORMAL
81102-18 15:23:16.201  3090  4221 I aml_audio_port: get_input_port_index(), samplerate 48000
81202-18 15:23:16.201  3090  4221 D aml_audio_port: new_input_port(), index:0, rbuf size:8192, direct_on:0
81302-18 15:23:16.201  3090  4221 D aml_audio_port: new_input_port(), fmt 0x1, rate 48000
81402-18 15:23:16.201  3090  4221 I amlaudioMixer: ++init_mixer_input_port port index 0, size 512 frames
81502-18 15:23:16.201  3090  4221 I amlaudioMixer: init_mixer_input_port(), port->initial_frames: 3254272
81602-18 15:23:16.201  3090  4221 I aml_audio_port: get_input_port_index(), samplerate 48000
81702-18 15:23:16.201  3090  4221 I audio-subMixingFactory: mixer_aux_buffer_write_sm(), primary 0xa1ed8000 port index = 0
81802-18 15:23:16.201  3090  4221 I audio-subMixingFactory: padding_bytes 16384
81902-18 15:23:16.201  3090  4221 I amlaudioMixer: port index 0 is active now
82002-18 15:23:16.201  3090  4221 I audio-subMixingFactory: padding_bytes 14336
82102-18 15:23:16.201  3090  4221 I audio-subMixingFactory: padding_bytes 12288
82202-18 15:23:16.201  3090  4221 I audio-subMixingFactory: padding_bytes 10240
82302-18 15:23:16.201  3090  4221 I audio-subMixingFactory: padding_bytes 8192
82402-18 15:23:16.202  3090  4221 I audio-subMixingFactory: padding_bytes 6144
82502-18 15:23:16.206  3090  4221 I audio-subMixingFactory: padding_bytes 4096
82602-18 15:23:16.206  3090  4221 I audio-subMixingFactory: padding_bytes 2048
82702-18 15:23:16.216  3090  4221 W amlaudioMixer: mixer_get_presentation_position(), not valid now
82802-18 15:23:16.216  3090  4221 W audio-subMixingFactory: out_get_presentation_position_port(), pts not valid yet
82902-18 15:23:16.226  3090  4221 W amlaudioMixer: mixer_get_presentation_position(), not valid now
83002-18 15:23:16.226  3090  4221 W audio-subMixingFactory: out_get_presentation_position_port(), pts not valid yet
83102-18 15:23:16.241  3130  3293 D AudioFlinger: mixer(0xa3c03fc0) throttle end: throttle time(2)
83202-18 15:23:16.270  3893  3893 D RemoteIME: keycode: 22, realAction: true
83302-18 15:23:16.270  4025  4107 V Kodi    : CAndroidKey: key up (dev:4; src:769; code: 22; repeat: 0; flags: 0x8; alt: no; shift: no; sym: no)
83402-18 15:23:16.374  3893  3893 D RemoteIME: keycode: 22, realAction: false
83502-18 15:23:16.374  4025  4107 V Kodi    : CAndroidKey: key down (dev:4; src:769; code: 22; repeat: 0; flags: 0x8; alt: no; shift: no; sym: no)
83602-18 15:23:16.453  3893  3893 D RemoteIME: keycode: 22, realAction: true
83702-18 15:23:16.455  4025  4107 V Kodi    : CAndroidKey: key up (dev:4; src:769; code: 22; repeat: 0; flags: 0x8; alt: no; shift: no; sym: no)
83802-18 15:23:16.557  3893  3893 D RemoteIME: keycode: 22, realAction: false
83902-18 15:23:16.558  4025  4107 V Kodi    : CAndroidKey: key down (dev:4; src:769; code: 22; repeat: 0; flags: 0x8; alt: no; shift: no; sym: no)
84002-18 15:23:16.637  3893  3893 D RemoteIME: keycode: 22, realAction: true
84102-18 15:23:16.638  4025  4107 V Kodi    : CAndroidKey: key up (dev:4; src:769; code: 22; repeat: 0; flags: 0x8; alt: no; shift: no; sym: no)
84202-18 15:23:16.757  3893  3893 D RemoteIME: keycode: 22, realAction: false
84302-18 15:23:16.758  4025  4107 V Kodi    : CAndroidKey: key down (dev:4; src:769; code: 22; repeat: 0; flags: 0x8; alt: no; shift: no; sym: no)
84402-18 15:23:16.821  3893  3893 D RemoteIME: keycode: 22, realAction: true
84502-18 15:23:16.822  4025  4107 V Kodi    : CAndroidKey: key up (dev:4; src:769; code: 22; repeat: 0; flags: 0x8; alt: no; shift: no; sym: no)
84602-18 15:23:17.071  3893  3893 D RemoteIME: keycode: 22, realAction: false
84702-18 15:23:17.074  4025  4107 V Kodi    : CAndroidKey: key down (dev:4; src:769; code: 22; repeat: 0; flags: 0x8; alt: no; shift: no; sym: no)
84802-18 15:23:17.174  3893  3893 D RemoteIME: keycode: 22, realAction: true
84902-18 15:23:17.175  4025  4107 V Kodi    : CAndroidKey: key up (dev:4; src:769; code: 22; repeat: 0; flags: 0x8; alt: no; shift: no; sym: no)
85002-18 15:23:17.277  3893  3893 D RemoteIME: keycode: 22, realAction: false
85102-18 15:23:17.278  4025  4107 V Kodi    : CAndroidKey: key down (dev:4; src:769; code: 22; repeat: 0; flags: 0x8; alt: no; shift: no; sym: no)
85202-18 15:23:17.364  3893  3893 D RemoteIME: keycode: 22, realAction: true
85302-18 15:23:17.365  4025  4107 V Kodi    : CAndroidKey: key up (dev:4; src:769; code: 22; repeat: 0; flags: 0x8; alt: no; shift: no; sym: no)
85402-18 15:23:17.781  3893  3893 D RemoteIME: keycode: 22, realAction: false
85502-18 15:23:17.782  4025  4107 V Kodi    : CAndroidKey: key down (dev:4; src:769; code: 22; repeat: 0; flags: 0x8; alt: no; shift: no; sym: no)
85602-18 15:23:17.869  3893  3893 D RemoteIME: keycode: 22, realAction: true
85702-18 15:23:17.874  4025  4107 V Kodi    : CAndroidKey: key up (dev:4; src:769; code: 22; repeat: 0; flags: 0x8; alt: no; shift: no; sym: no)
85802-18 15:23:18.349  3893  3893 D RemoteIME: keycode: 66, realAction: false
85902-18 15:23:18.350  4025  4107 V Kodi    : CAndroidKey: key down (dev:4; src:769; code: 66; repeat: 0; flags: 0x8; alt: no; shift: no; sym: no)
86002-18 15:23:18.472  3893  3893 D RemoteIME: keycode: 66, realAction: true
86102-18 15:23:18.473  4025  4107 V Kodi    : CAndroidKey: key up (dev:4; src:769; code: 66; repeat: 0; flags: 0x8; alt: no; shift: no; sym: no)
86202-18 15:23:18.839  3893  3893 D RemoteIME: keycode: 20, realAction: false
86302-18 15:23:18.839  4025  4107 V Kodi    : CAndroidKey: key down (dev:4; src:769; code: 20; repeat: 0; flags: 0x8; alt: no; shift: no; sym: no)
86402-18 15:23:18.957  3893  3893 D RemoteIME: keycode: 20, realAction: true
86502-18 15:23:18.957  4025  4107 V Kodi    : CAndroidKey: key up (dev:4; src:769; code: 20; repeat: 0; flags: 0x8; alt: no; shift: no; sym: no)
86602-18 15:23:19.117  3893  3893 D RemoteIME: keycode: 20, realAction: false
86702-18 15:23:19.118  4025  4107 V Kodi    : CAndroidKey: key down (dev:4; src:769; code: 20; repeat: 0; flags: 0x8; alt: no; shift: no; sym: no)
86802-18 15:23:19.214  3083  3145 W Netd    : No subsystem found in netlink event
86902-18 15:23:19.272  3893  3893 D RemoteIME: keycode: 20, realAction: true
87002-18 15:23:19.273  4025  4107 V Kodi    : CAndroidKey: key up (dev:4; src:769; code: 20; repeat: 0; flags: 0x8; alt: no; shift: no; sym: no)
87102-18 15:23:19.678  3893  3893 D RemoteIME: keycode: 19, realAction: false
87202-18 15:23:19.679  4025  4107 V Kodi    : CAndroidKey: key down (dev:4; src:769; code: 19; repeat: 0; flags: 0x8; alt: no; shift: no; sym: no)
87302-18 15:23:19.790  3893  3893 D RemoteIME: keycode: 19, realAction: true
87402-18 15:23:19.791  4025  4107 V Kodi    : CAndroidKey: key up (dev:4; src:769; code: 19; repeat: 0; flags: 0x8; alt: no; shift: no; sym: no)
87502-18 15:23:20.213  3893  3893 D RemoteIME: keycode: 20, realAction: false
87602-18 15:23:20.214  4025  4107 V Kodi    : CAndroidKey: key down (dev:4; src:769; code: 20; repeat: 0; flags: 0x8; alt: no; shift: no; sym: no)
87702-18 15:23:20.317  3893  3893 D RemoteIME: keycode: 20, realAction: true
87802-18 15:23:20.318  4025  4107 V Kodi    : CAndroidKey: key up (dev:4; src:769; code: 20; repeat: 0; flags: 0x8; alt: no; shift: no; sym: no)
87902-18 15:23:20.396  3893  3893 D RemoteIME: keycode: 20, realAction: false
88002-18 15:23:20.398  4025  4107 V Kodi    : CAndroidKey: key down (dev:4; src:769; code: 20; repeat: 0; flags: 0x8; alt: no; shift: no; sym: no)
88102-18 15:23:20.518  3893  3893 D RemoteIME: keycode: 20, realAction: true
88202-18 15:23:20.518  4025  4107 V Kodi    : CAndroidKey: key up (dev:4; src:769; code: 20; repeat: 0; flags: 0x8; alt: no; shift: no; sym: no)
88302-18 15:23:20.726  3893  3893 D RemoteIME: keycode: 20, realAction: false
88402-18 15:23:20.728  4025  4107 V Kodi    : CAndroidKey: key down (dev:4; src:769; code: 20; repeat: 0; flags: 0x8; alt: no; shift: no; sym: no)
88502-18 15:23:20.862  3893  3893 D RemoteIME: keycode: 20, realAction: true
88602-18 15:23:20.863  4025  4107 V Kodi    : CAndroidKey: key up (dev:4; src:769; code: 20; repeat: 0; flags: 0x8; alt: no; shift: no; sym: no)
88702-18 15:23:20.957  3893  3893 D RemoteIME: keycode: 20, realAction: false
88802-18 15:23:20.958  4025  4107 V Kodi    : CAndroidKey: key down (dev:4; src:769; code: 20; repeat: 0; flags: 0x8; alt: no; shift: no; sym: no)
88902-18 15:23:21.055  3893  3893 D RemoteIME: keycode: 20, realAction: true
89002-18 15:23:21.056  4025  4107 V Kodi    : CAndroidKey: key up (dev:4; src:769; code: 20; repeat: 0; flags: 0x8; alt: no; shift: no; sym: no)
89102-18 15:23:21.935  3893  3893 D RemoteIME: keycode: 66, realAction: false
89202-18 15:23:21.936  4025  4107 V Kodi    : CAndroidKey: key down (dev:4; src:769; code: 66; repeat: 0; flags: 0x8; alt: no; shift: no; sym: no)
89302-18 15:23:22.054  3893  3893 D RemoteIME: keycode: 66, realAction: true
89402-18 15:23:22.055  4025  4107 V Kodi    : CAndroidKey: key up (dev:4; src:769; code: 66; repeat: 0; flags: 0x8; alt: no; shift: no; sym: no)
89502-18 15:23:22.177  4025  5237 V Kodi    : Debug Print: CPythonInvoker(5): Script invoked without an addon. Adding all addon modules installed to python path as fallback. This behaviour will be removed in future version.
89602-18 15:23:22.290  4025  4108 D Kodi    : Starting: Intent { cmp=com.android.tv.settings/.accessories.AddAccessoryActivity }
89702-18 15:23:22.293  3310  3948 I ActivityManager: START u0 {flg=0x10000000 cmp=com.android.tv.settings/.accessories.AddAccessoryActivity} from uid 10054
89802-18 15:23:22.310  4025  4025 V threaded_app: Pause: 0xabecfb00
89902-18 15:23:22.310  4025  4107 V threaded_app: activityState=13
90002-18 15:23:22.310  4025  4107 V Kodi    : virtual void CXBMCApp::onPause():
90102-18 15:23:22.310  4025  4107 V Kodi    : static bool CXBMCApp::EnableWakeLock(bool): false
90202-18 15:23:22.315  3553  3553 W ActivityThread: handleWindowVisibility: no activity for token android.os.BinderProxy@538fc7
90302-18 15:23:22.327  4025  4025 V threaded_app: WindowFocusChanged: 0xabecfb00 -- 0
90402-18 15:23:22.327  4025  4107 V Kodi    : virtual void CXBMCApp::onLostFocus():
90502-18 15:23:22.343  3100  3229 I [Gralloc]: framebuffer hal alread move to hwcomposer
90602-18 15:23:22.382  3132  4021 D PermissionCache: checking android.permission.READ_FRAME_BUFFER for uid=1000 => granted (255 us)
90702-18 15:23:22.382  3100  3229 I [Gralloc]: framebuffer hal alread move to hwcomposer
90802-18 15:23:22.428  3553  3553 D BluetoothDevicePairer: Adding A2dp device criteria for pairing
90902-18 15:23:22.437  3427  3441 D BluetoothAdapterService: startDiscovery
91002-18 15:23:22.439  3427  3875 W bt_btif : bta_dm_check_av:0
91102-18 15:23:22.440  3553  3553 D BluetoothScanner: startListening
91202-18 15:23:22.467  3553  3611 I ConfigStore: android::hardware::configstore::V1_0::ISurfaceFlingerConfigs::hasWideColorDisplay retrieved: 0
91302-18 15:23:22.467  3553  3611 I ConfigStore: android::hardware::configstore::V1_0::ISurfaceFlingerConfigs::hasHDRDisplay retrieved: 0
91402-18 15:23:22.467  3553  3611 I OpenGLRenderer: Initialized EGL, version 1.4
91502-18 15:23:22.467  3553  3611 D OpenGLRenderer: Swap behavior 2
91602-18 15:23:22.483  3553  3611 D mali_winsys: EGLint new_window_surface(egl_winsys_display *, void *, EGLSurface, EGLConfig, egl_winsys_surface **, EGLBoolean) returns 0x3000
91702-18 15:23:22.483  3100  3229 I [Gralloc]: framebuffer hal alread move to hwcomposer
91802-18 15:23:22.496  3310  3384 I InputReader: Reconfiguring input devices.  changes=0x00000004
91902-18 15:23:22.500  3100  3100 I [Gralloc]: framebuffer hal alread move to hwcomposer
92002-18 15:23:22.500  4025  4025 V Kodi    : Input device changed: 2
92102-18 15:23:22.508  3100  3229 I [Gralloc]: framebuffer hal alread move to hwcomposer
92202-18 15:23:22.536  4025  4025 V Kodi    : Debug Print: CPeripheralBusAndroid: failed to update input device with ID 2 because it couldn't be found
92302-18 15:23:22.545  4025  4025 V Kodi    : Input device changed: 5
92402-18 15:23:22.545  4025  4025 V Kodi    : Debug Print: CPeripheralBusAndroid: failed to update input device with ID 5 because it couldn't be found
92502-18 15:23:22.582  3310  3333 I ActivityManager: Displayed com.android.tv.settings/.accessories.AddAccessoryActivity: +257ms
92602-18 15:23:22.585  4025  4025 V threaded_app: SaveInstanceState: 0xabecfb00
92702-18 15:23:22.585  4025  4107 V Kodi    : virtual void CXBMCApp::onSaveState(void **, size_t *):
92802-18 15:23:22.585  4025  4107 V threaded_app: APP_CMD_SAVE_STATE
92902-18 15:23:22.589  4025  4025 D XBMCMainView: Surface Destroyed
93002-18 15:23:22.589  4025  4025 V Kodi    : virtual void CXBMCApp::surfaceDestroyed(CJNISurfaceHolder):
93102-18 15:23:22.589  4025  4025 V Kodi    : XBMC_DestroyDisplay()
93202-18 15:23:22.616  4025  4109 V Kodi    : Debug Print: CWinSystemAndroid::DestroyWindow
93302-18 15:23:22.616  4025  4025 W org.xbmc.kodi: Attempt to remove non-JNI local reference, dumping thread
93402-18 15:23:22.618  4025  4025 V threaded_app: Stop: 0xabecfb00
93502-18 15:23:22.618  4025  4107 V threaded_app: activityState=14
93602-18 15:23:22.618  4025  4107 V Kodi    : virtual void CXBMCApp::onStop():
93702-18 15:23:22.624  3132  3165 E BufferQueueProducer: [org.xbmc.kodi/org.xbmc.kodi.Main#0] disconnect: not connected (req=1)
93802-18 15:23:22.624  4025  4110 W libEGL  : EGLNativeWindowType 0x93670008 disconnect failed
93902-18 15:23:22.708  3132  4021 W SurfaceFlinger: Attempting to set client state on removed layer: Splash Screen com.android.tv.settings#0
94002-18 15:23:22.709  3132  4021 W SurfaceFlinger: Attempting to destroy on removed layer: Splash Screen com.android.tv.settings#0
94102-18 15:23:22.740  3310  5244 I ConfigStore: android::hardware::configstore::V1_0::ISurfaceFlingerConfigs::hasWideColorDisplay retrieved: 0
94202-18 15:23:22.741  3310  5244 I ConfigStore: android::hardware::configstore::V1_0::ISurfaceFlingerConfigs::hasHDRDisplay retrieved: 0
94302-18 15:23:22.741  3310  5244 I OpenGLRenderer: Initialized EGL, version 1.4
94402-18 15:23:22.741  3310  5244 D OpenGLRenderer: Swap behavior 2
94502-18 15:23:22.842  3310  3320 I system_server: Background concurrent copying GC freed 30898(1567KB) AllocSpace objects, 4(80KB) LOS objects, 19% free, 7MB/9MB, paused 1.201ms total 119.249ms
94602-18 15:23:24.440  3427  3566 E bt_hci  : command_timed_out
94702-18 15:23:24.440  3427  3566 E bt_hci  : command_timed_out_log_info: 1 commands pending response
94802-18 15:23:24.440  3427  3566 E bt_hci  : command_timed_out_log_info: Waited 2002 ms for a response to opcode: 0xfd57 *matches timer*
94902-18 15:23:24.440  3427  3566 E bt_hci  : command_timed_out_log_info: Size 6 Hex 57 fd 03 01
95002-18 15:23:24.440  3427  3566 E bt_hci  : command_timed_out: requesting a firmware dump.
95102-18 15:23:24.441  3427  3566 E bt_hci  : command_timed_out: Setting a timer to restart.
95202-18 15:23:24.929  3196  4702 I MessageMonitor: G[10]: [NU-AmNuPlayer] Last Message 15 is ['mckN':0], cost 35 Us
95302-18 15:23:24.940  3196  4704 I MessageMonitor: G[10]: [NU-GenericSource] Last Message 25 is [0x00000009:0], cost 633 Us
95402-18 15:23:25.166  3083  3145 W Netd    : No subsystem found in netlink event
95502-18 15:23:25.491  3196  4734 I MessageMonitor: G[10]: [NU-NuPlayerRenderer] Last Message 182 is ['paus':0], cost 19 Us
95602-18 15:23:25.493  3196  4738 I MessageMonitor: G[10]: [NU-NuPlayerDecoder-audN] Last Message 296 is ['rndr':0], cost 20 Us
95702-18 15:23:25.493  3196  4738 I MessageMonitor: [NU-NuPlayerDecoder-audN] DQ pts(1718666), In pts(0) [0/4], Out pts(1728749),Render pts(-1[0]), Drop pts(-1[0]) [0/4]
95802-18 15:23:29.440  3427  3566 E bt_hci  : hci_timeout_abort restarting the Bluetooth process.
959--------- beginning of crash
96002-18 15:23:29.441  3427  3566 F libc    : Fatal signal 6 (SIGABRT), code -6 (SI_TKILL) in tid 3566 (alarm_default_c), pid 3427 (droid.bluetooth)
96102-18 15:23:29.544  5259  5259 I crash_dump32: obtaining output fd from tombstoned, type: kDebuggerdTombstone
96202-18 15:23:29.545  3208  3208 I /system/bin/tombstoned: received crash request for pid 3566
96302-18 15:23:29.546  5259  5259 I crash_dump32: performing dump of process 3427 (target tid = 3566)
96402-18 15:23:29.571  5259  5259 F DEBUG   : *** *** *** *** *** *** *** *** *** *** *** *** *** *** *** ***
96502-18 15:23:29.571  5259  5259 F DEBUG   : Build fingerprint: 'Amlogic/franklin/franklin:9/PPR1.180610.011/V2.20200218.084033:userdebug/test-keys'
96602-18 15:23:29.571  5259  5259 F DEBUG   : Revision: '0'
96702-18 15:23:29.571  5259  5259 F DEBUG   : ABI: 'arm'
96802-18 15:23:29.571  5259  5259 F DEBUG   : pid: 3427, tid: 3566, name: alarm_default_c  >>> com.android.bluetooth <<<
96902-18 15:23:29.571  5259  5259 F DEBUG   : signal 6 (SIGABRT), code -6 (SI_TKILL), fault addr --------
97002-18 15:23:29.571  5259  5259 F DEBUG   :     r0  00000000  r1  00000dee  r2  00000006  r3  aee16918
97102-18 15:23:29.571  5259  5259 F DEBUG   :     r4  00000d63  r5  00000dee  r6  92b93224  r7  0000010c
97202-18 15:23:29.571  5259  5259 F DEBUG   :     r8  930c73dd  r9  00000000  r10 a4a1fe70  r11 00000000
97302-18 15:23:29.572  5259  5259 F DEBUG   :     ip  00000041  sp  92b93210  lr  aeda50e5  pc  aed9ce72
97402-18 15:23:29.621  5259  5259 F DEBUG   :
97502-18 15:23:29.621  5259  5259 F DEBUG   : backtrace:
97602-18 15:23:29.621  5259  5259 F DEBUG   :     #00 pc 0001ce72  /system/lib/libc.so (abort+62)
97702-18 15:23:29.621  5259  5259 F DEBUG   :     #01 pc 0010e3fb  /system/lib/libbluetooth.so (hci_timeout_abort(void*)+30)
97802-18 15:23:29.621  5259  5259 F DEBUG   :     #02 pc 001ab9bd  /system/lib/libbluetooth.so (alarm_ready_generic(alarm_t*, std::__1::unique_lock<std::__1::mutex>&)+164)
97902-18 15:23:29.621  5259  5259 F DEBUG   :     #03 pc 001ab8db  /system/lib/libbluetooth.so (alarm_queue_ready(fixed_queue_t*, void*)+66)
98002-18 15:23:29.621  5259  5259 F DEBUG   :     #04 pc 001ae4b5  /system/lib/libbluetooth.so (internal_dequeue_ready(void*)+48)
98102-18 15:23:29.621  5259  5259 F DEBUG   :     #05 pc 001b06c3  /system/lib/libbluetooth.so (run_reactor(reactor_t*, int)+218)
98202-18 15:23:29.622  5259  5259 F DEBUG   :     #06 pc 001b05bd  /system/lib/libbluetooth.so (reactor_start(reactor_t*)+44)
98302-18 15:23:29.622  5259  5259 F DEBUG   :     #07 pc 001b1757  /system/lib/libbluetooth.so (run_thread(void*)+142)
98402-18 15:23:29.622  5259  5259 F DEBUG   :     #08 pc 00063c15  /system/lib/libc.so (__pthread_start(void*)+22)
98502-18 15:23:29.622  5259  5259 F DEBUG   :     #09 pc 0001e065  /system/lib/libc.so (__start_thread+22)
98602-18 15:23:29.644  5259  5259 I crash_dump32: type=1400 audit(0.0:155): avc: denied { read } for name="btopp.db-shm" dev="mmcblk0p20" ino=519540 scontext=u:r:crash_dump:s0 tcontext=u:object_r:bluetooth_data_file:s0 tclass=file permissive=1
98702-18 15:23:30.351  3208  3208 E /system/bin/tombstoned: Tombstone written to: /data/tombstones/tombstone_01
98802-18 15:23:30.369  3310  3330 I BootReceiver: Copying /data/tombstones/tombstone_01 to DropBox (SYSTEM_TOMBSTONE)
98902-18 15:23:30.373  3310  3325 W BroadcastQueue: Background execution not allowed: receiving Intent { act=android.intent.action.DROPBOX_ENTRY_ADDED flg=0x10 (has extras) } to com.google.android.gms/.stats.service.DropBoxEntryAddedReceiver
99002-18 15:23:30.373  3310  3325 W BroadcastQueue: Background execution not allowed: receiving Intent { act=android.intent.action.DROPBOX_ENTRY_ADDED flg=0x10 (has extras) } to com.google.android.gms/.chimera.GmsIntentOperationService$PersistentTrustedReceiver
99102-18 15:23:30.403  3310  3325 W BroadcastQueue: Background execution not allowed: receiving Intent { act=android.intent.action.DROPBOX_ENTRY_ADDED flg=0x10 (has extras) } to com.google.android.gms/.stats.service.DropBoxEntryAddedReceiver
99202-18 15:23:30.403  3310  3325 W BroadcastQueue: Background execution not allowed: receiving Intent { act=android.intent.action.DROPBOX_ENTRY_ADDED flg=0x10 (has extras) } to com.google.android.gms/.chimera.GmsIntentOperationService$PersistentTrustedReceiver
99302-18 15:23:30.455  3091  3091 E android.hardware.bluetooth@1.0-impl: BluetoothDeathRecipient::serviceDied - Bluetooth service died
99402-18 15:23:30.455  3091  3091 I android.hardware.bluetooth@1.0-impl: BluetoothHci::close()
99502-18 15:23:30.455  3091  3091 I android.hardware.bluetooth@1.0-impl: Skipping unlink call, service died.
99602-18 15:23:30.455  3091  3091 E [BT]    : mtk_bt_op: BT_VND_OP_LPM_SET_MODE 0
99702-18 15:23:30.455  3091  3091 D android.hardware.bluetooth@1.0-impl: low_power_mode_cb result: 0
99802-18 15:23:30.455  3091  3091 E [BT]    : mtk_bt_op: BT_VND_OP_USERIAL_CLOSE
99902-18 15:23:30.467  3091  3091 E [BT]    : mtk_bt_op: BT_VND_OP_POWER_CTRL 0
100002-18 15:23:30.467  3091  3091 E [BT]    : mtk_bt_cleanup #288
100102-18 15:23:30.472  3310  3321 W BluetoothManagerService: Profile service for profile: ComponentInfo{com.android.bluetooth/com.android.bluetooth.hfp.HeadsetService} died.
100202-18 15:23:30.472  3310  3310 D BluetoothHeadset: Proxy object disconnected
100302-18 15:23:30.472  3310  3310 I AudioService: setBtScoActiveDevice: null -> null
100402-18 15:23:30.472  3310  3310 D BluetoothA2dp: Proxy object disconnected
100502-18 15:23:30.472  3310  3593 W AudioService.PlaybackActivityMonitor: client died
100602-18 15:23:30.472  3310  3310 D BluetoothManagerService: BluetoothServiceConnection, disconnected: com.android.bluetooth.btservice.AdapterService
100702-18 15:23:30.472  3310  3310 D BluetoothManagerService: BluetoothServiceConnection, disconnected: com.android.bluetooth.gatt.GattService
100802-18 15:23:30.472  3310  3332 E BluetoothManagerService: MESSAGE_BLUETOOTH_SERVICE_DISCONNECTED(1)
100902-18 15:23:30.472  3310  3321 I ActivityManager: Process com.android.bluetooth (pid 3427) has died: psvc PER
101002-18 15:23:30.473  3310  3332 D BluetoothManagerService: Broadcasting onBluetoothServiceDown() to 5 receivers.
101102-18 15:23:30.473  3310  3321 W ActivityManager: Scheduling restart of crashed service com.android.bluetooth/.hfp.HeadsetService in 1000ms
101202-18 15:23:30.473  3310  3332 D BluetoothAdapter: onBluetoothServiceDown: android.bluetooth.IBluetooth$Stub$Proxy@639f0fe
101302-18 15:23:30.473  3310  3321 W ActivityManager: Scheduling restart of crashed service com.android.bluetooth/.opp.BluetoothOppService in 1000ms
101402-18 15:23:30.473  3842  5187 D BluetoothAdapter: onBluetoothServiceDown: android.bluetooth.IBluetooth$Stub$Proxy@a859d5d
101502-18 15:23:30.473  3310  3332 D BluetoothManagerService: Sending BLE State Change: ON > TURNING_OFF
101602-18 15:23:30.473  3310  3321 W ActivityManager: Scheduling restart of crashed service com.android.bluetooth/.gatt.GattService in 1000ms
101702-18 15:23:30.473  3310  3321 W ActivityManager: Scheduling restart of crashed service com.android.bluetooth/.hid.HidDeviceService in 11000ms
101802-18 15:23:30.473  3310  3321 W ActivityManager: Scheduling restart of crashed service com.android.bluetooth/.pbap.BluetoothPbapService in 11000ms
101902-18 15:23:30.473  3310  3321 W ActivityManager: Scheduling restart of crashed service com.android.bluetooth/.hid.HidHostService in 10999ms
102002-18 15:23:30.473  4663  4680 D BluetoothAdapter: onBluetoothServiceDown: android.bluetooth.IBluetooth$Stub$Proxy@b99d300
102102-18 15:23:30.474  3310  3321 W ActivityManager: Scheduling restart of crashed service com.android.bluetooth/.avrcp.AvrcpTargetService in 10999ms
102202-18 15:23:30.474  3310  3321 W ActivityManager: Scheduling restart of crashed service com.android.bluetooth/.pan.PanService in 10999ms
102302-18 15:23:30.474  3310  3321 W ActivityManager: Scheduling restart of crashed service com.android.bluetooth/.a2dp.A2dpService in 10999ms
102402-18 15:23:30.474  3310  3321 W ActivityManager: Scheduling restart of crashed service com.android.bluetooth/.map.BluetoothMapService in 10999ms
102502-18 15:23:30.474  3310  3321 W ActivityManager: Scheduling restart of crashed service com.android.bluetooth/.hdp.HealthService in 10999ms
102602-18 15:23:30.474  3310  3321 W ActivityManager: Scheduling restart of crashed service com.android.bluetooth/.btservice.AdapterService in 10999ms
102702-18 15:23:30.474  3496  3514 D BluetoothAdapter: onBluetoothServiceDown: android.bluetooth.IBluetooth$Stub$Proxy@9dcd6ad
102802-18 15:23:30.474  3553  4083 D BluetoothAdapter: onBluetoothServiceDown: android.bluetooth.IBluetooth$Stub$Proxy@a3a315a
102902-18 15:23:30.477  3084  3084 I Zygote  : Process 3427 exited due to signal (6)
103002-18 15:23:30.485  3310  3328 W libprocessgroup: kill(-3427, 9) failed: No such process
103102-18 15:23:30.485  3310  3328 W libprocessgroup: kill(3427, 9) failed: No such process
103202-18 15:23:30.485  3310  3310 I AudioService: setBtScoActiveDevice: null -> null
103302-18 15:23:30.486  3310  3325 W BroadcastQueue: Background execution not allowed: receiving Intent { act=android.bluetooth.adapter.action.STATE_CHANGED flg=0x4000010 (has extras) } to com.google.android.gms/.chimera.GmsIntentOperationService$PersistentTrustedReceiver
103402-18 15:23:30.488  3310  3332 D BluetoothManagerService: Bluetooth is complete send Service Down
103502-18 15:23:30.488  3310  3332 D BluetoothManagerService: Broadcasting onBluetoothServiceDown() to 5 receivers.
103602-18 15:23:30.489  3310  3332 D BluetoothAdapter: onBluetoothServiceDown: null
103702-18 15:23:30.489  3310  3332 D BluetoothManagerService: unbindAndFinish(): null mBinding = false mUnbinding = false
103802-18 15:23:30.489  3310  3332 D BluetoothManagerService: Sending BLE State Change: TURNING_OFF > OFF
103902-18 15:23:30.489  3842  3856 D BluetoothAdapter: onBluetoothServiceDown: null
104002-18 15:23:30.489  3553  4083 D BluetoothAdapter: onBluetoothServiceDown: null
104102-18 15:23:30.489  3310  3332 E BluetoothManagerService: MESSAGE_BLUETOOTH_SERVICE_DISCONNECTED(2)
104202-18 15:23:30.489  3496  3514 D BluetoothAdapter: onBluetoothServiceDown: null
104302-18 15:23:30.490  4663  4680 D BluetoothAdapter: onBluetoothServiceDown: null
104402-18 15:23:30.522  3310  3320 I system_server: Background concurrent copying GC freed 16408(819KB) AllocSpace objects, 7(1196KB) LOS objects, 19% free, 8MB/10MB, paused 193us total 156.102ms
104502-18 15:23:30.530  3310  3328 W libprocessgroup: kill(-3427, 9) failed: No such process
104602-18 15:23:30.530  3310  3328 I libprocessgroup: Successfully killed process cgroup uid 1002 pid 3427 in 57ms
104702-18 15:23:30.673  3310  3332 D BluetoothManagerService: MESSAGE_RESTART_BLUETOOTH_SERVICE
104802-18 15:23:30.689  3310  3327 I ActivityManager: Start proc 5278:com.android.bluetooth/1002 for service com.android.bluetooth/.btservice.AdapterService
104902-18 15:23:30.692  5278  5278 I Zygote  : seccomp disabled by setenforce 0
105002-18 15:23:30.699  5278  5278 W droid.bluetoot: Using default instruction set features for ARM CPU variant (cortex-a9) using conservative defaults
105102-18 15:23:30.743  5278  5278 I droid.bluetoot: The ClassLoaderContext is a special shared library.
105202-18 15:23:30.744  5278  5278 I droid.bluetoot: The ClassLoaderContext is a special shared library.
105302-18 15:23:30.779  5278  5278 D BluetoothOppFileProvider: Initialized
105402-18 15:23:30.804  5278  5278 V AdapterServiceConfig: Adding HeadsetService
105502-18 15:23:30.805  5278  5278 V AdapterServiceConfig: Adding A2dpService
105602-18 15:23:30.805  5278  5278 V AdapterServiceConfig: Adding HidHostService
105702-18 15:23:30.805  5278  5278 V AdapterServiceConfig: Adding HealthService
105802-18 15:23:30.805  5278  5278 V AdapterServiceConfig: Adding PanService
105902-18 15:23:30.805  5278  5278 V AdapterServiceConfig: Adding GattService
106002-18 15:23:30.805  5278  5278 V AdapterServiceConfig: Adding BluetoothMapService
106102-18 15:23:30.805  5278  5278 V AdapterServiceConfig: Adding AvrcpTargetService
106202-18 15:23:30.805  5278  5278 V AdapterServiceConfig: Adding HidDeviceService
106302-18 15:23:30.806  5278  5278 V AdapterServiceConfig: Adding BluetoothOppService
106402-18 15:23:30.806  5278  5278 V AdapterServiceConfig: Adding BluetoothPbapService
106502-18 15:23:30.814  5278  5278 I         : [0218/152330.814601:INFO:com_android_bluetooth_btservice_AdapterService.cpp(630)] hal_util_load_bt_library loaded HAL: btinterface=0x933c4234, handle=0x3b28a401
106602-18 15:23:30.815  5278  5278 D BluetoothAdapterService: onCreate()
106702-18 15:23:30.821  5278  5278 D AdapterState: make() - Creating AdapterState
106802-18 15:23:30.822  5278  5278 I bt_btif : init
106902-18 15:23:30.822  5278  5293 I AdapterState: OFF : entered
107002-18 15:23:30.822  5278  5293 D AdapterProperties: Setting state to OFF
107102-18 15:23:30.822  5278  5278 D bt_osi_allocation_tracker: canary initialized
107202-18 15:23:30.823  5278  5294 I bt_osi_thread: run_thread: thread id 5294, thread name stack_manager started
107302-18 15:23:30.823  5278  5294 I bt_stack_manager: event_init_stack is initializing the stack
107402-18 15:23:30.824  5278  5297 I bt_osi_thread: run_thread: thread id 5297, thread name alarm_default_ca started
107502-18 15:23:30.825  5278  5298 I bt_osi_thread: run_thread: thread id 5298, thread name alarm_dispatcher started
107602-18 15:23:30.827  5278  5294 I bt_btif_core: btif_init_bluetooth entered
107702-18 15:23:30.827  5278  5294 I bt_stack_config: init attempt to load stack conf from /etc/bluetooth/bt_stack.conf
107802-18 15:23:30.828  5278  5299 I bt_osi_thread: run_thread: thread id 5299, thread name bt_jni_workqueue started
107902-18 15:23:30.828  5278  5294 I bt_btif_core: btif_init_bluetooth finished
108002-18 15:23:30.828  5278  5294 I bt_stack_manager: event_init_stack finished
108102-18 15:23:30.828  5278  5278 I bt_osi_wakelock: wakelock_set_os_callouts set to non-native
108202-18 15:23:30.828  5278  5278 I bt_btif : get_profile_interface: id = socket
108302-18 15:23:30.828  5278  5278 W bt_btif : do_in_jni_thread: Dropped message, message_loop not initialized yet!
108402-18 15:23:30.828  5278  5278 I chatty  : uid=1002(bluetooth) com.android.bluetooth identical 1 line
108502-18 15:23:30.828  5278  5278 W bt_btif : do_in_jni_thread: Dropped message, message_loop not initialized yet!
108602-18 15:23:30.828  5278  5299 I bt_btif_core: run_message_loop entered
108702-18 15:23:30.832  5278  5278 I bt_btif : get_profile_interface: id = sdp
108802-18 15:23:30.837  5278  5278 I BluetoothAdapterService: Phone policy enabled
108902-18 15:23:30.839  5278  5278 D BluetoothActiveDeviceManager: start()
109002-18 15:23:30.845  5278  5300 D BluetoothActiveDeviceManager: onAudioDevicesAdded
109102-18 15:23:30.845  5278  5278 D BluetoothAdapterService: setAdapterService() - trying to set service to com.android.bluetooth.btservice.AdapterService@572578a
109202-18 15:23:30.845  5278  5300 D BluetoothActiveDeviceManager: Audio device added: Android KM9 type: 9
109302-18 15:23:30.845  5278  5300 D BluetoothActiveDeviceManager: Audio device added: Android KM9 type: 2
109402-18 15:23:30.846  5278  5300 D BluetoothActiveDeviceManager: Audio device added: Android KM9 type: 15
109502-18 15:23:30.846  5278  5300 D BluetoothActiveDeviceManager: Audio device added: Android KM9 type: 15
109602-18 15:23:30.846  5278  5300 D BluetoothActiveDeviceManager: Audio device added: USB-Audio - USB Device 0x1d57:0xad03 type: 11
109702-18 15:23:30.850  5278  5278 D BluetoothAdapterService: onBind()
109802-18 15:23:30.851  3310  3310 D BluetoothManagerService: BluetoothServiceConnection: com.android.bluetooth.btservice.AdapterService
109902-18 15:23:30.851  3310  3332 D BluetoothManagerService: MESSAGE_BLUETOOTH_SERVICE_CONNECTED: 1
110002-18 15:23:30.852  3310  3332 D BluetoothManagerService: Broadcasting onBluetoothServiceUp() to 6 receivers.
110102-18 15:23:30.852  3842  5187 D BluetoothAdapter: onBluetoothServiceUp: android.bluetooth.IBluetooth$Stub$Proxy@88f9ccc
110202-18 15:23:30.852  5278  5278 I BtGatt.JNI: classInitNative(L875): classInitNative: Success!
110302-18 15:23:30.852  3310  3332 D BluetoothAdapter: onBluetoothServiceUp: android.bluetooth.IBluetooth$Stub$Proxy@da3a418
110402-18 15:23:30.853  4663  4680 D BluetoothAdapter: onBluetoothServiceUp: android.bluetooth.IBluetooth$Stub$Proxy@c90739
110502-18 15:23:30.853  5278  5291 D BluetoothAdapter: onBluetoothServiceUp: com.android.bluetooth.btservice.AdapterService$AdapterServiceBinder@1a94be1
110602-18 15:23:30.853  3553  4083 D BluetoothAdapter: onBluetoothServiceUp: android.bluetooth.IBluetooth$Stub$Proxy@b6c9f8b
110702-18 15:23:30.853  3496  3514 D BluetoothAdapter: onBluetoothServiceUp: android.bluetooth.IBluetooth$Stub$Proxy@33e4de2
110802-18 15:23:30.855  5278  5290 D BluetoothAdapterService: enable() - Enable called with quiet mode status =  false
110902-18 15:23:30.855  5278  5293 I AdapterState: BLE_TURNING_ON : entered
111002-18 15:23:30.855  5278  5293 D AdapterProperties: Setting state to BLE_TURNING_ON
111102-18 15:23:30.855  5278  5293 D BluetoothAdapterService: updateAdapterState() - Broadcasting state BLE_TURNING_ON to 1 receivers.
111202-18 15:23:30.855  3310  3332 D BluetoothManagerService: MESSAGE_BLUETOOTH_STATE_CHANGE: OFF > BLE_TURNING_ON
111302-18 15:23:30.855  3310  3332 D BluetoothManagerService: Sending BLE State Change: OFF > BLE_TURNING_ON
111402-18 15:23:30.856  3310  3310 D BluetoothManagerService: BluetoothServiceConnection: com.android.bluetooth.gatt.GattService
111502-18 15:23:30.856  3310  3332 D BluetoothManagerService: MESSAGE_BLUETOOTH_SERVICE_CONNECTED: 2
111602-18 15:23:30.856  3310  3332 D BluetoothManagerService: continueFromBleOnState()
111702-18 15:23:30.856  5278  5293 D BluetoothAdapterService: bleOnProcessStart()
111802-18 15:23:30.856  5278  5293 I AdapterProperties: init(), maxConnectedAudioDevices, default=5, propertyOverlayed=5, finalValue=5
111902-18 15:23:30.856  3310  3332 D BluetoothManagerService: Persisting Bluetooth Setting: 1
112002-18 15:23:30.857  5278  5293 D BluetoothAdapterService: bleOnProcessStart() - Make Bond State Machine
112102-18 15:23:30.857  5278  5293 D BluetoothBondStateMachine: make
112202-18 15:23:30.860  5278  5293 I AdapterState: BLE_TURNING_ON : Unhandled message - USER_TURN_ON
112302-18 15:23:30.860  5278  5306 I BluetoothBondStateMachine: StableState(): Entering Off State
112402-18 15:23:30.860  5278  5278 D BtGatt.DebugUtils: handleDebugAction() action=null
112502-18 15:23:30.861  5278  5278 D BluetoothAdapterService: getAdapterService() - returning com.android.bluetooth.btservice.AdapterService@572578a
112602-18 15:23:30.862  5278  5278 I bt_btif : get_profile_interface: id = gatt
112702-18 15:23:30.863  5278  5278 D BluetoothAdapterService: getAdapterService() - returning com.android.bluetooth.btservice.AdapterService@572578a
112802-18 15:23:30.870  5278  5278 D BluetoothAdapterService: getAdapterService() - returning com.android.bluetooth.btservice.AdapterService@572578a
112902-18 15:23:30.871  5278  5278 D BluetoothAdapterService: handleMessage() - Message: 2
113002-18 15:23:30.871  5278  5278 D BluetoothAdapterService: handleMessage() - MESSAGE_PROFILE_SERVICE_REGISTERED
113102-18 15:23:30.871  5278  5278 D BluetoothAdapterService: handleMessage() - Message: 1
113202-18 15:23:30.871  5278  5278 D BluetoothAdapterService: handleMessage() - MESSAGE_PROFILE_SERVICE_STATE_CHANGED
113302-18 15:23:30.872  5278  5278 I bt_btif : enable: start restricted = 0
113402-18 15:23:30.872  5278  5294 I bt_stack_manager: event_start_up_stack is bringing up the stack
113502-18 15:23:30.872  5278  5294 I bt_core_module: module_start_up Starting module "btif_config_module"
113602-18 15:23:30.872  5278  5294 I bt_core_module: module_start_up Started module "btif_config_module"
113702-18 15:23:30.872  5278  5294 I bt_core_module: module_start_up Starting module "btsnoop_module"
113802-18 15:23:30.872  5278  5294 I bt_core_module: module_start_up Started module "btsnoop_module"
113902-18 15:23:30.872  5278  5294 I bt_core_module: module_start_up Starting module "hci_module"
114002-18 15:23:30.872  5278  5294 I bt_hci  : hci_module_start_up
114102-18 15:23:30.873  5278  5310 I bt_osi_thread: run_thread: thread id 5310, thread name hci_thread started
114202-18 15:23:30.873  5278  5310 I bt_hci  : hci_initialize
114302-18 15:23:30.874  5278  5294 D bt_hci  : hci_module_start_up starting async portion
114402-18 15:23:30.875  5278  5310 I bt_hci  : hci_initialize: IBluetoothHci::getService() returned 0xa4a11260 (remote)
114502-18 15:23:30.875  3091  3091 I android.hardware.bluetooth@1.0-impl: BluetoothHci::initialize()
114602-18 15:23:30.877  3091  3091 E bda_generation: FallthroughBTA: No factory BDADDR found
114702-18 15:23:30.877  3091  3091 E [BT]    : mtk_bt_init: mtk_bt_init : VENDOR LIBRARY VERSION =700.0.17122701
114802-18 15:23:30.877  3091  3091 E [BT]    : mtk_bt_init #197
114902-18 15:23:30.877  3091  3091 D android.hardware.bluetooth@1.0-impl: Open vendor library loaded
115002-18 15:23:30.877  3091  3091 E [BT]    : mtk_bt_op: BT_VND_OP_POWER_CTRL 1
115102-18 15:23:30.877  3091  3091 E [BT]    : mtk_bt_op: BT_VND_OP_USERIAL_OPEN
115202-18 15:23:30.877  3091  3091 E [BT]    : init_uart #130
115302-18 15:23:30.877  3091  3091 D bt-platform: We're goting to access rfkill to power on this device(sdk: 28) by default...
115402-18 15:23:30.877  3091  3091 D bt-platform: rfkill state path init successed: /sys/class/rfkill/rfkill0/state
115502-18 15:23:30.877  3091  3091 D bt-platform: open /sys/class/rfkill/rfkill0/state :6(Success)
115602-18 15:23:31.303  3091  3091 D bt-platform: rfkill_operations: power up done(Success)!
115702-18 15:23:31.304  3091  3091 D bt-platform: ismod_bt_driver: driver_status = true
115802-18 15:23:31.304  3091  3091 W bt-platform: ismod_bt_driver: btmtksdio.ko is already insmod!
115902-18 15:23:31.504  3091  3091 D [BT]    : init_uart: attemping(1) to open stpbt...
116002-18 15:23:33.779  3083  3145 W Netd    : No subsystem found in netlink event
116102-18 15:23:34.858  5278  5293 E AdapterState: BLE_TURNING_ON : BLE_START_TIMEOUT
116202-18 15:23:34.858  5278  5293 I AdapterState: BLE_TURNING_OFF : entered
116302-18 15:23:34.858  5278  5293 D AdapterProperties: Setting state to BLE_TURNING_OFF
116402-18 15:23:34.858  5278  5293 D BluetoothAdapterService: updateAdapterState() - Broadcasting state BLE_TURNING_OFF to 1 receivers.
116502-18 15:23:34.863  3310  3332 D BluetoothManagerService: MESSAGE_BLUETOOTH_STATE_CHANGE: BLE_TURNING_ON > BLE_TURNING_OFF
116602-18 15:23:34.863  3310  3332 D BluetoothManagerService: Sending BLE State Change: BLE_TURNING_ON > BLE_TURNING_OFF
116702-18 15:23:34.864  5278  5293 D AdapterProperties: onBleDisable
116802-18 15:23:34.865  5278  5278 D BtGatt.DebugUtils: handleDebugAction() action=null
116902-18 15:23:34.872  5278  5278 D BluetoothAdapterService: handleMessage() - Message: 1
117002-18 15:23:34.872  5278  5278 D BluetoothAdapterService: handleMessage() - MESSAGE_PROFILE_SERVICE_STATE_CHANGED
117102-18 15:23:34.872  5278  5278 D BluetoothAdapterService: handleMessage() - Message: 3
117202-18 15:23:34.872  5278  5278 D BluetoothAdapterService: handleMessage() - MESSAGE_PROFILE_SERVICE_UNREGISTERED
117302-18 15:23:34.873  5278  5293 I AdapterState: OFF : entered
117402-18 15:23:34.873  5278  5293 D AdapterProperties: Setting state to OFF
117502-18 15:23:34.874  5278  5293 D BluetoothAdapterService: updateAdapterState() - Broadcasting state OFF to 1 receivers.
117602-18 15:23:34.875  3310  3332 D BluetoothManagerService: MESSAGE_BLUETOOTH_STATE_CHANGE: BLE_TURNING_OFF > OFF
117702-18 15:23:34.875  3310  3332 D BluetoothManagerService: Bluetooth is complete send Service Down
117802-18 15:23:34.875  3310  3332 D BluetoothManagerService: Broadcasting onBluetoothServiceDown() to 6 receivers.
117902-18 15:23:34.875  4663  4680 D BluetoothAdapter: onBluetoothServiceDown: android.bluetooth.IBluetooth$Stub$Proxy@c90739
118002-18 15:23:34.875  3842  5187 D BluetoothAdapter: onBluetoothServiceDown: android.bluetooth.IBluetooth$Stub$Proxy@88f9ccc
118102-18 15:23:34.875  3553  4083 D BluetoothAdapter: onBluetoothServiceDown: android.bluetooth.IBluetooth$Stub$Proxy@b6c9f8b
118202-18 15:23:34.876  3496  3514 D BluetoothAdapter: onBluetoothServiceDown: android.bluetooth.IBluetooth$Stub$Proxy@33e4de2
118302-18 15:23:34.876  3310  3332 D BluetoothAdapter: onBluetoothServiceDown: android.bluetooth.IBluetooth$Stub$Proxy@da3a418
118402-18 15:23:34.876  3310  3332 D BluetoothManagerService: unbindAndFinish(): android.bluetooth.IBluetooth$Stub$Proxy@da3a418 mBinding = false mUnbinding = false
118502-18 15:23:34.877  5278  5290 D BluetoothAdapter: onBluetoothServiceDown: com.android.bluetooth.btservice.AdapterService$AdapterServiceBinder@1a94be1
118602-18 15:23:34.881  5278  5278 W         : [0218/152334.881640:WARNING:bta_gattc_api.cc(61)] GATTC Module not enabled/already disabled
118702-18 15:23:34.881  5278  5278 W         : [0218/152334.881743:WARNING:bta_gatts_api.cc(56)] GATTS Module not enabled/already disabled
118802-18 15:23:34.883  5278  5278 W BtGatt.ScanManager: exception when invoking removeOnUidImportanceListener
118902-18 15:23:34.883  5278  5278 W BtGatt.ScanManager: java.lang.IllegalArgumentException: Listener not registered: com.android.bluetooth.gatt.ScanManager$2@91fb6de
119002-18 15:23:34.883  5278  5278 W BtGatt.ScanManager:    at android.app.ActivityManager.removeOnUidImportanceListener(ActivityManager.java:3552)
119102-18 15:23:34.883  5278  5278 W BtGatt.ScanManager:    at com.android.bluetooth.gatt.ScanManager.cleanup(ScanManager.java:157)
119202-18 15:23:34.883  5278  5278 W BtGatt.ScanManager:    at com.android.bluetooth.gatt.GattService.cleanup(GattService.java:244)
119302-18 15:23:34.883  5278  5278 W BtGatt.ScanManager:    at com.android.bluetooth.btservice.ProfileService.onDestroy(ProfileService.java:211)
119402-18 15:23:34.883  5278  5278 W BtGatt.ScanManager:    at android.app.ActivityThread.handleStopService(ActivityThread.java:3698)
119502-18 15:23:34.883  5278  5278 W BtGatt.ScanManager:    at android.app.ActivityThread.access$1700(ActivityThread.java:200)
119602-18 15:23:34.883  5278  5278 W BtGatt.ScanManager:    at android.app.ActivityThread$H.handleMessage(ActivityThread.java:1687)
119702-18 15:23:34.883  5278  5278 W BtGatt.ScanManager:    at android.os.Handler.dispatchMessage(Handler.java:106)
119802-18 15:23:34.883  5278  5278 W BtGatt.ScanManager:    at android.os.Looper.loop(Looper.java:193)
119902-18 15:23:34.883  5278  5278 W BtGatt.ScanManager:    at android.app.ActivityThread.main(ActivityThread.java:6680)
120002-18 15:23:34.883  5278  5278 W BtGatt.ScanManager:    at java.lang.reflect.Method.invoke(Native Method)
120102-18 15:23:34.883  5278  5278 W BtGatt.ScanManager:    at com.android.internal.os.RuntimeInit$MethodAndArgsCaller.run(RuntimeInit.java:493)
120202-18 15:23:34.883  5278  5278 W BtGatt.ScanManager:    at com.android.internal.os.ZygoteInit.main(ZygoteInit.java:858)
120302-18 15:23:34.885  5278  5278 W BtGatt.ScanManager: exception when invoking unregisterReceiver(mLocationReceiver)
120402-18 15:23:34.885  5278  5278 W BtGatt.ScanManager: java.lang.IllegalArgumentException: Receiver not registered: com.android.bluetooth.gatt.ScanManager$3@9ecb0bf
120502-18 15:23:34.885  5278  5278 W BtGatt.ScanManager:    at android.app.LoadedApk.forgetReceiverDispatcher(LoadedApk.java:1271)
120602-18 15:23:34.885  5278  5278 W BtGatt.ScanManager:    at android.app.ContextImpl.unregisterReceiver(ContextImpl.java:1504)
120702-18 15:23:34.885  5278  5278 W BtGatt.ScanManager:    at android.content.ContextWrapper.unregisterReceiver(ContextWrapper.java:659)
120802-18 15:23:34.885  5278  5278 W BtGatt.ScanManager:    at com.android.bluetooth.gatt.ScanManager.cleanup(ScanManager.java:178)
120902-18 15:23:34.885  5278  5278 W BtGatt.ScanManager:    at com.android.bluetooth.gatt.GattService.cleanup(GattService.java:244)
121002-18 15:23:34.885  5278  5278 W BtGatt.ScanManager:    at com.android.bluetooth.btservice.ProfileService.onDestroy(ProfileService.java:211)
121102-18 15:23:34.885  5278  5278 W BtGatt.ScanManager:    at android.app.ActivityThread.handleStopService(ActivityThread.java:3698)
121202-18 15:23:34.885  5278  5278 W BtGatt.ScanManager:    at android.app.ActivityThread.access$1700(ActivityThread.java:200)
121302-18 15:23:34.885  5278  5278 W BtGatt.ScanManager:    at android.app.ActivityThread$H.handleMessage(ActivityThread.java:1687)
121402-18 15:23:34.885  5278  5278 W BtGatt.ScanManager:    at android.os.Handler.dispatchMessage(Handler.java:106)
121502-18 15:23:34.885  5278  5278 W BtGatt.ScanManager:    at android.os.Looper.loop(Looper.java:193)
121602-18 15:23:34.885  5278  5278 W BtGatt.ScanManager:    at android.app.ActivityThread.main(ActivityThread.java:6680)
121702-18 15:23:34.885  5278  5278 W BtGatt.ScanManager:    at java.lang.reflect.Method.invoke(Native Method)
121802-18 15:23:34.885  5278  5278 W BtGatt.ScanManager:    at com.android.internal.os.RuntimeInit$MethodAndArgsCaller.run(RuntimeInit.java:493)
121902-18 15:23:34.885  5278  5278 W BtGatt.ScanManager:    at com.android.internal.os.ZygoteInit.main(ZygoteInit.java:858)
122002-18 15:23:34.885  3310  3332 D BluetoothManagerService: Sending BLE State Change: BLE_TURNING_OFF > OFF
122102-18 15:23:34.885  5278  5278 D BluetoothAdapterService: onUnbind() - calling cleanup
122202-18 15:23:34.885  5278  5278 D BluetoothAdapterService: cleanup()
122302-18 15:23:34.887  3310  3332 D BluetoothManagerService: Entering STATE_OFF but mEnabled is true; restarting.
122402-18 15:23:34.888  3310  3332 E BluetoothManagerService: waitForOnOff time out
122502-18 15:23:34.891  5278  5278 W BluetoothSdpJni: Cleaning up Bluetooth SDP Interface...
122602-18 15:23:34.891  5278  5278 W BluetoothSdpJni: Cleaning up Bluetooth SDP object
122702-18 15:23:34.891  5278  5278 D BluetoothAdapterService: cleanup() - Cleaning up adapter native
122802-18 15:23:35.288  3310  3332 D BluetoothManagerService: MESSAGE_RESTART_BLUETOOTH_SERVICE
122902-18 15:23:36.589  3091  3091 D [BT]    : init_uart: open stpbt succeesed[6]...
123002-18 15:23:36.594  3091  3091 E [BT]    : mtk_bt_op: BT VENDOR start wait signal from kernel[6]...
123102-18 15:23:36.594  3091  3091 E [BT]    : mtk_bt_op: BT_VND_OP_FW_CFG
123202-18 15:23:36.594  3091  3091 D android.hardware.bluetooth@1.0-impl: OnFirmwareConfigured result: 0
123302-18 15:23:36.594  3091  3091 I android.hardware.bluetooth@1.0-impl: Firmware configured in 0.000s
123402-18 15:23:36.595  3091  3091 E [BT]    : mtk_bt_op: BT_VND_OP_GET_LPM_IDLE_TIMEOUT
123502-18 15:23:36.595  3091  3091 I android.hardware.bluetooth@1.0-impl: OnFirmwareConfigured: lpm_timeout_ms 5000
123602-18 15:23:36.595  3091  3091 E [BT]    : mtk_bt_op: BT_VND_OP_LPM_SET_MODE 1
123702-18 15:23:36.595  3091  3091 D android.hardware.bluetooth@1.0-impl: low_power_mode_cb result: 0
123802-18 15:23:36.595  3091  3091 D android.hardware.bluetooth@1.0-impl: OnFirmwareConfigured Calling StartLowPowerWatchdog()
123902-18 15:23:36.595  5278  5310 I bt_hci  : event_finish_startup
124002-18 15:23:36.597  5278  5294 I bt_core_module: module_start_up Started module "hci_module"
124102-18 15:23:36.606  5278  5314 I bt_osi_thread: run_thread: thread id 5314, thread name bt_workqueue started
124202-18 15:23:36.607  5278  5314 I         : [0218/152336.607125:INFO:btu_task.cc(107)] Bluetooth chip preload is complete
124302-18 15:23:36.608  5278  5314 I         : [0218/152336.608018:INFO:gatt_api.cc(948)] GATT_Register 81818181-8181-8181-8181-818181818181
124402-18 15:23:36.608  5278  5314 I         : [0218/152336.608183:INFO:gatt_api.cc(968)] allocated gatt_if=1
124502-18 15:23:36.608  5278  5314 I         : [0218/152336.608249:INFO:gatt_api.cc(161)] GATTS_AddService
124602-18 15:23:36.608  5278  5314 I         : [0218/152336.608309:INFO:gatt_api.cc(265)] GATTS_AddService: service parsed correctly, now starting
124702-18 15:23:36.608  5278  5314 I         : [0218/152336.608566:INFO:gatt_api.cc(948)] GATT_Register 82828282-8282-8282-8282-828282828282
124802-18 15:23:36.608  5278  5314 I         : [0218/152336.608645:INFO:gatt_api.cc(968)] allocated gatt_if=2
124902-18 15:23:36.608  5278  5314 I         : [0218/152336.608702:INFO:gatt_api.cc(161)] GATTS_AddService
125002-18 15:23:36.608  5278  5314 I         : [0218/152336.608761:INFO:gatt_api.cc(265)] GATTS_AddService: service parsed correctly, now starting
125102-18 15:23:36.609  5278  5314 I bt_bte  : BTE_InitTraceLevels -- TRC_HCI : Level 2
125202-18 15:23:36.609  5278  5314 I bt_bte  : BTE_InitTraceLevels -- TRC_L2CAP : Level 2
125302-18 15:23:36.609  5278  5314 I bt_bte  : BTE_InitTraceLevels -- TRC_RFCOMM : Level 2
125402-18 15:23:36.609  5278  5314 I bt_bte  : BTE_InitTraceLevels -- TRC_AVDT : Level 2
125502-18 15:23:36.609  5278  5314 I bt_bte  : BTE_InitTraceLevels -- TRC_AVRC : Level 2
125602-18 15:23:36.609  5278  5314 I bt_bte  : BTE_InitTraceLevels -- TRC_A2D : Level 2
125702-18 15:23:36.609  5278  5314 I bt_bte  : BTE_InitTraceLevels -- TRC_BNEP : Level 2
125802-18 15:23:36.609  5278  5314 I bt_bte  : BTE_InitTraceLevels -- TRC_BTM : Level 2
125902-18 15:23:36.609  5278  5314 I bt_bte  : BTE_InitTraceLevels -- TRC_HID_HOST : Level 2
126002-18 15:23:36.609  5278  5314 I bt_bte  : BTE_InitTraceLevels -- TRC_PAN : Level 2
126102-18 15:23:36.609  5278  5314 I bt_bte  : BTE_InitTraceLevels -- TRC_SDP : Level 2
126202-18 15:23:36.609  5278  5314 I bt_bte  : BTE_InitTraceLevels -- TRC_SMP : Level 2
126302-18 15:23:36.609  5278  5314 I bt_bte  : BTE_InitTraceLevels -- TRC_HID_DEV : Level 2
126402-18 15:23:36.609  5278  5314 I bt_bte  : BTE_InitTraceLevels -- TRC_BTAPP : Level 2
126502-18 15:23:36.609  5278  5314 I bt_bte  : BTE_InitTraceLevels -- TRC_BTIF : Level 2
126602-18 15:23:36.609  5278  5315 I bt_osi_thread: run_thread: thread id 5315, thread name btu message loop started
126702-18 15:23:36.610  5278  5316 I bt_osi_thread: run_thread: thread id 5316, thread name module_wrapper started
126802-18 15:23:36.610  5278  5316 I bt_core_module: module_start_up Starting module "controller_module"
126902-18 15:23:38.290  3310  3332 E BluetoothManagerService: MESSAGE_TIMEOUT_BIND
127002-18 15:23:38.613  5278  5297 E bt_hci  : command_timed_out
127102-18 15:23:38.613  5278  5297 E bt_hci  : command_timed_out_log_info: 1 commands pending response
127202-18 15:23:38.614  5278  5297 E bt_hci  : command_timed_out_log_info: Waited 2002 ms for a response to opcode: 0xc03 *matches timer*
127302-18 15:23:38.614  5278  5297 E bt_hci  : command_timed_out_log_info: Size 3 Hex 03 0c 00
127402-18 15:23:38.614  5278  5297 E bt_hci  : command_timed_out: requesting a firmware dump.
127502-18 15:23:38.614  5278  5297 E bt_hci  : command_timed_out: Setting a timer to restart.
127602-18 15:23:40.010  3083  3145 W Netd    : No subsystem found in netlink event
127702-18 15:23:40.236  4025  4025 I Thread-3: type=1400 audit(0.0:158): avc: denied { open } for path="/proc/stat" dev="proc" ino=4026531973 scontext=u:r:untrusted_app_27:s0:c512,c768 tcontext=u:object_r:proc_stat:s0 tclass=file permissive=1
127802-18 15:23:41.960  3197  3245 E storaged: getDiskStats failed with result NOT_SUPPORTED and size 0
127902-18 15:23:43.615  5278  5297 E bt_hci  : hci_timeout_abort restarting the Bluetooth process.
128002-18 15:23:43.615  5278  5297 F libc    : Fatal signal 6 (SIGABRT), code -6 (SI_TKILL) in tid 5297 (alarm_default_c), pid 5278 (droid.bluetooth)
128102-18 15:23:43.702  5319  5319 I crash_dump32: obtaining output fd from tombstoned, type: kDebuggerdTombstone
128202-18 15:23:43.703  3208  3208 I /system/bin/tombstoned: received crash request for pid 5297
128302-18 15:23:43.703  5319  5319 I crash_dump32: performing dump of process 5278 (target tid = 5297)
128402-18 15:23:43.726  5319  5319 F DEBUG   : *** *** *** *** *** *** *** *** *** *** *** *** *** *** *** ***
128502-18 15:23:43.726  5319  5319 F DEBUG   : Build fingerprint: 'Amlogic/franklin/franklin:9/PPR1.180610.011/V2.20200218.084033:userdebug/test-keys'
128602-18 15:23:43.726  5319  5319 F DEBUG   : Revision: '0'
128702-18 15:23:43.726  5319  5319 F DEBUG   : ABI: 'arm'
128802-18 15:23:43.726  5319  5319 F DEBUG   : pid: 5278, tid: 5297, name: alarm_default_c  >>> com.android.bluetooth <<<
128902-18 15:23:43.726  5319  5319 F DEBUG   : signal 6 (SIGABRT), code -6 (SI_TKILL), fault addr --------
129002-18 15:23:43.726  5319  5319 F DEBUG   :     r0  00000000  r1  000014b1  r2  00000006  r3  aee16918
129102-18 15:23:43.726  5319  5319 F DEBUG   :     r4  0000149e  r5  000014b1  r6  92c98224  r7  0000010c
129202-18 15:23:43.726  5319  5319 F DEBUG   :     r8  931cb3dd  r9  00000000  r10 a4a1f758  r11 00000000
129302-18 15:23:43.726  5319  5319 F DEBUG   :     ip  00000041  sp  92c98210  lr  aeda50e5  pc  aed9ce72
129402-18 15:23:43.776  5319  5319 F DEBUG   :
129502-18 15:23:43.776  5319  5319 F DEBUG   : backtrace:
129602-18 15:23:43.776  5319  5319 F DEBUG   :     #00 pc 0001ce72  /system/lib/libc.so (abort+62)
129702-18 15:23:43.776  5319  5319 F DEBUG   :     #01 pc 0010e3fb  /system/lib/libbluetooth.so (hci_timeout_abort(void*)+30)
129802-18 15:23:43.776  5319  5319 F DEBUG   :     #02 pc 001ab9bd  /system/lib/libbluetooth.so (alarm_ready_generic(alarm_t*, std::__1::unique_lock<std::__1::mutex>&)+164)
129902-18 15:23:43.776  5319  5319 F DEBUG   :     #03 pc 001ab8db  /system/lib/libbluetooth.so (alarm_queue_ready(fixed_queue_t*, void*)+66)
130002-18 15:23:43.776  5319  5319 F DEBUG   :     #04 pc 001ae4b5  /system/lib/libbluetooth.so (internal_dequeue_ready(void*)+48)
130102-18 15:23:43.776  5319  5319 F DEBUG   :     #05 pc 001b06c3  /system/lib/libbluetooth.so (run_reactor(reactor_t*, int)+218)
130202-18 15:23:43.776  5319  5319 F DEBUG   :     #06 pc 001b05bd  /system/lib/libbluetooth.so (reactor_start(reactor_t*)+44)
130302-18 15:23:43.776  5319  5319 F DEBUG   :     #07 pc 001b1757  /system/lib/libbluetooth.so (run_thread(void*)+142)
130402-18 15:23:43.776  5319  5319 F DEBUG   :     #08 pc 00063c15  /system/lib/libc.so (__pthread_start(void*)+22)
130502-18 15:23:43.776  5319  5319 F DEBUG   :     #09 pc 0001e065  /system/lib/libc.so (__start_thread+22)
130602-18 15:23:44.372  3208  3208 E /system/bin/tombstoned: Tombstone written to: /data/tombstones/tombstone_02
130702-18 15:23:44.387  3310  3330 I BootReceiver: Copying /data/tombstones/tombstone_02 to DropBox (SYSTEM_TOMBSTONE)
130802-18 15:23:44.429  3310  3326 I ActivityManager: Showing crash dialog for package com.android.bluetooth u0
130902-18 15:23:44.432  3310  3325 W BroadcastQueue: Background execution not allowed: receiving Intent { act=android.intent.action.DROPBOX_ENTRY_ADDED flg=0x10 (has extras) } to com.google.android.gms/.stats.service.DropBoxEntryAddedReceiver
131002-18 15:23:44.432  3310  3325 W BroadcastQueue: Background execution not allowed: receiving Intent { act=android.intent.action.DROPBOX_ENTRY_ADDED flg=0x10 (has extras) } to com.google.android.gms/.chimera.GmsIntentOperationService$PersistentTrustedReceiver
131102-18 15:23:44.432  3310  3325 W BroadcastQueue: Background execution not allowed: receiving Intent { act=android.intent.action.DROPBOX_ENTRY_ADDED flg=0x10 (has extras) } to com.google.android.gms/.stats.service.DropBoxEntryAddedReceiver
131202-18 15:23:44.433  3310  3325 W BroadcastQueue: Background execution not allowed: receiving Intent { act=android.intent.action.DROPBOX_ENTRY_ADDED flg=0x10 (has extras) } to com.google.android.gms/.chimera.GmsIntentOperationService$PersistentTrustedReceiver
131302-18 15:23:44.442  3084  3084 I Zygote  : Process 5278 exited due to signal (6)
131402-18 15:23:44.442  3091  3091 E android.hardware.bluetooth@1.0-impl: BluetoothDeathRecipient::serviceDied - Bluetooth service died
131502-18 15:23:44.442  3091  3091 I android.hardware.bluetooth@1.0-impl: BluetoothHci::close()
131602-18 15:23:44.442  3091  3091 I android.hardware.bluetooth@1.0-impl: Skipping unlink call, service died.
131702-18 15:23:44.442  3091  3091 E [BT]    : mtk_bt_op: BT_VND_OP_LPM_SET_MODE 0
131802-18 15:23:44.442  3091  3091 D android.hardware.bluetooth@1.0-impl: low_power_mode_cb result: 0
131902-18 15:23:44.442  3091  3091 E [BT]    : mtk_bt_op: BT_VND_OP_USERIAL_CLOSE
132002-18 15:23:44.452  3310  3952 I ActivityManager: Process com.android.bluetooth (pid 5278) has died: psvc PER
132102-18 15:23:44.452  3310  3952 W ActivityManager: Scheduling restart of crashed service com.android.bluetooth/.btservice.AdapterService in 1000ms
132202-18 15:23:44.453  3310  3328 W libprocessgroup: kill(-5278, 9) failed: No such process
132302-18 15:23:44.453  3310  3328 I libprocessgroup: Successfully killed process cgroup uid 1002 pid 5278 in 0ms
132402-18 15:23:44.458  3091  3091 E [BT]    : mtk_bt_op: BT_VND_OP_POWER_CTRL 0
132502-18 15:23:44.458  3091  3091 E [BT]    : mtk_bt_cleanup #288
132602-18 15:23:44.517  3100  3229 I [Gralloc]: framebuffer hal alread move to hwcomposer
132702-18 15:23:44.518  3310  3320 I system_server: Background concurrent copying GC freed 32959(1593KB) AllocSpace objects, 6(424KB) LOS objects, 19% free, 9MB/11MB, paused 322us total 138.986ms
132802-18 15:23:44.541  3100  3229 I [Gralloc]: framebuffer hal alread move to hwcomposer
132902-18 15:23:45.471  3310  3327 I ActivityManager: Start proc 5337:com.android.bluetooth/1002 for service com.android.bluetooth/.btservice.AdapterService
133002-18 15:23:44.563  3100  3229 I [Gralloc]: framebuffer hal alread move to hwcomposer
133102-18 15:23:45.475  5337  5337 I Zygote  : seccomp disabled by setenforce 0
133202-18 15:23:45.484  5337  5337 W droid.bluetoot: Using default instruction set features for ARM CPU variant (cortex-a9) using conservative defaults
133302-18 15:23:45.537  5337  5337 I droid.bluetoot: The ClassLoaderContext is a special shared library.
133402-18 15:23:45.539  5337  5337 I droid.bluetoot: The ClassLoaderContext is a special shared library.
133502-18 15:23:45.576  5337  5337 D BluetoothOppFileProvider: Initialized
133602-18 15:23:45.592  5337  5337 V AdapterServiceConfig: Adding HeadsetService
133702-18 15:23:45.592  5337  5337 V AdapterServiceConfig: Adding A2dpService
133802-18 15:23:45.593  5337  5337 V AdapterServiceConfig: Adding HidHostService
133902-18 15:23:45.593  5337  5337 V AdapterServiceConfig: Adding HealthService
134002-18 15:23:45.593  5337  5337 V AdapterServiceConfig: Adding PanService
134102-18 15:23:45.593  5337  5337 V AdapterServiceConfig: Adding GattService
134202-18 15:23:45.593  5337  5337 V AdapterServiceConfig: Adding BluetoothMapService
134302-18 15:23:45.593  5337  5337 V AdapterServiceConfig: Adding AvrcpTargetService
134402-18 15:23:45.593  5337  5337 V AdapterServiceConfig: Adding HidDeviceService
134502-18 15:23:45.593  5337  5337 V AdapterServiceConfig: Adding BluetoothOppService
134602-18 15:23:45.593  5337  5337 V AdapterServiceConfig: Adding BluetoothPbapService
134702-18 15:23:45.604  5337  5337 I         : [0218/152345.604365:INFO:com_android_bluetooth_btservice_AdapterService.cpp(630)] hal_util_load_bt_library loaded HAL: btinterface=0x93308234, handle=0x5361bda3
134802-18 15:23:45.605  5337  5337 D BluetoothAdapterService: onCreate()
134902-18 15:23:45.609  5337  5337 D AdapterState: make() - Creating AdapterState
135002-18 15:23:45.610  5337  5337 I bt_btif : init
135102-18 15:23:45.610  5337  5353 I AdapterState: OFF : entered
135202-18 15:23:45.610  5337  5353 D AdapterProperties: Setting state to OFF
135302-18 15:23:45.610  5337  5337 D bt_osi_allocation_tracker: canary initialized
135402-18 15:23:45.611  5337  5354 I bt_osi_thread: run_thread: thread id 5354, thread name stack_manager started
135502-18 15:23:45.611  5337  5354 I bt_stack_manager: event_init_stack is initializing the stack
135602-18 15:23:45.613  5337  5357 I bt_osi_thread: run_thread: thread id 5357, thread name alarm_default_ca started
135702-18 15:23:45.614  5337  5358 I bt_osi_thread: run_thread: thread id 5358, thread name alarm_dispatcher started
135802-18 15:23:45.616  5337  5354 I bt_btif_core: btif_init_bluetooth entered
135902-18 15:23:45.616  5337  5354 I bt_stack_config: init attempt to load stack conf from /etc/bluetooth/bt_stack.conf
136002-18 15:23:45.617  5337  5359 I bt_osi_thread: run_thread: thread id 5359, thread name bt_jni_workqueue started
136102-18 15:23:45.617  5337  5354 I bt_btif_core: btif_init_bluetooth finished
136202-18 15:23:45.617  5337  5354 I bt_stack_manager: event_init_stack finished
136302-18 15:23:45.617  5337  5359 I bt_btif_core: run_message_loop entered
136402-18 15:23:45.617  5337  5337 I bt_osi_wakelock: wakelock_set_os_callouts set to non-native
136502-18 15:23:45.617  5337  5337 I bt_btif : get_profile_interface: id = socket
136602-18 15:23:45.617  5337  5359 E bt_btif_storage: btif_storage_get_adapter_property: Controller not ready! Unable to return Bluetooth Address
136702-18 15:23:45.617  5337  5359 E BluetoothServiceJni: adapter_properties_callback: Status 1 is incorrect
136802-18 15:23:45.618  5337  5337 I bt_btif : get_profile_interface: id = sdp
136902-18 15:23:45.619  3310  3310 D BluetoothManagerService: Bluetooth Adapter name changed to Android KM9
137002-18 15:23:45.619  5337  5359 D AdapterProperties: Name is: Android KM9
137102-18 15:23:45.619  3310  3310 D BluetoothManagerService: Stored Bluetooth name: Android KM9
137202-18 15:23:45.619  5337  5359 D AdapterProperties: BT Class:200424
137302-18 15:23:45.621  5337  5337 I BluetoothAdapterService: Phone policy enabled
137402-18 15:23:45.623  5337  5337 D BluetoothActiveDeviceManager: start()
137502-18 15:23:45.627  5337  5337 D BluetoothAdapterService: setAdapterService() - trying to set service to com.android.bluetooth.btservice.AdapterService@572578a
137602-18 15:23:45.627  5337  5360 D BluetoothActiveDeviceManager: onAudioDevicesAdded
137702-18 15:23:45.627  5337  5360 D BluetoothActiveDeviceManager: Audio device added: Android KM9 type: 9
137802-18 15:23:45.627  5337  5360 D BluetoothActiveDeviceManager: Audio device added: Android KM9 type: 2
137902-18 15:23:45.627  5337  5360 D BluetoothActiveDeviceManager: Audio device added: Android KM9 type: 15
138002-18 15:23:45.627  5337  5360 D BluetoothActiveDeviceManager: Audio device added: Android KM9 type: 15
138102-18 15:23:45.627  5337  5360 D BluetoothActiveDeviceManager: Audio device added: USB-Audio - USB Device 0x1d57:0xad03 type: 11
138202-18 15:23:45.631  5337  5337 D BluetoothAdapterService: onBind()
138302-18 15:23:45.632  3310  3310 D BluetoothManagerService: BluetoothServiceConnection: com.android.bluetooth.btservice.AdapterService
138402-18 15:23:45.632  3310  3332 D BluetoothManagerService: MESSAGE_BLUETOOTH_SERVICE_CONNECTED: 1
138502-18 15:23:45.634  3310  3332 D BluetoothManagerService: Broadcasting onBluetoothServiceUp() to 6 receivers.
138602-18 15:23:45.634  3310  3332 D BluetoothAdapter: onBluetoothServiceUp: android.bluetooth.IBluetooth$Stub$Proxy@6c5323
138702-18 15:23:45.634  4663  4680 D BluetoothAdapter: onBluetoothServiceUp: android.bluetooth.IBluetooth$Stub$Proxy@4e1a17e
138802-18 15:23:45.634  3496  3514 D BluetoothAdapter: onBluetoothServiceUp: android.bluetooth.IBluetooth$Stub$Proxy@c0e3573
138902-18 15:23:45.635  3553  4083 D BluetoothAdapter: onBluetoothServiceUp: android.bluetooth.IBluetooth$Stub$Proxy@65e7868
139002-18 15:23:45.635  3842  3856 D BluetoothAdapter: onBluetoothServiceUp: android.bluetooth.IBluetooth$Stub$Proxy@668e3c9
139102-18 15:23:45.635  5337  5350 D BluetoothAdapterService: enable() - Enable called with quiet mode status =  false
139202-18 15:23:45.635  5337  5351 D BluetoothAdapter: onBluetoothServiceUp: com.android.bluetooth.btservice.AdapterService$AdapterServiceBinder@832148
139302-18 15:23:45.636  5337  5353 I AdapterState: BLE_TURNING_ON : entered
139402-18 15:23:45.636  5337  5353 D AdapterProperties: Setting state to BLE_TURNING_ON
139502-18 15:23:45.636  5337  5353 D BluetoothAdapterService: updateAdapterState() - Broadcasting state BLE_TURNING_ON to 1 receivers.
139602-18 15:23:45.636  5337  5353 D BluetoothAdapterService: bleOnProcessStart()
139702-18 15:23:45.636  3310  3332 D BluetoothManagerService: MESSAGE_BLUETOOTH_STATE_CHANGE: OFF > BLE_TURNING_ON
139802-18 15:23:45.636  3310  3332 D BluetoothManagerService: Sending BLE State Change: OFF > BLE_TURNING_ON
139902-18 15:23:45.636  5337  5353 I AdapterProperties: init(), maxConnectedAudioDevices, default=5, propertyOverlayed=5, finalValue=5
140002-18 15:23:45.639  5337  5353 D BluetoothAdapterService: bleOnProcessStart() - Make Bond State Machine
140102-18 15:23:45.639  5337  5353 D BluetoothBondStateMachine: make
140202-18 15:23:45.643  5337  5366 I BluetoothBondStateMachine: StableState(): Entering Off State
140302-18 15:23:45.644  5337  5337 I BtGatt.JNI: classInitNative(L875): classInitNative: Success!
140402-18 15:23:45.647  5337  5337 D BtGatt.DebugUtils: handleDebugAction() action=null
140502-18 15:23:45.648  5337  5337 D BluetoothAdapterService: getAdapterService() - returning com.android.bluetooth.btservice.AdapterService@572578a
140602-18 15:23:45.649  5337  5337 I bt_btif : get_profile_interface: id = gatt
140702-18 15:23:45.650  5337  5337 D BluetoothAdapterService: getAdapterService() - returning com.android.bluetooth.btservice.AdapterService@572578a
140802-18 15:23:45.666  5337  5337 D BluetoothAdapterService: getAdapterService() - returning com.android.bluetooth.btservice.AdapterService@572578a
140902-18 15:23:45.667  5337  5337 D BluetoothAdapterService: handleMessage() - Message: 2
141002-18 15:23:45.667  5337  5337 D BluetoothAdapterService: handleMessage() - MESSAGE_PROFILE_SERVICE_REGISTERED
141102-18 15:23:45.667  5337  5337 D BluetoothAdapterService: handleMessage() - Message: 1
141202-18 15:23:45.667  5337  5337 D BluetoothAdapterService: handleMessage() - MESSAGE_PROFILE_SERVICE_STATE_CHANGED
141302-18 15:23:45.668  5337  5337 I bt_btif : enable: start restricted = 0
141402-18 15:23:45.668  5337  5354 I bt_stack_manager: event_start_up_stack is bringing up the stack
141502-18 15:23:45.668  5337  5354 I bt_core_module: module_start_up Starting module "btif_config_module"
141602-18 15:23:45.668  5337  5354 I bt_core_module: module_start_up Started module "btif_config_module"
141702-18 15:23:45.668  5337  5354 I bt_core_module: module_start_up Starting module "btsnoop_module"
141802-18 15:23:45.668  5337  5354 I bt_core_module: module_start_up Started module "btsnoop_module"
141902-18 15:23:45.669  5337  5354 I bt_core_module: module_start_up Starting module "hci_module"
142002-18 15:23:45.669  5337  5354 I bt_hci  : hci_module_start_up
142102-18 15:23:45.669  5337  5370 I bt_osi_thread: run_thread: thread id 5370, thread name hci_thread started
142202-18 15:23:45.669  5337  5370 I bt_hci  : hci_initialize
142302-18 15:23:45.671  5337  5354 D bt_hci  : hci_module_start_up starting async portion
142402-18 15:23:45.672  5337  5370 I bt_hci  : hci_initialize: IBluetoothHci::getService() returned 0xa4a11260 (remote)
142502-18 15:23:45.673  3091  3091 I android.hardware.bluetooth@1.0-impl: BluetoothHci::initialize()
142602-18 15:23:45.674  3091  3091 E bda_generation: FallthroughBTA: No factory BDADDR found
142702-18 15:23:45.674  3091  3091 E [BT]    : mtk_bt_init: mtk_bt_init : VENDOR LIBRARY VERSION =700.0.17122701
142802-18 15:23:45.674  3091  3091 E [BT]    : mtk_bt_init #197
142902-18 15:23:45.674  3091  3091 D android.hardware.bluetooth@1.0-impl: Open vendor library loaded
143002-18 15:23:45.674  3091  3091 E [BT]    : mtk_bt_op: BT_VND_OP_POWER_CTRL 1
143102-18 15:23:45.674  3091  3091 E [BT]    : mtk_bt_op: BT_VND_OP_USERIAL_OPEN
143202-18 15:23:45.674  3091  3091 E [BT]    : init_uart #130
143302-18 15:23:45.674  3091  3091 D bt-platform: We're goting to access rfkill to power on this device(sdk: 28) by default...
143402-18 15:23:45.674  3091  3091 D bt-platform: rfkill state path init successed: /sys/class/rfkill/rfkill0/state
143502-18 15:23:45.674  3091  3091 D bt-platform: open /sys/class/rfkill/rfkill0/state :6(Success)
143602-18 15:23:46.099  3091  3091 D bt-platform: rfkill_operations: power up done(Success)!
143702-18 15:23:46.100  3091  3091 D bt-platform: ismod_bt_driver: driver_status = true
143802-18 15:23:46.100  3091  3091 W bt-platform: ismod_bt_driver: btmtksdio.ko is already insmod!
143902-18 15:23:46.300  3091  3091 D [BT]    : init_uart: attemping(1) to open stpbt...