CfM is rebooted with error msg "Shutting down for reboot: unknown-reason" |
|||||||||||
Issue descriptionChrome Version: (copy from chrome://version) 9765.49.0 (Official Build) dev-channel guado test What steps will reproduce the problem? (1) Manually join and leave meeting (2) Manually click "refresh" on Mimo cfg page What is the expected result? no unknown reboot What happens instead? Guado is rebooted. Please use labels and text to provide additional information. ============================================================= 2017-08-31T15:15:56.691221-07:00 ERR shill[1313]: [ERROR:object_proxy.cc(582)] Failed to call method: org.chromium.dhcpcd.Release: object_path= /org/chromium/dhcpcd: org.freedesktop.DBus.Error.ServiceUnknown: The name :1.52 was not provided by any .service files 2017-08-31T15:15:56.691261-07:00 ERR shill[1313]: [ERROR:dbus_method_invoker.h(111)] CallMethodAndBlockWithTimeout(...): Domain=dbus, Code=org.freedesktop.DBus.Error.ServiceUnknown, Message=The name :1.52 was not provided by any .service files 2017-08-31T15:15:56.691752-07:00 INFO avahi-daemon[1114]: Withdrawing address record for 100.107.146.22 on eth0. 2017-08-31T15:15:56.691833-07:00 INFO avahi-daemon[1114]: Leaving mDNS multicast group on interface eth0.IPv4 with address 100.107.146.22. 2017-08-31T15:15:56.691870-07:00 INFO avahi-daemon[1114]: Interface eth0.IPv4 no longer relevant for mDNS. 2017-08-31T15:15:56.740903-07:00 ERR wpa_supplicant[535]: rmdir[ctrl_interface=/run/wpa_supplicant]: Permission denied 2017-08-31T15:15:56.778713-07:00 NOTICE shill_logout_user[2940]: Failed to pop shill user profiles 2017-08-31T15:15:56.787379-07:00 INFO session_manager[677]: [INFO:browser_job.cc(157)] Terminating process: 2017-08-31T15:15:56.787394-07:00 INFO session_manager[677]: [INFO:system_utils_impl.cc(111)] Sending 15 to 725 as 1000 2017-08-31T15:15:56.807252-07:00 ERR chrome[725]: [725:725:0831/151556.807220:INFO:remote_commands_invalidator.cc(45)] Shutdown RemoteCommandsInvalidator. 2017-08-31T15:15:56.807342-07:00 ERR chrome[725]: [725:725:0831/151556.807330:INFO:remote_commands_invalidator.cc(68)] Stopping RemoteCommandsInvalidator. 2017-08-31T15:15:56.807374-07:00 ERR chrome[725]: [725:725:0831/151556.807365:INFO:remote_commands_invalidator.cc(167)] Unregister RemoteCommandsInvalidator. 2017-08-31T15:15:57.116177-07:00 INFO session_manager[677]: [INFO:session_manager_service.cc(464)] SessionManagerService quitting run loop 2017-08-31T15:15:57.116222-07:00 INFO session_manager[677]: [INFO:session_manager_service.cc(206)] SessionManagerService exiting 2017-08-31T15:15:57.164473-07:00 INFO chapsd[878]: Token at /home/root/019504d03d9cd57b60201aadde52607720de8a26/chaps has been removed from slot 1 2017-08-31T15:15:57.193978-07:00 INFO chapsd[878]: Unloaded keys for slot 1 2017-08-31T15:15:57.194791-07:00 INFO kernel: [ 410.735160] tpm_tis tpm_tis: command 0xba (size 18) returned code 0x0 2017-08-31T15:15:57.318480-07:00 WARNING chapsd[878]: Unload Token event received for unknown path: /home/root/019504d03d9cd57b60201aadde52607720de8a26/chaps >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>> 2017-08-31T15:15:57.325529-07:00 NOTICE pre-shutdown[3055]: Shutting down for reboot: unknown-reason <<<<<<<<<<<<<<<<<<<<<<<<<<<<< 2017-08-31T15:16:01.047772-07:00 INFO kernel: [ 0.000000] Initializing cgroup subsys cpuset 161 | 2017-08-31 15:09:06 | Kernel Event | Clean Shutdown 162 | 2017-08-31 15:09:06 | System boot | 2743 163 | 2017-08-31 15:09:06 | System Reset 164 | 2017-08-31 15:09:06 | Wake Source | GPIO | 27 165 | 2017-08-31 15:15:58 | Kernel Event | Clean Shutdown <<<<<<<<<<<<<<<<< 166 | 2017-08-31 15:15:59 | System boot | 2744 <<<<<<<<<<<<<<<< 167 | 2017-08-31 15:15:59 | System Reset <<<<<<<<<<<<<<<<< 168 | 2017-08-31 15:15:59 | Log area cleared | 1028 169 | 2017-08-31 15:15:59 | Wake Source | GPIO | 27 For graphics-related bugs, please copy/paste the contents of the about:gpu page at the end of this report.
,
Sep 15 2017
powerd.PREVIOUS, ./power_manager/powerd.20170831-150914:[0831/150914:INFO:daemon.cc(1081)] D-Bus org.chromium.LibCrosService ownership changed to :1.11 [0831/151556:INFO:daemon.cc(1168)] Got RequestRestart message from :1.11 [0831/151556:INFO:display_power_setter.cc(82)] Asking Chrome to turn all displays off [0831/151556:INFO:daemon.cc(1574)] Restarting, reason: user-request [0831/151556:INFO:main.cc(214)] Launching "/usr/bin/powerd_setuid_helper --action=reboot" Seems like dbus has gone away and I suspect in its absence the only useful thing to do is reboot. There are certainly alot of 'dbus-is-angry' messages in the logs.
,
Dec 17 2017
Hit the issue with 10176.15.0 during manual autotest run.
script fails due to 100.107.146.1 was rebooted, and reboot was requested by chrome.
12/16 17:27:07.529 INFO | test_runner_utils:0199| autoserv| Start_verification:check_cras_speaker_vol
12/16 17:28:03.258 INFO | test_runner_utils:0199| autoserv| ---found nodes for default speaker ['9']
12/16 17:28:03.277 INFO | test_runner_utils:0199| autoserv| Master ssh connection to 100.107.146.1 is down.
12/16 17:28:03.279 INFO | test_runner_utils:0199| autoserv| Starting master ssh connection '/usr/bin/ssh -a -x -N -o ControlMaster=yes -o ControlPath=/tmp/_autotmp_DoEJzUssh-master/socket -o StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -o BatchMode=yes -o ConnectTimeout=30 -o ServerAliveInterval=900 -o ServerAliveCountMax=3 -o ConnectionAttempts=4 -o Protocol=2 -l root -p 22 100.107.146.1'
powerd.20171213-152941:
[1214/152651:INFO:daemon.cc(1201)] Got RequestRestart message from :1.186 with reason unknown (chrome.runtime API) <<<<<<<<<<<<<<<<<<<<<<<<<<<<<
[1214/152651:INFO:display_power_setter.cc(81)] Asking DisplayService to turn all displays off
[1214/152651:INFO:daemon.cc(1594)] Restarting, reason: unknown
[1214/152651:INFO:main.cc(225)] Launching "/usr/bin/powerd_setuid_helper --action=reboot"
[1214/152651:INFO:daemon.cc(1418)] Chrome is using normal display mode
[1214/152651:INFO:suspend_delay_controller.cc(75)] Unregistering suspend delay 57016321 (shill) on behalf of :1.11
[1214/152651:INFO:suspend_delay_controller.cc(75)] Unregistering dark suspend delay 57049089 (shill) on behalf of :1.11
[1214/152651:INFO:daemon.cc(1522)] Session state changed to stopped
[1214/152651:INFO:state_controller.cc(833)] Updated settings: dim=7m screen_off=7m30s lock=0s idle_warn=0s idle=30m (no-op) lid_closed=suspend use_audio=1 use_video=1
[1214/152651:INFO:state_controller.cc(845)] Wake locks: screen
[1214/152651:INFO:activity_logger.cc(20)] Audio activity stopped
[1214/152651:INFO:daemon.cc(1436)] Received updated external policy: ac_dim=7m ac_screen_off=7m30s ac_lock=0s ac_idle_warn=0s ac_idle=30m battery_dim=5m battery_screen_off=5m30s battery_lock=0s battery_idle_warn=0s battery_idle=6m30s ac_idle=suspend battery_idle=suspend lid_closed=no-op screen_wake_lock=1 use_audio=1 use_video=1 presentation_factor=2.0 user_activity_factor=2.0 wait_for_initial_user_activity=0 force_nonzero_brightness_for_user_activity=1 (Prefs, extension)
[1214/152651:INFO:state_controller.cc(833)] Updated settings: dim=7m screen_off=7m30s lock=0s idle_warn=0s idle=30m (no-op) lid_closed=no-op use_audio=1 use_video=1
[1214/152651:INFO:state_controller.cc(845)] Wake locks: screen
[1214/152652:INFO:suspend_delay_controller.cc(121)] Unregistering suspend delay 57016326 (chrome) due to D-Bus client :1.186 going away
[1214/152652:INFO:suspend_delay_controller.cc(121)] Unregistering dark suspend delay 57049093 (chrome) due to D-Bus client :1.186 going away
test log can be checked at https://drive.google.com/corp/drive/folders/11QNn6Fx7FTTBpbzafuI8BzyMMnJAvg9z.
,
Dec 17 2017
Another instance happens to 100.107.146.6 around 1216/151618. [1216/151331:INFO:activity_logger.cc(20)] Audio activity ongoing [1216/151618:INFO:daemon.cc(1196)] Got RequestRestart message from :1.171 with reason unknown (chrome.runtime API) [1216/151618:INFO:display_power_setter.cc(81)] Asking DisplayService to turn all displays off [1216/151618:INFO:daemon.cc(1589)] Restarting, reason: unknown [1216/151618:INFO:main.cc(218)] Launching "/usr/bin/powerd_setuid_helper --action=reboot" [1216/151618:INFO:daemon.cc(1412)] Chrome is using normal display mode [1216/151618:INFO:suspend_delay_controller.cc(75)] Unregistering suspend delay 55771138 (shill) on behalf of :1.32 [1216/151618:INFO:suspend_delay_controller.cc(75)] Unregistering dark suspend delay 55803906 (shill) on behalf of :1.32 [1216/151618:INFO:daemon.cc(1516)] Session state changed to stopped [1216/151618:INFO:state_controller.cc(820)] Updated settings: dim=7m screen_off=8m lock=0s idle_warn=0s idle=30m (no-op) lid_closed=suspend use_audio=1 use_video=1 [1216/151618:INFO:state_controller.cc(832)] Wake locks: screen system [1216/151618:INFO:daemon.cc(1430)] Received updated external policy: ac_dim=7m ac_screen_off=8m ac_lock=0s ac_idle_warn=0s ac_idle=30m battery_dim=5m battery_screen_off=6m battery_lock=0s battery_idle_warn=0s battery_idle=10m ac_idle=suspend battery_idle=suspend lid_closed=no-op screen_wake_lock=1 system_wake_lock=1 use_audio=1 use_video=1 presentation_factor=2.0 user_activity_factor=2.0 wait_for_initial_user_activity=0 force_nonzero_brightness_for_user_activity=1 (Prefs, extension, WebRTC has active PeerConnections, Playing video, Playing audio) [1216/151618:INFO:state_controller.cc(820)] Updated settings: dim=7m screen_off=8m lock=0s idle_warn=0s idle=30m (no-op) lid_closed=no-op use_audio=1 use_video=1 [1216/151618:INFO:state_controller.cc(832)] Wake locks: screen system [1216/151618:INFO:activity_logger.cc(20)] Audio activity stopped [1216/151618:INFO:daemon.cc(1430)] Received updated external policy: ac_dim=7m ac_screen_off=8m ac_lock=0s ac_idle_warn=0s ac_idle=30m battery_dim=5m battery_screen_off=6m battery_lock=0s battery_idle_warn=0s battery_idle=10m ac_idle=suspend battery_idle=suspend lid_closed=no-op screen_wake_lock=1 system_wake_lock=1 use_audio=1 use_video=1 presentation_factor=2.0 user_activity_factor=2.0 wait_for_initial_user_activity=0 force_nonzero_brightness_for_user_activity=1 (Prefs, extension, WebRTC has active PeerConnections, Playing audio) [1216/151618:INFO:state_controller.cc(820)] Updated settings: dim=7m screen_off=8m lock=0s idle_warn=0s idle=30m (no-op) lid_closed=no-op use_audio=1 use_video=1 [1216/151618:INFO:state_controller.cc(832)] Wake locks: screen system [1216/151618:INFO:daemon.cc(1430)] Received updated external policy: ac_dim=7m ac_screen_off=8m ac_lock=0s ac_idle_warn=0s ac_idle=30m battery_dim=5m battery_screen_off=6m battery_lock=0s battery_idle_warn=0s battery_idle=10m ac_idle=suspend battery_idle=suspend lid_closed=no-op screen_wake_lock=1 system_wake_lock=1 use_audio=1 use_video=1 presentation_factor=2.0 user_activity_factor=2.0 wait_for_initial_user_activity=0 force_nonzero_brightness_for_user_activity=1 (Prefs, extension, WebRTC has active PeerConnections) [1216/151618:INFO:state_controller.cc(820)] Updated settings: dim=7m screen_off=8m lock=0s idle_warn=0s idle=30m (no-op) lid_closed=no-op use_audio=1 use_video=1 [1216/151618:INFO:state_controller.cc(832)] Wake locks: screen system [1216/151618:INFO:daemon.cc(1430)] Received updated external policy: ac_dim=7m ac_screen_off=8m ac_lock=0s ac_idle_warn=0s ac_idle=30m battery_dim=5m battery_screen_off=6m battery_lock=0s battery_idle_warn=0s battery_idle=10m ac_idle=suspend battery_idle=suspend lid_closed=no-op screen_wake_lock=1 use_audio=1 use_video=1 presentation_factor=2.0 user_activity_factor=2.0 wait_for_initial_user_activity=0 force_nonzero_brightness_for_user_activity=1 (Prefs, extension) [1216/151618:INFO:state_controller.cc(820)] Updated settings: dim=7m screen_off=8m lock=0s idle_warn=0s idle=30m (no-op) lid_closed=no-op use_audio=1 use_video=1 [1216/151618:INFO:state_controller.cc(832)] Wake locks: screen [1216/151618:INFO:suspend_delay_controller.cc(121)] Unregistering suspend delay 55771141 (chrome) due to D-Bus client :1.171 going away [1216/151618:INFO:suspend_delay_controller.cc(121)] Unregistering dark suspend delay 55803909 (chrome) due to D-Bus client :1.171 going away localhost chrome # all log for 100.107.146.6 can be found at all log files can be found at https://drive.google.com/open?id=1Bha-ZNaH7o11y-Qw3HNAr_MZ-YJzdC7r.
,
Dec 17 2017
Another instance found on 100.107.146.4 with R63-10032.71.1. log can be found at https://drive.google.com/open?id=1odYMZVB0_lAuyg3dzhDAsYt_kn-4Z1Kg
,
Jan 18 2018
,
Jan 18 2018
|
|||||||||||
►
Sign in to add a comment |
|||||||||||
Comment 1 by mzhuo@chromium.org
, Aug 31 2017