Thank you very much for taking the time to record a video.
If you enable the bar on your phone:
System -> Buttons -> Enable on-screen nav bar
then when you turn off the phone it will be more noticeable how the screen goes out and part of this bar is still running in the background.
My guess is that the Android code is getting bigger and bigger and is becoming BLOWED UP and soon, if not already now, we will all have to go to a technical institute to learn how to use a phone)))
Thanks for your reply.
I have a test phone, it has no other apps, so now when I report strange behavior of the phone when turned off, 20 processes are running (including apps)
System -> Developer options -> Running services
There is a support page: Capture a debug information from /e/OS.
logcat is a command out of adb. It is worth doing a few practice runs, the files can be enormous – in common with other adb commands it only works with a device connected,
adb logcat --help
is quite informative.
I’ve tried adb logcat -d > logcat.txt but it give output for the 5 previous minutes and my event was older.
so i reproduce the step (which include rebooting the phone) and the logcat start after the reboot …
I’ve tried logfox also, but it dont record on shuntdown …
sorry, I’ve tried my best, but now this is over.
Hi @didou I think, perhaps, you missed the event you wanted due to the -d option, (Edit, specifically -d → dump & exit).
Start with
adb devices
adb logcat --help # Will only work with a suitable device connected and detected
option -d does not work for all situations, it records the event to the moment of generating the logcat and no more,
Where you do not yet have a real event to look for, restart the device, so you potentially reduce the size of output.
Try
adb logcat > logcat1.txt
adb devices
adb logcat -d > logcat2.txt
Search for “beginning” – this example found six. Note the time of first entry
--------- beginning of kernel
05-05 00:48:36.523 0 0 I : [ 0.000000,0] Booting Linux on physical CPU 0x0
--------- beginning of system
05-05 00:48:36.682 576 576 I vold : Vold 3.0 (the awakening) firing up
Seen beginning of crash
--------- beginning of main
Next two beginnings were regarding noticfications.
V Verbose
D Debug
I Info
W Warn
E Error
F Fatal
S Silent (suppress all output)
Search the output for instance ERROR by searching for “space E space”
Check for possible Shutdown error.
adb logcat > logcat3.txt
Output is 16989 lines in a test of short time from Start.
Many lines seemed benign, then these lines seemed of interest.
11-02 09:42:33.425 1604 4882 W ProcessStatsService: Writing process stats before shutdown...
11-02 09:42:33.431 1604 4882 I ProcessStatsService: Pruning old procstats: /data/system/procstats/state-v2-2024-10-31-21-02-45.bin
11-02 09:42:33.432 1604 4882 I ShutdownThread: Shutting down package manager...
11-02 09:42:33.440 1604 4882 I PackageWatchdog: Not pruning observers, elapsed time: 0ms
11-02 09:42:33.440 1604 4882 I PackageWatchdog: Saving observer state to file
11-02 09:42:33.442 1604 4882 I PackageWatchdog: Last write to update package durations
11-02 09:42:33.448 1604 4893 W ShutdownThread: Turning off cellular radios...
11-02 09:42:33.448 2412 2624 V PhoneInterfaceManager: [PhoneIntfMgr] Shutting down Phone 0
11-02 09:42:33.454 1604 4893 I ShutdownThread: Waiting for Radio...
11-02 09:42:34.362 1604 4882 I ShutdownThread: Performing low-level shutdown...
11-02 09:42:34.365 0 0 I [ 68.743976,5] init: Received sys.powerctl='shutdown,userrequested' from pid: 1604 (system_server)
11-02 09:42:34.365 0 0 I [ 68.743994,5] init: sys.powerctl: do_shutdown: 0 IsShuttingDown: 0
11-02 09:42:34.574 0 0 W : [ 68.954325,4] MSM-CPP msm_cpp_subdev_ioctl:3531 shutdown cpp node. open cnt:0
11-02 09:42:34.580 0 0 I [ 68.954328,3] msm_pm_qos_remove_request: remove request
11-02 09:42:34.580 0 0 I [ 68.960103,3] smd_pkt_open: wait on smd_pkt_dev id:3 OPEN interrupted
11-02 09:42:34.583 0 0 I [ 68.963390,6] mdss_dsi_panel_off: ctrl=0000000000000000 ndx=0
11-02 09:42:34.600 801 1571 I statsd : StatsService::Terminating
11-02 09:42:34.602 0 0 E [ 68.980217,5] [RMNET: HI] rmnet_config_notify_cb(): Kernel is trying to unregister r_rmnet_data6
11-02 09:42:34.600 801 1571 I statsd : Statsd skipping writing data to disk. Already wrote data in last 15 seconds
11-02 09:42:34.602 0 0 E [ 68.980601,5] [RMNET: HI] rmnet_config_notify_cb(): Kernel is trying to unregister r_rmnet_data7
11-02 09:42:34.600 801 1571 I statsd : Statsd skipping writing active metrics to disk. Already wrote data in last 15 seconds
11-02 09:42:34.600 801 1571 I statsd : Statsd skipping writing metadata to disk. Already wrote data in last 15 seconds
11-02 09:42:34.600 801 1571 I BpBinder: onLastStrongRef automatically unlinking death recipients: android.os.IPendingIntentRef
11-02 09:42:34.601 801 1571 W RefBase : RefBase: Explicit destruction, weak count = 0 (in 0x595a61c1a8). Use sp<> to manage this object.
11-02 09:42:34.601 801 1571 W RefBase : CallStack::getCurrentInternal not linked, returning null
11-02 09:42:34.601 801 1571 W RefBase : CallStack::logStackInternal not linked
11-02 09:42:34.627 2003 2003 D wpa_supplicant: Control interface directory not empty - leaving it behind
11-02 09:42:34.627 2003 2003 D wpa_supplicant: Deiniting aidl control
11-02 09:42:34.646 0 0 E [ 69.023724,5] [RMNET: HI] rmnet_config_notify_cb(): Kernel is trying to unregister r_rmnet_data7
11-02 09:42:34.646 0 0 E [ 69.024168,5] [RMNET: HI] rmnet_config_notify_cb(): Kernel is trying to unregister r_rmnet_data8
11-02 09:42:34.646 0 0 I : [ 69.026309,6] mdss_dsi_off[0]+.
11-02 09:42:34.659 0 0 E [ 69.038663,6] [09: 42:34.658258] [0000000059E212E5] [VosMC] wlan: [E :HDD] wlan: disconnected
11-02 09:42:34.659 0 0 E [ 69.038704,6] [09: 42:34.658299] [0000000059E21609] [VosMC] wlan: [E :HDD] wlan(0) 00:00:00:00:00:00 Standalone
11-02 09:42:34.666 0 0 I : [ 69.041956,6] mdss_dsi_off[0]-.
11-02 09:42:34.687 0 0 E [ 69.063552,5] [RMNET: HI] rmnet_config_notify_cb(): Kernel is trying to unregister r_rmnet_data8
11-02 09:42:34.773 1183 1669 E QC-NETMGR-LIB: serviceDied(): Netd service died
11-02 09:42:34.803 0 0 I [ 69.181477,6] binder: undelivered transaction 97427, process died.
11-02 09:42:34.803 0 0 I [ 69.181506,6] binder: undelivered transaction 97426, process died.
11-02 09:42:34.853 0 0 I [ 69.232382,6] binder: undelivered transaction 97478, process died.
11-02 09:42:34.861 0 0 I [ 69.236994,7] send_filled_buffers_to_user: Send Failed -3 drop_count = 0
11-02 09:42:34.896 0 0 I : [ 69.275614,6] mtp_release
11-02 09:42:34.896 4925 4925 I NetUtilsWrapper: Unexpected command: /system/bin/ip -4 rule del prio 11999
11-02 09:42:34.939 0 0 I [ 69.314766,6] binder: release 4827:4827 transaction 97474 out, still active
11-02 09:42:34.939 0 0 I [ 69.314774,6] binder: undelivered TRANSACTION_COMPLETE
11-02 09:42:34.939 0 0 I [ 69.319329,6] binder: release 886:886 transaction 96091 in, still active
11-02 09:42:35.102 2796 2799 I adbd : adbd_auth: hit EOF on framework fd
11-02 09:42:35.102 2796 2799 I adbd : adbd_auth: received new framework fd -1 (current = 19)
11-02 09:42:35.294 1183 1238 E power : IWakeLock::release() call failed: Status(-129, EX_TRANSACTION_FAILED): 'DEAD_OBJECT: '
11-02 09:42:35.456 0 0 I [ 69.833508,5] binder: undelivered TRANSACTION_COMPLETE
11-02 09:42:35.456 0 0 I [ 69.833512,5] binder: undelivered TRANSACTION_ERROR: 29189
11-02 09:42:35.456 0 0 I [ 69.833695,5] binder: undelivered TRANSACTION_COMPLETE
11-02 09:42:35.456 0 0 I [ 69.833698,5] binder: undelivered transaction 97501, process died.
11-02 09:42:35.456 0 0 I [ 69.833815,5] binder: release 1604:1994 transaction 97474 in, still active
11-02 09:42:35.456 0 0 I [ 69.833817,5] binder: undelivered TRANSACTION_COMPLETE
11-02 09:42:35.456 0 0 I [ 69.833818,5] binder: undelivered TRANSACTION_ERROR: 29189
11-02 09:42:36.716 0 0 E : [ 71.093366,6] [NVT-ts] nvt_esd_check_func 865: do ESD recovery, timer = 3017, retry = 0
11-02 09:42:36.716 0 0 W : [ 71.093376,6] do ESD recovery, timer = 3017, retry = 0
11-02 09:42:37.543 0 0 W [ 71.922866,4] init: 1 output lines suppressed due to ratelimiting
11-02 09:42:37.553 575 745 D vold : Aborting fuse connection entry /sys/fs/fuse/connections/148/abort
11-02 09:42:37.553 575 745 W vold : Skip fsync /sys/fs/fuse/connections/148/abort on a file system does not support synchronization: Invalid argument
11-02 09:42:37.554 575 745 D vold : Aborting fuse connection entry /sys/fs/fuse/connections/81/abort
11-02 09:42:37.554 575 745 W vold : Skip fsync /sys/fs/fuse/connections/81/abort on a file system does not support synchronization: Invalid argument
11-02 09:42:37.550 4948 4948 W vdc : type=1400 audit(0.0:41): avc: denied { kill } for uid=0 capability=5 scontext=u:r:vdc:s0 tcontext=u:r:vdc:s0 tclass=capability permissive=0
11-02 09:42:37.556 0 0 I [ 71.933488,5] binder: 4948:4948 ioctl 40046210 7fccd58a60 returned -22
11-02 09:42:37.556 0 0 D [ 71.934067,5] vdc: Waited 0ms for vold
11-02 09:42:37.566 0 0 I [ 71.946586,5] binder: 4949:4949 ioctl 40046210 7fffc0bfb0 returned -22
11-02 09:42:37.569 0 0 D [ 71.947281,6] vdc: Waited 0ms for vold
11-02 09:42:37.567 575 745 I vold : Killing all processes referencing /storage/emulated/0
11-02 09:42:37.633 575 745 I vold : Unmounting /mnt/installer/0/emulated/0/Android/obb
11-02 09:42:37.666 575 745 I vold : Unmounting /mnt/user/0/emulated/0/Android/data
11-02 09:42:37.686 575 745 I vold : Unmounted /mnt/user/0/emulated/0/Android/data
11-02 09:42:37.686 575 745 I vold : Unmounting /mnt/user/0/emulated/0/Android/obb
11-02 09:42:37.706 575 745 I vold : Unmounted /mnt/user/0/emulated/0/Android/obb
11-02 09:42:37.706 575 745 I vold : Unmounting fuse path /mnt/user/0/emulated
11-02 09:42:37.727 575 745 I vold : Unmounting pass_through_path /mnt/pass_through/0/emulated
11-02 09:42:37.888 575 745 I vold : Unmounting fuse path /mnt/user/0/7DF9-FC6C
11-02 09:42:37.896 575 745 I vold : Unmounting pass_through_path /mnt/pass_through/0/7DF9-FC6C
11-02 09:42:38.013 4949 4949 W vdc : type=1400 audit(0.0:42): avc: denied { kill } for uid=0 capability=5 scontext=u:r:vdc:s0 tcontext=u:r:vdc:s0 tclass=capability permissive=0
11-02 09:42:38.019 0 0 W : [ 72.397028,5] [EXFAT] trying to unmount...
11-02 09:42:38.019 0 0 W : [ 72.397073,5] [EXFAT] unmounted successfully
11-02 09:42:38.019 0 0 I [ 72.398539,5] init: Sending signal 9 to service 'vold' (pid 575) process group...
I will try this out tomorow !
Just wanted to confirm that i’m having the same issue on my S7, even though it has never been a significant problem. I did not even know it was incorrect until now.
Edit: My bad, i just misunderstood, i actually don’t have this issue. I can see the shutting down sxreen on my phone.
could you confirm the behavior with button Vs top panel ?
and do you have a screen lock or not ?
I’m afraid i haven’t really understood the difference between the 2 shutdown. I have watched your video, read a bit of the forum and seen that it corresponded to my own shutdown.
What do you mean by screen lock ? When my phone shuts down i can’t do anything about it once it’s launched, yes.
Edit: i never use the panel way to shut it down. I tried to see any difference. As usual it takes 7-10 seconds to shut down with a “screen lock”. Plus i’ve seen that the brightness reduces on the shuting down screen after a few seconds.
one is made by pushing the power button, the other one is made from the panel.
the proper term would rather be lock pattern
to lock your phone, you can choose a wipe gesture, a pin , a locking pattern or nothing.
Please read again the original post to check the behavior to be reproduced.
OK, so here are my outputs, cleared from personal data, as far as I’m aware (let me know if it’s not the case).
Looking for shutdown, one can find in logcat1 :
11-02 23:41:09.451 3827 4162 V ShutdownCheckPoints: Binder shutdown checkpoint recorded with pid=3982
11-02 23:41:09.451 3827 3845 V WindowManager: Unknown focus tokens, dropping reportFocusChanged
11-02 23:41:09.453 3827 3827 V ShutdownCheckPoints: System server shutdown checkpoint recorded
11-02 23:41:09.453 3827 3827 D ShutdownThread: Notifying thread to start shutdown longPressBehavior=1
11-02 23:41:09.456 3827 3827 D ShutdownThread: Attempting to use SysUI shutdown UI
11-02 23:41:09.457 3827 3827 D ShutdownThread: SysUI handling shutdown UI
11-02 23:41:09.465 3827 5982 I ShutdownThread: Logging pre-reboot information...
11-02 23:41:09.466 3827 5982 D PreRebootLogger: Wiping pre-reboot information...
11-02 23:41:09.466 0 0 W : [0: irq/6-arizona: 1269] arizona spi0.0: Spurious HPDET IRQ
11-02 23:41:09.466 3827 5982 I ShutdownThread: Sending shutdown broadcast...
11-02 23:41:09.469 3827 3827 I BluetoothManagerService: Device is shutting down.
11-02 23:41:09.475 3827 3827 W SyncManager: Writing sync state before shutdown...
11-02 23:41:09.844 3827 3858 E system_server: Cannot read thread CPU times for PID 3827
11-02 23:41:09.880 3827 5982 W ProcessStatsService: Writing process stats before shutdown...
11-02 23:41:09.892 3827 5982 I ShutdownThread: Shutting down package manager...
11-02 23:41:09.917 3827 5982 I PackageWatchdog: Not pruning observers, elapsed time: 0ms
11-02 23:41:09.917 3827 5982 I PackageWatchdog: Saving observer state to file
11-02 23:41:09.921 3827 5982 I PackageWatchdog: Last write to update package durations
11-02 23:41:09.931 3827 5993 W ShutdownThread: Turning off cellular radios...
11-02 23:41:09.932 4270 4428 V PhoneInterfaceManager: [PhoneIntfMgr] Shutting down Phone 0
11-02 23:41:09.943 3827 5993 I ShutdownThread: Waiting for Radio...
11-02 23:41:09.995 3827 3932 D CompatibilityChangeReporter: Compat change id reported: 124107808; UID 1001; state: ENABLED
11-02 23:41:10.002 3982 3982 V KeyguardUpdateMonitor: onSubscriptionInfoChanged()
11-02 23:41:10.047 4270 5020 V PhoneInterfaceManager: [PhoneIntfMgr] 1 Phones are shutdown.
11-02 23:41:10.047 3827 5993 I ShutdownThread: Radio turned off.
11-02 23:41:10.048 3827 5993 V ShutdownTiming: ShutdownRadio took to complete: 117ms
11-02 23:41:10.048 3827 5993 I ShutdownThread: Radio shutdown complete.
11-02 23:41:10.272 4190 4190 I Choreographer: Skipped 35 frames! The application may be doing too much work on its main thread.
11-02 23:41:10.385 3827 3905 I InputDispatcher: Dropped event because input dispatch is disabled.
11-02 23:41:10.553 3827 5982 I ShutdownThread: Performing low-level shutdown...
11-02 23:41:10.559 0 0 I [1: init: 2364] init: Received sys.powerctl='shutdown,userrequested' from pid: 3827 (system_server)
11-02 23:41:10.559 0 0 I [1: init: 2364] init: sys.powerctl: do_shutdown: 0 IsShuttingDown: 0
11-02 23:41:10.559 0 0 I [3: init: 1] init: Got shutdown_command 'shutdown,userrequested' Calling HandlePowerctlMessage()
11-02 23:41:10.559 0 0 I [3: init: 1] init: Clear action queue and start shutdown trigger
11-02 23:41:10.559 0 0 I [3: init: 1] init: Entering shutdown mode
11-02 23:41:10.560 0 0 I [3: init: 1] init: processing action (shutdown) from (/vendor/etc/init/hw/init.samsungexynos8890.rc:978)
11-02 23:41:10.560 0 0 I : [0:max77854_haptic: 1729] [SSP] ssp_motor_callback : Motor state 0
11-02 23:41:10.560 0 0 D : [0:max77854_haptic: 1729] [VIB] off
11-02 23:41:10.564 0 0 D : [2:android.hardwar: 3638] [VIB] 25737 0ms
11-02 23:41:10.564 0 0 I [3: init: 1] init: processing action (shutdown_done) from (<Builtin Action>:0)
11-02 23:41:10.564 0 0 I [3: init: 1] init: Reboot start, reason: shutdown,userrequested, reboot_target:
11-02 23:41:10.564 0 0 I [3: init: 1] init: Shutdown timeout: 6000 ms
11-02 23:41:10.564 0 0 I [3: init: 1] init: Create reboot monitor thread.
11-02 23:41:10.564 0 0 I : [2:android.hardwar: 3638] [SSP] ssp_motor_callback : Motor state 0
11-02 23:41:10.564 0 0 D : [2:android.hardwar: 3638] [VIB] off
11-02 23:41:10.566 0 0 I : [2: kworker/u16:2: 1291] [SSP] send_motor_state - En : 0
11-02 23:41:10.566 0 0 I : [2: kworker/u16:2: 1291] [SSP] ssp_motor_work_func : Motor state 0, iRet 0
11-02 23:41:10.566 0 0 I [4: init: 5996] init: shutdown_timeout_timespec.tv_sec: 440
11-02 23:41:13.733 4674 4777 I FuseDaemon: DESTROY /storage/emulated
11-02 23:41:13.734 0 0 D [4: vdc: 5999] vdc: Waited 0ms for vold
11-02 23:41:13.733 4674 4777 I FuseDaemon: Ended fuse
11-02 23:41:13.738 0 0 I [2: init: 1] init: Calling /system/bin/vdc volume shutdown
11-02 23:41:13.734 4674 4777 I FuseDaemonThread: Exiting thread for emulated;0 ...
11-02 23:41:13.734 4674 4777 I ExternalStorageServiceImpl: Exiting session for id: emulated;0
11-02 23:41:13.734 4674 4777 I FuseDaemonThread: Exited thread for emulated;0
11-02 23:41:14.305 0 0 I [3: init: 1] init: sync() before umount took12ms
11-02 23:41:14.305 0 0 I [3: init: 1] init: No zram backing device configured
11-02 23:41:14.305 0 0 I [3: init: 1] init: Ready to unmount apexes. So far shutdown sequence took 3741ms
Edit: I never get those flashing touch buttons as shown in @Didou 's video (second shutdown via power button) in a previous post.
Hi @VinK3645,
I think the main worrying thing about @tom1995’s report is that the phone fails to show a clear “Shutting down …” message on the screen; then (have I understood fully ?) a clear “Restarting …” on the screen following such shutdown.
This would be a bit like what I see on a PC going to suspend.
It is said that a Fairphone can go to a non-shutdown state by pressing the power button and that users find this confusing (so would I !).
Do you think that you can reproduce specifically the state of "fails to show a clear “Shutting down …” message on the screen; then a clear “Restarting …” on the screen following such shutdown ?
I tried to run through your logs @Didou really looking for similarities with my shutdown log taken from Motorola G7 Power ocean which as far as I am concerned is shutting down correctly.
At first sight I see a lot of similarity with both with your logcat1.txt and logcat2.txt and the one from my Moto.
In a very rough comparison … things which look similar between yours and mine. I quote here from logcat1.txt (as you did) I spent less time on logcat2.txt as I began to see patterns repeated. I have to say that this in not especially well informed and includes guesses at what I consider benign.
I started with a search for shutdown finding 64 matches (very close to my log, 66 matches).
Note on priorities:
V Verbose
D Debug
I Info
W Warn
E Error
F Fatal
The first 5 or so “shutdown mentioned” are low priority D, I, V even if apparently negative till the first success
11-02 23:39:16.734 3827 3845 D SystemServerInitThreadPool: Shutdown successful
Negative patch here, priority E, W seen
11-02 23:41:09.669 3827 5982 W AppOps : Writing app ops before shutdown...
11-02 23:41:09.673 3827 3841 D WifiMemoryStoreImpl: Set cluster G8b8e935b13dc7338 for W8b8e935b13dc7338: SUCCESS
11-02 23:41:09.727 3827 5982 I UsageStatsService: User[0] Flushing usage stats to disk
11-02 23:41:09.753 3827 5982 E UsageStatsService: Failed to write /data/system_de/usagestats/globalcomponentusage
11-02 23:41:09.766 3827 5982 W BatteryStats: Writing battery stats before shutdown...
11-02 23:41:09.814 3827 3840 D TelephonyManager: requestModemActivityInfo: Sending result to app: ModemActivityInfo{ mTimestamp=133340 mSleepTimeMs=0 mIdleTimeMs=113308 mTxTimeMs[]=[0, 0, 0, 0, 0] mRxTimeMs=2909}
11-02 23:41:09.836 3827 3858 W KernelCpuProcStringReader: File not found. It's normal if not implemented: /proc/uid_concurrent_active_time
11-02 23:41:09.838 3827 3858 W KernelCpuProcStringReader: File not found. It's normal if not implemented: /proc/uid_concurrent_policy_time
11-02 23:41:09.842 3827 3858 E system_server: Cannot obtain CPU frequency count
11-02 23:41:09.844 3827 3858 E system_server: Cannot obtain CPU frequency count
11-02 23:41:09.844 3827 3858 E system_server: Cannot obtain CPU frequency count
11-02 23:41:09.844 3827 3858 E system_server: Cannot read thread CPU times for PID 3827
11-02 23:41:09.880 3827 5982 W ProcessStatsService: Writing process stats before shutdown...
All good ? – Priority I, D, 2 x E are “only” No such file or directory
11-02 23:41:10.553 3827 5982 I ShutdownThread: Performing low-level shutdown...
11-02 23:41:10.559 0 0 I [1: init: 2364] init: Received sys.powerctl='shutdown,userrequested' from pid: 3827 (system_server)
11-02 23:41:10.559 0 0 I [1: init: 2364] init: sys.powerctl: do_shutdown: 0 IsShuttingDown: 0
11-02 23:41:10.559 0 0 I [3: init: 1] init: Got shutdown_command 'shutdown,userrequested' Calling HandlePowerctlMessage()
11-02 23:41:10.559 0 0 I [3: init: 1] init: Clear action queue and start shutdown trigger
11-02 23:41:10.559 0 0 I [3: init: 1] init: Entering shutdown mode
11-02 23:41:10.560 0 0 I [3: init: 1] init: processing action (shutdown) from (/vendor/etc/init/hw/init.samsungexynos8890.rc:978)
11-02 23:41:10.560 0 0 I : [0:max77854_haptic: 1729] [SSP] ssp_motor_callback : Motor state 0
11-02 23:41:10.560 0 0 D : [0:max77854_haptic: 1729] [VIB] off
11-02 23:41:10.564 0 0 D : [2:android.hardwar: 3638] [VIB] 25737 0ms
11-02 23:41:10.564 0 0 I [3: init: 1] init: processing action (shutdown_done) from (<Builtin Action>:0)
11-02 23:41:10.564 0 0 I [3: init: 1] init: Reboot start, reason: shutdown,userrequested, reboot_target:
11-02 23:41:10.564 0 0 I [3: init: 1] init: Shutdown timeout: 6000 ms
11-02 23:41:10.564 0 0 I [3: init: 1] init: Create reboot monitor thread.
11-02 23:41:10.564 0 0 I : [2:android.hardwar: 3638] [SSP] ssp_motor_callback : Motor state 0
11-02 23:41:10.564 0 0 D : [2:android.hardwar: 3638] [VIB] off
11-02 23:41:10.566 0 0 I : [2: kworker/u16:2: 1291] [SSP] send_motor_state - En : 0
11-02 23:41:10.566 0 0 I : [2: kworker/u16:2: 1291] [SSP] ssp_motor_work_func : Motor state 0, iRet 0
11-02 23:41:10.566 0 0 I [4: init: 5996] init: shutdown_timeout_timespec.tv_sec: 440
11-02 23:41:10.571 0 0 I : [3: kworker/u16:2: 1291] [SSP] send_motor_state - En : 0
11-02 23:41:10.571 0 0 I : [3: kworker/u16:2: 1291] [SSP] ssp_motor_work_func : Motor state 0, iRet 0
11-02 23:41:10.574 0 0 E [3: init: 1] init: Could not open '/metadata/bootstat/persist.sys.boot.reason' to persist reboot reason: No such file or directory
11-02 23:41:10.575 0 0 E [3: init: 1] init: Could not start shutdown 'to_start' service 'watchdogd': Cannot find '/sbin/watchdogd': No such file or directory
11-02 23:41:10.578 0 0 I [3: init: 1] init: starting service 'blank_screen'...
Now Priority I, many init like
11-02 23:41:10.637 0 0 I [3: init: 1] init: Sending signal 15 to service 'vendor.power-hal-aidl' (pid 3532) process group...
11-02 23:41:10.637 0 0 I [0: init: 1] init: Sending signal 15 to service 'vendor.hwcomposer-2-1' (pid 3531) process group...
11-02 23:41:10.638 0 0 I [0: init: 1] init: Sending signal 15 to service 'vendor.gralloc-2-0' (pid 3495) process group...
A run of priority E
11-02 23:41:13.662 0 0 E [5: init: 1] init: [service-misbehaving] : service 'zygote_secondary' is still running 3000ms after receiving SIGTERM
11-02 23:41:13.662 0 0 E [5: init: 1] init: [service-misbehaving] : service 'hidl_memory' is still running 3000ms after receiving SIGTERM
11-02 23:41:13.662 0 0 E [5: init: 1] init: [service-misbehaving] : service 'system_suspend' is still running 3000ms after receiving SIGTERM
11-02 23:41:13.662 0 0 E [5: init: 1] init: [service-misbehaving] : service 'audioserver' is still running 3000ms after receiving SIGTERM
11-02 23:41:13.662 0 0 E [5: init: 1] init: [service-misbehaving] : service 'cameraserver' is still running 3000ms after receiving SIGTERM
11-02 23:41:13.662 0 0 E [5: init: 1] init: [service-misbehaving] : service 'credstore' is still running 3000ms after receiving SIGTERM
11-02 23:41:13.662 0 0 E [5: init: 1] init: [service-misbehaving] : service 'gatekeeperd' is still running 3000ms after receiving SIGTERM
11-02 23:41:13.662 0 0 E [5: init: 1] init: [service-misbehaving] : service 'gpu' is still running 3000ms after receiving SIGTERM
11-02 23:41:13.662 0 0 E [5: init: 1] init: [service-misbehaving] : service 'idmap2d' is still running 3000ms after receiving SIGTERM
11-02 23:41:13.662 0 0 E [5: init: 1] init: [service-misbehaving] : service 'incidentd' is still running 3000ms after receiving SIGTERM
11-02 23:41:13.662 0 0 E [5: init: 1] init: [service-misbehaving] : service 'installd' is still running 3000ms after receiving SIGTERM
11-02 23:41:13.662 0 0 E [5: init: 1] init: [service-misbehaving] : service 'keystore2' is still running 3000ms after receiving SIGTERM
11-02 23:41:13.662 0 0 E [5: init: 1] init: [service-misbehaving] : service 'lmkd' is still running 3000ms after receiving SIGTERM
A run of vold – vold is Volume deamon, fundamental, looks after volumes, especially ones like SD card
11-02 23:41:13.732 3416 3416 D vold : Aborting fuse connection entry /sys/fs/fuse/connections/74/abort
11-02 23:41:13.732 3416 3416 W vold : Skip fsync /sys/fs/fuse/connections/74/abort on a file system does not support synchronization: Invalid argument
11-02 23:41:13.732 4674 4777 I FuseDaemon: Ending fuse...
11-02 23:41:13.733 0 0 I [4: vdc: 5999] binder: 5999:5999 ioctl 40046210 7fd2d6a4f4 returned -22
11-02 23:41:13.734 0 0 D [4: vdc: 5999] ProcessState: Binder ioctl to enable oneway spam detection failed: Invalid argument
11-02 23:41:13.733 4674 4777 I FuseDaemon: DESTROY /storage/emulated
11-02 23:41:13.734 0 0 D [4: vdc: 5999] vdc: Waited 0ms for vold
11-02 23:41:13.733 4674 4777 I FuseDaemon: Ended fuse
11-02 23:41:13.738 0 0 I [2: init: 1] init: Calling /system/bin/vdc volume shutdown
11-02 23:41:13.734 4674 4777 I FuseDaemonThread: Exiting thread for emulated;0 ...
11-02 23:41:13.734 4674 4777 I ExternalStorageServiceImpl: Exiting session for id: emulated;0
11-02 23:41:13.734 4674 4777 I FuseDaemonThread: Exited thread for emulated;0
11-02 23:41:13.758 0 0 I [4: vdc: 6000] binder: 6000:6000 ioctl 40046210 7fc034d6b4 returned -22
11-02 23:41:13.758 0 0 D [4: vdc: 6000] ProcessState: Binder ioctl to enable oneway spam detection failed: Invalid argument
11-02 23:41:13.760 0 0 D [4: vdc: 6000] vdc: Waited 0ms for vold
11-02 23:41:13.757 3416 3416 I vold : Killing all processes referencing /storage/emulated/0
All priority I to
11-02 23:41:14.305 0 0 I [3: init: 1] init: Ready to unmount apexes. So far shutdown sequence took 3741ms
Difficult to tell if there is significant problems 41:14.305 to 41:16.595 … seems “natural” …
Closing sequence, I think the W seen are fairly benign
11-02 23:41:16.595 0 0 I [3: init: 1] sysrq: Show backtrace of all active CPUs
11-02 23:41:16.596 0 0 I [3: init: 1] sysrq: Show Blocked State
11-02 23:41:16.596 0 0 I : [3: init: 1] task PC stack pid father
11-02 23:41:16.596 0 0 I I[1:DefaultDispatch: 4807] CPU1:
11-02 23:41:16.596 0 0 F : I[1:DefaultDispatch: 4807] Call trace:
11-02 23:41:16.596 0 0 W : I[1:DefaultDispatch: 4807] [<ffffffc00008bc64>] dump_backtrace+0x0/0x130
11-02 23:41:16.596 0 0 W : I[1:DefaultDispatch: 4807] [<ffffffc00008bf34>] show_stack+0x14/0x1c
11-02 23:41:16.596 0 0 W : I[1:DefaultDispatch: 4807] [<ffffffc00040da68>] showacpu+0x54/0x70
11-02 23:41:16.596 0 0 W : I[1:DefaultDispatch: 4807] [<ffffffc00010ada0>] flush_smp_call_function_queue+0xa4/0x144
11-02 23:41:16.596 0 0 W : I[1:DefaultDispatch: 4807] [<ffffffc00010b074>] generic_smp_call_function_single_interrupt+0x10/0x18
11-02 23:41:16.596 0 0 W : I[1:DefaultDispatch: 4807] [<ffffffc00008fc50>] handle_IPI+0x15c/0x300
11-02 23:41:16.596 0 0 W : I[1:DefaultDispatch: 4807] [<ffffffc000085594>] gic_handle_irq+0x74/0x88
11-02 23:41:16.596 0 0 W : I[1:DefaultDispatch: 4807] Exception stack(0xffffffc85a00bea0 to 0xffffffc85a00bfd0)
11-02 23:41:16.596 0 0 W I[1:DefaultDispatch: 4807] bea0: 00000800 00000000 00000000 00000000 ffffffff ffffffff 87218fa0 00000073
11-02 23:41:16.596 0 0 W I[1:DefaultDispatch: 4807] bec0: 1383c180 00000000 1373cb18 00000000 00000000 00000000 00000000 00000000
11-02 23:41:16.596 0 0 W I[1:DefaultDispatch: 4807] bee0: 068cf4c0 00000073 13763c50 00000000 00000000 00000000 0000d2c8 00000000
11-02 23:41:16.596 0 0 W I[1:DefaultDispatch: 4807] bf00: 142c72e8 00000073 1373f540 00000000 068cf584 00000073 068cf580 00000073
11-02 23:41:16.596 0 0 W I[1:DefaultDispatch: 4807] bf20: 00000004 00000000 63fd4a3e 00000000 068cf4a0 00000073 00000000 00000000
11-02 23:41:16.596 0 0 W I[1:DefaultDispatch: 4807] bf40: 142c72e8 00000073 000000ba 00000000 0624a000 00000073 f945c7d0 00000074
11-02 23:41:16.596 0 0 W I[1:DefaultDispatch: 4807] bf60: 00000000 00000000 00000000 00000000 142c72e8 00000073 0000001f 00000000
11-02 23:41:16.596 0 0 W I[1:DefaultDispatch: 4807] bf80: 87208d00 00000073 068cf4b8 00000073 068cf4bc 00000073 068cf4b8 00000073
11-02 23:41:16.596 0 0 W I[1:DefaultDispatch: 4807] bfa0: 068cf4c0 00000073 068cf4bc 00000073 87209cb0 00000073 068cf4a0 00000073
11-02 23:41:16.596 0 0 W I[1:DefaultDispatch: 4807] bfc0: 87218fa0 00000073 60000000 00000000
11-02 23:41:16.596 0 0 W : [3: init: 1] ffffffc00008973c 0 1649 2 0x00000000
11-02 23:41:16.596 0 0 F : [3: init: 1] Call trace:
11-02 23:41:16.596 0 0 W : [3: init: 1] [<ffffffc00008973c>] __switch_to+0x8c/0x98
11-02 23:41:16.596 0 0 W : [3: init: 1] [<ffffffc000b34468>] __schedule+0x4b0/0x6b0
11-02 23:41:16.596 0 0 W : [3: init: 1] [<ffffffc000b346c8>] schedule+0x60/0x68
11-02 23:41:16.596 0 0 W : [3: init: 1] [<ffffffc000b36f74>] schedule_timeout+0x3c/0x24c
11-02 23:41:16.596 0 0 W : [3: init: 1] [<ffffffc000b35380>] wait_for_common+0x148/0x180
11-02 23:41:16.596 0 0 W : [3: init: 1] [<ffffffc000b35500>] wait_for_completion+0x14/0x1c
11-02 23:41:16.596 0 0 W : [3: init: 1] [<ffffffc0008b6940>] irq_bh_worker+0x4c/0x1d0
11-02 23:41:16.596 0 0 W : [3: init: 1] [<ffffffc0000b94a0>] kthread+0xe0/0xe8
11-02 23:41:16.596 0 0 I : [3: init: 1] tee_scheduler D ffffffc00008973c 0 1650 2 0x00000000
11-02 23:41:16.596 0 0 F : [3: init: 1] Call trace:
11-02 23:41:16.596 0 0 W : [3: init: 1] [<ffffffc00008973c>] __switch_to+0x8c/0x98
11-02 23:41:16.596 0 0 W : [3: init: 1] [<ffffffc000b34468>] __schedule+0x4b0/0x6b0
11-02 23:41:16.596 0 0 W : [3: init: 1] [<ffffffc000b346c8>] schedule+0x60/0x68
11-02 23:41:16.596 0 0 W : [3: init: 1] [<ffffffc000b37140>] schedule_timeout+0x208/0x24c
11-02 23:41:16.596 0 0 W : [3: init: 1] [<ffffffc000b35380>] wait_for_common+0x148/0x180
11-02 23:41:16.596 0 0 W : [3: init: 1] [<ffffffc000b35518>] wait_for_completion_timeout+0x10/0x18
11-02 23:41:16.597 0 0 W : [3: init: 1] [<ffffffc0008b8ff0>] tee_scheduler+0x90/0x1b8
11-02 23:41:16.597 0 0 W : [3: init: 1] [<ffffffc0000b94a0>] kthread+0xe0/0xe8
11-02 23:41:16.597 0 0 I : [3: init: 1] lpeff_worker D ffffffc00008973c 0 1784 2 0x00000000
11-02 23:41:16.597 0 0 F : [3: init: 1] Call trace:
11-02 23:41:16.597 0 0 W : [3: init: 1] [<ffffffc00008973c>] __switch_to+0x8c/0x98
11-02 23:41:16.597 0 0 W : [3: init: 1] [<ffffffc000b34468>] __schedule+0x4b0/0x6b0
11-02 23:41:16.597 0 0 W : [3: init: 1] [<ffffffc000b346c8>] schedule+0x60/0x68
11-02 23:41:16.597 0 0 W : [3: init: 1] [<ffffffc00094f188>] lpeff_worker_func+0x9c/0x150
11-02 23:41:16.597 0 0 W : [3: init: 1] [<ffffffc0000b94a0>] kthread+0xe0/0xe8
11-02 23:41:16.597 0 0 I : [3: init: 1] exynos_hpgov D ffffffc00008973c 0 1886 2 0x00000000
11-02 23:41:16.597 0 0 F : [3: init: 1] Call trace:
11-02 23:41:16.597 0 0 W : [3: init: 1] [<ffffffc00008973c>] __switch_to+0x8c/0x98
11-02 23:41:16.597 0 0 W : [3: init: 1] [<ffffffc000b34468>] __schedule+0x4b0/0x6b0
11-02 23:41:16.597 0 0 W : [3: init: 1] [<ffffffc000b346c8>] schedule+0x60/0x68
11-02 23:41:16.597 0 0 W : [3: init: 1] [<ffffffc0003f55f8>] exynos_hpgov_do_update_governor+0x88/0x248
11-02 23:41:16.597 0 0 W : [3: init: 1] [<ffffffc0000b94a0>] kthread+0xe0/0xe8
11-02 23:41:16.597 0 0 I : [3: init: 1] exynos_hp D ffffffc00008973c 0 1887 2 0x00000000
11-02 23:41:16.597 0 0 F : [3: init: 1] Call trace:
11-02 23:41:16.597 0 0 W : [3: init: 1] [<ffffffc00008973c>] __switch_to+0x8c/0x98
11-02 23:41:16.597 0 0 W : [3: init: 1] [<ffffffc000b34468>] __schedule+0x4b0/0x6b0
11-02 23:41:16.597 0 0 W : [3: init: 1] [<ffffffc000b346c8>] schedule+0x60/0x68
11-02 23:41:16.597 0 0 W : [3: init: 1] [<ffffffc0003f5448>] exynos_hpgov_do_hotplug+0x94/0x190
11-02 23:41:16.597 0 0 W : [3: init: 1] [<ffffffc0000b94a0>] kthread+0xe0/0xe8
11-02 23:41:16.597 0 0 I : [3: init: 1] composer@2.1-se D ffffffc00008973c 0 3531 1 0x00000000
11-02 23:41:16.597 0 0 F : [3: init: 1] Call trace:
11-02 23:41:16.597 0 0 W : [3: init: 1] [<ffffffc00008973c>] __switch_to+0x8c/0x98
11-02 23:41:16.597 0 0 W : [3: init: 1] [<ffffffc000b34468>] __schedule+0x4b0/0x6b0
11-02 23:41:16.597 0 0 W : [3: init: 1] [<ffffffc000b346c8>] schedule+0x60/0x68
11-02 23:41:16.597 0 0 W : [3: init: 1] [<ffffffc000b37140>] schedule_timeout+0x208/0x24c
11-02 23:41:16.597 0 0 W : [3: init: 1] [<ffffffc000b35380>] wait_for_common+0x148/0x180
11-02 23:41:16.597 0 0 W : [3: init: 1] [<ffffffc000b35518>] wait_for_completion_timeout+0x10/0x18
11-02 23:41:16.597 0 0 W : [3: init: 1] [<ffffffc0003a94d0>] dsim_wait_for_cmd_fifo_empty+0x64/0xe0
11-02 23:41:16.597 0 0 W : [3: init: 1] [<ffffffc0003a9acc>] dsim_write_data+0x320/0x3a8
11-02 23:41:16.597 0 0 W : [3: init: 1] [<ffffffc0003aaf30>] dsim_ioctl+0x31c/0x558
11-02 23:41:16.597 0 0 W : [3: init: 1] [<ffffffc0003b5a18>] decon_ioctl+0xe3c/0x10e0
11-02 23:41:16.597 0 0 W : [3: init: 1] [<ffffffc00039dc9c>] do_fb_ioctl+0x438/0x5ac
11-02 23:41:16.597 0 0 W : [3: init: 1] [<ffffffc00039de48>] fb_ioctl+0x38/0x48
11-02 23:41:16.597 0 0 W : [3: init: 1] [<ffffffc0001b3660>] do_vfs_ioctl+0x5f8/0x6b4
11-02 23:41:16.597 0 0 W : [3: init: 1] [<ffffffc0001b377c>] SyS_ioctl+0x60/0x88
11-02 23:41:16.597 0 0 W : [3: init: 1] Sched Debug Version: v0.11, 3.18.140-g57884d864fb5 #1
11-02 23:41:16.597 0 0 W [3: init: 1] ktime: 140124.747919
11-02 23:41:16.597 0 0 W [3: init: 1] sched_clk: 140128.111003
11-02 23:41:16.597 0 0 W [3: init: 1] cpu_clk: 140128.111195
11-02 23:41:16.597 0 0 W [3: init: 1] jiffies: 4294919333
11-02 23:41:16.598 0 0 W : [3: init: 1]
11-02 23:41:16.598 0 0 W : [3: init: 1] sysctl_sched
11-02 23:41:16.598 0 0 W [3: init: 1] .sysctl_sched_latency: 10.000000
11-02 23:41:16.598 0 0 W [3: init: 1] .sysctl_sched_min_granularity: 3.000000
11-02 23:41:16.598 0 0 W [3: init: 1] .sysctl_sched_wakeup_granularity: 2.000000
11-02 23:41:16.598 0 0 W [3: init: 1] .sysctl_sched_child_runs_first: 0
11-02 23:41:16.598 0 0 W [3: init: 1] .sysctl_sched_features: 11899
11-02 23:41:16.598 0 0 W [3: init: 1] .sysctl_sched_tunable_scaling: 0 (none)
11-02 23:41:16.598 0 0 W : [3: init: 1]
11-02 23:41:16.598 0 0 W : [3: init: 1] cpu#0
11-02 23:41:16.598 0 0 W [3: init: 1] .nr_running: 3
11-02 23:41:16.598 0 0 W [3: init: 1] .load: 1308
11-02 23:41:16.598 0 0 W [3: init: 1] .nr_switches: 162152
11-02 23:41:16.598 0 0 W [3: init: 1] .nr_load_updates: 23208
11-02 23:41:16.598 0 0 W [3: init: 1] .nr_uninterruptible: -218
11-02 23:41:16.598 0 0 W [3: init: 1] .next_balance: 4294.919334
11-02 23:41:16.598 0 0 W [3: init: 1] .curr->pid: 3413
11-02 23:41:16.598 0 0 W [3: init: 1] .clock: 140127.216503
11-02 23:41:16.598 0 0 W [3: init: 1] .cpu_load[0]: 48
11-02 23:41:16.598 0 0 W [3: init: 1] .cpu_load[1]: 25
11-02 23:41:16.598 0 0 W [3: init: 1] .cpu_load[2]: 17
11-02 23:41:16.598 0 0 W [3: init: 1] .cpu_load[3]: 56
11-02 23:41:16.598 0 0 W [3: init: 1] .cpu_load[4]: 115
11-02 23:41:16.598 0 0 W [3: init: 1] .yld_count: 7063
11-02 23:41:16.598 0 0 W [3: init: 1] .sched_count: 171915
11-02 23:41:16.598 0 0 W [3: init: 1] .sched_goidle: 50416
11-02 23:41:16.598 0 0 W [3: init: 1] .avg_idle: 246262
11-02 23:41:16.598 0 0 W [3: init: 1] .max_idle_balance_cost: 500000
11-02 23:41:16.598 0 0 W [3: init: 1] .ttwu_count: 120550
11-02 23:41:16.598 0 0 W [3: init: 1] .ttwu_local: 36324
11-02 23:41:16.598 0 0 W : [3: init: 1]
11-02 23:41:16.598 0 0 W [3: init: 1] cfs_rq[0]: /background
11-02 23:41:16.598 0 0 W [3: init: 1] .exec_clock: 8545.050806
11-02 23:41:16.598 0 0 W [3: init: 1] .MIN_vruntime: 0.000001
11-02 23:41:16.598 0 0 W [3: init: 1] .min_vruntime: 14951.402607
11-02 23:41:16.598 0 0 W [3: init: 1] .max_vruntime: 0.000001
11-02 23:41:16.598 0 0 W [3: init: 1] .spread: 0.000000
11-02 23:41:16.598 0 0 W [3: init: 1] .spread0: -759865.562058
11-02 23:41:16.598 0 0 W [3: init: 1] .nr_spread_over: 423
11-02 23:41:16.598 0 0 W [3: init: 1] .nr_running: 1
11-02 23:41:16.598 0 0 W [3: init: 1] .load: 110
11-02 23:41:16.598 0 0 W [3: init: 1] .runnable_load_avg: 6
11-02 23:41:16.598 0 0 W [3: init: 1] .blocked_load_avg: 0
11-02 23:41:16.598 0 0 W [3: init: 1] .tg_load_contrib: 14
11-02 23:41:16.598 0 0 W [3: init: 1] .tg_runnable_contrib: 86
11-02 23:41:16.598 0 0 W [3: init: 1] .tg_load_avg: 22
11-02 23:41:16.598 0 0 W [3: init: 1] .tg->runnable_avg: 96
11-02 23:41:16.598 0 0 W [3: init: 1] .tg->usage_avg: 411
11-02 23:41:16.598 0 0 W [3: init: 1] .tg->cfs_bandwidth.timer_active: 0
11-02 23:41:16.598 0 0 W [3: init: 1] .throttled: 0
11-02 23:41:16.598 0 0 W [3: init: 1] .throttle_count: 0
11-02 23:41:16.598 0 0 W [3: init: 1] .se->exec_start: 140128.752734
11-02 23:41:16.598 0 0 W [3: init: 1] .se->vruntime: 774817.299038
11-02 23:41:16.598 0 0 W [3: init: 1] .se->sum_exec_runtime: 8545.776576
11-02 23:41:16.598 0 0 W [3: init: 1] .se->statistics.wait_start: 0.000000
11-02 23:41:16.598 0 0 W [3: init: 1] .se->statistics.sleep_start: 0.000000
11-02 23:41:16.598 0 0 W [3: init: 1] .se->statistics.block_start: 0.000000
11-02 23:41:16.598 0 0 W [3: init: 1] .se->statistics.sleep_max: 0.000000
11-02 23:41:16.598 0 0 W [3: init: 1] .se->statistics.block_max: 0.000000
11-02 23:41:16.598 0 0 W [3: init: 1] .se->statistics.exec_max: 6.729769
11-02 23:41:16.598 0 0 W [3: init: 1] .se->statistics.slice_max: 4.182116
11-02 23:41:16.598 0 0 W [3: init: 1] .se->statistics.wait_max: 39.964500
11-02 23:41:16.598 0 0 W [3: init: 1] .se->statistics.wait_sum: 1386.699436
11-02 23:41:16.599 0 0 W [3: init: 1] .se->statistics.wait_count: 15927
11-02 23:41:16.599 0 0 W [3: init: 1] .se->load.weight: 1024
11-02 23:41:16.599 0 0 W [3: init: 1] .se->avg.runnable_avg_sum: 4000
11-02 23:41:16.599 0 0 W [3: init: 1] .se->avg.runnable_avg_period: 47622
11-02 23:41:16.599 0 0 W [3: init: 1] .se->avg.usage_avg_sum: 3955
11-02 23:41:16.599 0 0 W [3: init: 1] .se->avg.load_avg_contrib: 89
11-02 23:41:16.599 0 0 W [3: init: 1] .se->avg.decay_count: 0
11-02 23:41:16.599 0 0 W : [3: init: 1]
11-02 23:41:16.599 0 0 W [3: init: 1] cfs_rq[0]: /foreground
11-02 23:41:16.599 0 0 W [3: init: 1] .exec_clock: 6261.508030
11-02 23:41:16.599 0 0 W [3: init: 1] .MIN_vruntime: 4634.025092
11-02 23:41:16.599 0 0 W [3: init: 1] .min_vruntime: 4639.025092
11-02 23:41:16.599 0 0 W [3: init: 1] .max_vruntime: 4634.025092
11-02 23:41:16.599 0 0 W [3: init: 1] .spread: 0.000000
11-02 23:41:16.599 0 0 W [3: init: 1] .spread0: -770177.939573
11-02 23:41:16.599 0 0 W [3: init: 1] .nr_spread_over: 36
11-02 23:41:16.599 0 0 W [3: init: 1] .nr_running: 1
11-02 23:41:16.599 0 0 W [3: init: 1] .load: 1024
11-02 23:41:16.599 0 0 W [3: init: 1] .runnable_load_avg: 5
11-02 23:41:16.599 0 0 W [3: init: 1] .blocked_load_avg: 110
11-02 23:41:16.599 0 0 W [3: init: 1] .tg_load_contrib: 115
11-02 23:41:16.599 0 0 W [3: init: 1] .tg_runnable_contrib: 146
11-02 23:41:16.599 0 0 W [3: init: 1] .tg_load_avg: 2492
11-02 23:41:16.599 0 0 W [3: init: 1] .tg->runnable_avg: 687
11-02 23:41:16.599 0 0 W [3: init: 1] .tg->usage_avg: 583
11-02 23:41:16.599 0 0 W [3: init: 1] .tg->cfs_bandwidth.timer_active: 0
11-02 23:41:16.599 0 0 W [3: init: 1] .throttled: 0
11-02 23:41:16.599 0 0 W [3: init: 1] .throttle_count: 0
11-02 23:41:16.599 0 0 W [3: init: 1] .se->exec_start: 140123.122195
11-02 23:41:16.599 0 0 W [3: init: 1] .se->vruntime: 774816.964665
11-02 23:41:16.599 0 0 W [3: init: 1] .se->sum_exec_runtime: 6263.820957
11-02 23:41:16.599 0 0 W [3: init: 1] .se->statistics.wait_start: 140126.890657
11-02 23:41:16.599 0 0 W [3: init: 1] .se->statistics.sleep_start: 0.000000
11-02 23:41:16.599 0 0 W [3: init: 1] .se->statistics.block_start: 0.000000
11-02 23:41:16.599 0 0 W [3: init: 1] .se->statistics.sleep_max: 0.000000
11-02 23:41:16.599 0 0 W [3: init: 1] .se->statistics.block_max: 0.000000
11-02 23:41:16.599 0 0 W [3: init: 1] .se->statistics.exec_max: 3.913923
11-02 23:41:16.599 0 0 W [3: init: 1] .se->statistics.slice_max: 4.359846
11-02 23:41:16.599 0 0 W [3: init: 1] .se->statistics.wait_max: 29.398807
11-02 23:41:16.599 0 0 W [3: init: 1] .se->statistics.wait_sum: 1913.237896
11-02 23:41:16.599 0 0 W [3: init: 1] .se->statistics.wait_count: 14418
11-02 23:41:16.599 0 0 W [3: init: 1] .se->load.weight: 307
11-02 23:41:16.599 0 0 W [3: init: 1] .se->avg.runnable_avg_sum: 6818
11-02 23:41:16.599 0 0 W [3: init: 1] .se->avg.runnable_avg_period: 47643
11-02 23:41:16.599 0 0 W [3: init: 1] .se->avg.usage_avg_sum: 5769
11-02 23:41:16.599 0 0 W [3: init: 1] .se->avg.load_avg_contrib: 29
11-02 23:41:16.599 0 0 W [3: init: 1] .se->avg.decay_count: 0
11-02 23:41:16.599 0 0 W : [3: init: 1]
11-02 23:41:16.599 0 0 W [3: init: 1] cfs_rq[0]: /
11-02 23:41:16.599 0 0 W [3: init: 1] .exec_clock: 24709.613003
11-02 23:41:16.599 0 0 W [3: init: 1] .MIN_vruntime: 774816.964665
11-02 23:41:16.599 0 0 W [3: init: 1] .min_vruntime: 774816.964665
11-02 23:41:16.599 0 0 W [3: init: 1] .max_vruntime: 774816.964665
11-02 23:41:16.599 0 0 W [3: init: 1] .spread: 0.000000
11-02 23:41:16.599 0 0 W [3: init: 1] .spread0: 0.000000
11-02 23:41:16.599 0 0 W [3: init: 1] .nr_spread_over: 308
11-02 23:41:16.599 0 0 W [3: init: 1] .nr_running: 2
11-02 23:41:16.599 0 0 W [3: init: 1] .load: 1331
11-02 23:41:16.599 0 0 W [3: init: 1] .runnable_load_avg: 118
11-02 23:41:16.599 0 0 W [3: init: 1] .blocked_load_avg: 19
11-02 23:41:16.599 0 0 W [3: init: 1] .tg_load_contrib: 113
11-02 23:41:16.599 0 0 W [3: init: 1] .tg_runnable_contrib: 521
11-02 23:41:16.599 0 0 W [3: init: 1] .tg_load_avg: 6878
11-02 23:41:16.599 0 0 W [3: init: 1] .tg->runnable_avg: 2091
11-02 23:41:16.599 0 0 W [3: init: 1] .tg->usage_avg: 2091
11-02 23:41:16.599 0 0 W [3: init: 1] .tg->cfs_bandwidth.timer_active: 0
11-02 23:41:16.599 0 0 W [3: init: 1] .throttled: 0
11-02 23:41:16.599 0 0 W [3: init: 1] .throttle_count: 0
11-02 23:41:16.599 0 0 W [3: init: 1] .avg->runnable_avg_sum: 24047
11-02 23:41:16.599 0 0 W [3: init: 1] .avg->runnable_avg_period: 47197
11-02 23:41:16.599 0 0 W : [3: init: 1]
11-02 23:41:16.599 0 0 W [3: init: 1] rt_rq[0]:
11-02 23:41:16.599 0 0 W [3: init: 1] .rt_nr_running: 0
11-02 23:41:16.599 0 0 W [3: init: 1] .rt_throttled: 0
11-02 23:41:16.599 0 0 W [3: init: 1] .rt_time: 17.844543
11-02 23:41:16.599 0 0 W [3: init: 1] .rt_runtime: 950.000000
11-02 23:41:16.599 0 0 W : [3: init: 1]
11-02 23:41:16.599 0 0 W : [3: init: 1] runnable tasks:
11-02 23:41:16.599 0 0 W : [3: init: 1] task PID tree-key switches prio exec-runtime sum-exec sum-sleep
11-02 23:41:16.599 0 0 W : [3: init: 1] ----------------------------------------------------------------------------------------------------------
11-02 23:41:16.599 0 0 W : [3: init: 1] ksoftirqd/0 3 774797.080756 674 120 774797.080756 60.793690 139880.403550 /
11-02 23:41:16.599 0 0 W [3: init: 1] kworker/0: 0 4 774380.355127 51 120 774380.355127 2.544419 138874.981859 /
11-02 23:41:16.600 0 0 W [3: init: 1] kworker/0: 0H 5 774015.506270 3149 100 774015.506270 147.695597 137561.508213 /
11-02 23:41:16.600 0 0 W : [3: init: 1] rcu_bh 9 16.654423 2 120 16.654423 0.008577 0.000000 /
11-02 23:41:16.600 0 0 W : [3: init: 1] migration/0 10 0.000000 8329 0 0.000000 185.066832 0.000000 /
11-02 23:41:16.600 0 0 W : [3: init: 1] watchdog/0 11 0.000000 39 0 0.000000 2.531232 0.000000 /
11-02 23:41:16.600 0 0 W : [3: init: 1] spi1 657 774809.935841 17507 120 774809.935841 5356.160793 133291.804876 /
11-02 23:41:16.600 0 0 W : [3: init: 1] irq/7-max77854- 708 0.000000 2 49 0.000000 0.050693 0.000000 /
11-02 23:41:16.600 0 0 W : [3: init: 1] irq/9-sec-pmic- 761 0.000000 2 49 0.000000 0.043539 0.000000 /
11-02 23:41:16.600 0 0 W : [3: init: 1] cfinteractive 862 774812.229358 4910 120 774812.229358 513.090156 138624.870619 /
11-02 23:41:16.600 0 0 W [3: init: 1] kworker/0: 1 1199 774809.160299 8040 120 774809.160299 897.032351 137723.009161 /
11-02 23:41:16.600 0 0 W : [3: init: 1] irq/6-arizona 1269 0.000000 61 49 0.000000 1.804617 0.000000 /
11-02 23:41:16.600 0 0 W : [3: init: 1] irq/2-sec-nfc 1282 0.000000 31 49 0.000000 1.367615 0.000000 /
11-02 23:41:16.600 0 0 W [3: init: 1] kworker/u16: 6 1333 774531.344211 5977 120 774531.344211 597.310551 137352.671342 /
11-02 23:41:16.600 0 0 W : [3: init: 1] irq/1-abov-touc 1400 0.000000 2 49 0.000000 0.063577 0.000000 /
11-02 23:41:16.600 0 0 W : [3: init: 1] irq/594-sec_ts 1412 0.000000 5622 49 0.000000 541.839400 0.000000 /
11-02 23:41:16.600 0 0 W : [3: init: 1] irq/595-SMPL WA 1423 0.000000 2 49 0.000000 0.055538 0.000000 /
11-02 23:41:16.600 0 0 W : [3: init: 1] irq/599-jig-irq 1622 0.000000 2 49 0.000000 0.101423 0.000000 /
11-02 23:41:16.600 0 0 W : [3: init: 1] irq/601-wpd-det 1639 0.000000 2 49 0.000000 0.134962 0.000000 /
11-02 23:41:16.600 0 0 W : [3: init: 1] irq/602-wpc-irq 1642 0.000000 4 49 0.000000 0.534308 0.000000 /
11-02 23:41:16.600 0 0 W : [3: init: 1] irq/5-sx9310_ir 1657 0.000000 2 49 0.000000 0.072270 0.000000 /
11-02 23:41:16.600 0 0 W : [3: init: 1] irq/8-hrm_senso 1721 0.000000 5 49 0.000000 0.261269 0.000000 /
11-02 23:41:16.600 0 0 W : [3: init: 1] s3c-fb-vsync 1839 774809.172068 3218 120 774809.172068 194.147490 137865.702823 /
11-02 23:41:16.600 0 0 W : [3: init: 1] irq/626-flip_co 1950 0.000000 2 49 0.000000 0.025770 0.000000 /
11-02 23:41:16.600 0 0 W : [3: init: 1] irq/627-certify 1954 0.000000 2 49 0.000000 0.020884 0.000000 /
11-02 23:41:16.600 0 0 W [3: init: 1] kworker/0: 1H 2043 16166.764561 5 100 16166.764561 0.113271 12729.926026 /
11-02 23:41:16.600 0 0 W : [3: init: 1] dhd_rpm_state_t 2359 774700.960134 883 120 774700.960134 320.634761 137167.174753 /
11-02 23:41:16.600 0 0 W : [3: init: 1] logd.reader 3409 13211.162658 5 130 13211.162658 3.254193 109856.909965 /background
11-02 23:41:16.600 0 0 W : [3: init: 1] logd.klogd 3413 14963.207258 1416 130 14963.207258 408.763282 135705.856226 /background
11-02 23:41:16.600 0 0 W : [3: init: 1] logd.pkglist 4411 3012.945812 5 130 3012.945812 4.011770 6177.435861 /background
11-02 23:41:16.600 0 0 W : [3: init: 1] servicemanager 3406 774318.444033 3912 120 774318.444033 997.169813 133725.926987 /
11-02 23:41:16.600 0 0 W : [3: init: 1] vndservicemanag 3408 774318.257841 37 120 774318.257841 18.687001 134999.950870 /
11-02 23:41:16.600 0 0 W [3: init: 1] Binder: 3493_1 3504 4631.837719 2516 120 4631.837719 474.950615 135243.100900 /foreground
11-02 23:41:16.600 0 0 W : [3: init: 1] mali-cmar-backe 3520 0.000000 6292 0 0.000000 1324.751436 0.000000 /foreground
End of file
Ok i think i get it better.
If this is what is shown on @Didou’s video, then ye, i reproduce it every time i shut it down. Most of the time the “shutting down” screen appears, stay there for 5-7sec then the brightness decrease but the screen remains before a vibration occures and the phone really shuts down. Then, when i boot it, it asks for my pin, which is required after a restart.
So if i understand correctly, my phone is working correctly, which is not @tom1995’s case according to his video. I just spotted that, but he seems to be using the virtual buttons and not the physical ones, you can see that at the bottom of the screen. Could it be a reason ??
Edit: so i’m sorry but i just misunderstood, i don’t have the same issue as tom. Sorry for the loss of time…
Hi. Thanks for your answer.
To understand what I mean you need to take your friend’s phone (not Samsung S7) it can be Xiaomi and turn it off, you will see the difference. I tested Xiaomi 12 lite today and when I press the power off button, the phone screen DOESN’T GO OFF and I wait 7 seconds)) and the phone turns off.
The buttons that you see that light up after I press the power off button are called “nav bar”
System -> Buttons -> Enable on-screen nav bar
Yes, this is not a critical problem, but the behavior is wrong, it seems to me that this is a code problem.
it would be usefull if you provide log
If you are the owner of the operating system /e/OS, you can get the logs on your phone yourself, since you or I or another user will have the same problem with turning off the phone.
On other operating systems, turning off does not work so crookedly)) If the developers are not interested in fixing this, then I, as a user, will not be able to help in any way. But the crooked turning off of the phone is obvious, turning off the phone should not work like this.
Did you report the issue in gitlab?
howto
Discussing in the community forum is fine and often you can find help there … but to make sure that you reach the developers, a report in Gitlab is the right way.