Problem with turning off the phone | stable version Samsung S7

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.

1 Like

Hi @didou I think, perhaps, you missed the event you wanted due to the -d option, (Edit, specifically -d → dump & exit).

Practice / intro / suggestions

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
Example, run with -d option
adb devices
adb logcat -d > logcat2.txt

Some suggested searching of your first logcat

Search for “beginning” – this example found six. Note the time of first entry

  • Line 1
--------- beginning of kernel
05-05 00:48:36.523     0     0 I         : [    0.000000,0] Booting Linux on physical CPU 0x0
  • Line 1149
--------- beginning of system
05-05 00:48:36.682   576   576 I vold    : Vold 3.0 (the awakening) firing up
  • Line 7100
Seen beginning of crash 
  • Line 7166
--------- beginning of main

Next two beginnings were regarding noticfications.

Check lines for these components

  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”

Example 2, Shutdown

Check for possible Shutdown error.

adb logcat > logcat3.txt

Output is 16989 lines in a test of short time from Start.

Search for shutdown → 66 matches

Many lines seemed benign, then these lines seemed of interest.

  • from line 16,333
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...
  • from line 16,745
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
  • from line 16,912 to end is successful shutdown
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...
1 Like

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.

1 Like

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).

logcat1.txt

logcat2.txt

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 ?

1 Like

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.

Summary

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

2 Likes

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…

2 Likes

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.

1 Like