ChromsOS: R61-9765.72.0
Platform: Guado with Kelper

What steps will reproduce the problem?
(1) Run rebooting test, reboot guado and check system health

What is the expected result?

What happens instead?
Panic/Oops reported

Please use labels and text to provide additional information.

210 | 2017-09-28 14:58:48 | Kernel Event | Clean Shutdown
211 | 2017-09-28 14:58:48 | System boot | 622
212 | 2017-09-28 14:58:48 | System Reset
213 | 2017-09-28 14:58:48 | Wake Source | GPIO | 27
214 | 2017-09-28 14:58:49 | Chrome OS Developer Mode
215 | 2017-09-28 15:00:10 | Kernel Event | Clean Shutdown
216 | 2017-09-28 15:00:11 | System boot | 623
217 | 2017-09-28 15:00:11 | System Reset
218 | 2017-09-28 15:00:11 | Wake Source | GPIO | 27
219 | 2017-09-28 15:00:11 | Chrome OS Developer Mode
220 | 2017-09-28 15:01:01 | Kernel Event | Oops
221 | 2017-09-28 15:01:01 | Kernel Event | Panic
222 | 2017-09-28 15:01:02 | System boot | 624
223 | 2017-09-28 15:01:02 | System Reset
224 | 2017-09-28 15:01:02 | Wake Source | GPIO | 27
225 | 2017-09-28 15:01:02 | Chrome OS Developer Mode
226 | 2017-09-28 17:06:07 | Kernel Event | Clean Shutdown
227 | 2017-09-28 17:06:07 | System boot | 625
228 | 2017-09-28 17:06:07 | System Reset
229 | 2017-09-28 17:06:07 | Wake Source | GPIO | 27
230 | 2017-09-28 17:06:08 | Chrome OS Developer Mode
231 | 2017-09-30 01:17:00 | Kernel Event | Clean Shutdown
232 | 2017-09-30 01:17:01 | System boot | 626
233 | 2017-09-30 01:17:01 | System Reset
234 | 2017-09-30 01:17:01 | Wake Source | GPIO | 27
235 | 2017-09-30 01:17:02 | Chrome OS Developer Mode
localhost log # cat /etc/lsb-release                                                                                                                                                                       
CHROMEOS_RELEASE_APPID={8AA6D9AC-6EBC-4288-A615-171F56F66B4E}
CHROMEOS_BOARD_APPID={8AA6D9AC-6EBC-4288-A615-171F56F66B4E}
CHROMEOS_CANARY_APPID={90F229CE-83E2-4FAF-8479-E368A34938B1}
DEVICETYPE=CHROMEBOX
CHROMEOS_RELEASE_BUILDER_PATH=guado-release/R61-9765.72.0
GOOGLE_RELEASE=9765.72.0
CHROMEOS_DEVSERVER=
CHROMEOS_RELEASE_BOARD=guado
CHROMEOS_RELEASE_BUILD_NUMBER=9765
CHROMEOS_RELEASE_BRANCH_NUMBER=72
CHROMEOS_RELEASE_CHROME_MILESTONE=61
CHROMEOS_RELEASE_PATCH_NUMBER=0
CHROMEOS_RELEASE_TRACK=testimage-channel
CHROMEOS_RELEASE_DESCRIPTION=9765.72.0 (Official Build) dev-channel guado test
CHROMEOS_RELEASE_BUILD_TYPE=Official Build
CHROMEOS_RELEASE_NAME=Chrome OS
CHROMEOS_RELEASE_VERSION=9765.72.0
CHROMEOS_AUSERVER=https://tools.google.com/service/update2
localhost log # uptime                                                                                                                                                                                     
 11:36:46 up 10:19,  0 users,  load average: 2.14, 2.99, 3.10
localhost log # generate_logs                                                                                                                                                                              
[0930/113650:INFO:generate_logs.cc(86)] Gathering logs, please wait
[0930/113652:INFO:generate_logs.cc(91)] Logs saved to /tmp/debug-logs_20170930-113650.tgz
localhost log # grep "09-28T15:" /var/log/messages                                                                                                                                                         
localhost log # grep "09-28T15:" /var/log/messages.1                                                                                                                                                       
localhost log # grep "09-28T15:" /var/log/messages.*                                                                                                                                                       
/var/log/messages.2:2017-09-28T15:00:06.974081-07:00 NOTICE ag[3201]: autotest server[stack::_compare_cmd_output|run|wrapper] -> ssh_run(usb-devices | grep ^P:)
/var/log/messages.2:2017-09-28T15:00:08.131419-07:00 NOTICE ag[3668]: autotest server[stack::parse_cmd_output|run|wrapper] -> ssh_run(cat /etc/lsb-release)
/var/log/messages.2:2017-09-28T15:00:08.195112-07:00 NOTICE ag[3671]: autotest server[stack::get_boot_id|run|wrapper] -> ssh_run(if [ -f '/proc/sys/kernel/random/boot_id' ]; then cat '/proc/sys/kernel/random/boot_id'; else echo 'no boot_id available'; fi)
/var/log/messages.2:2017-09-28T15:00:08.258526-07:00 NOTICE ag[3674]: autotest server[stack::run_background|run|wrapper] -> ssh_run(( sleep 1; reboot & sleep 10; reboot -f ) </dev/null >/dev/null 2>&1 & echo -n $!)
/var/log/messages.2:2017-09-28T15:00:08.319856-07:00 NOTICE ag[3678]: autotest server[stack::get_boot_id|run|wrapper] -> ssh_run(if [ -f '/proc/sys/kernel/random/boot_id' ]; then cat '/proc/sys/kernel/random/boot_id'; else echo 'no boot_id available'; fi)
/var/log/messages.2:2017-09-28T15:00:09.333564-07:00 ERR shill[1001]: [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
/var/log/messages.2:2017-09-28T15:00:09.333616-07:00 ERR shill[1001]: [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
/var/log/messages.2:2017-09-28T15:00:09.334335-07:00 INFO avahi-daemon[1161]: Withdrawing address record for 100.107.146.16 on eth0.
/var/log/messages.2:2017-09-28T15:00:09.334413-07:00 INFO avahi-daemon[1161]: Leaving mDNS multicast group on interface eth0.IPv4 with address 100.107.146.16.
/var/log/messages.2:2017-09-28T15:00:09.334496-07:00 INFO avahi-daemon[1161]: Interface eth0.IPv4 no longer relevant for mDNS.
/var/log/messages.2:2017-09-28T15:00:09.385597-07:00 ERR wpa_supplicant[538]: rmdir[ctrl_interface=/run/wpa_supplicant]: Permission denied
/var/log/messages.2:2017-09-28T15:00:09.407265-07:00 NOTICE shill_logout_user[3696]: Failed to pop shill user profiles
/var/log/messages.2:2017-09-28T15:00:09.412043-07:00 INFO session_manager[695]: [INFO:browser_job.cc(157)] Terminating process: 
/var/log/messages.2:2017-09-28T15:00:09.412068-07:00 INFO session_manager[695]: [INFO:system_utils_impl.cc(111)] Sending 15 to 728 as 1000
/var/log/messages.2:2017-09-28T15:00:09.439146-07:00 ERR chrome[728]: [728:728:0928/150009.439093:INFO:remote_commands_invalidator.cc(45)] Shutdown RemoteCommandsInvalidator.
/var/log/messages.2:2017-09-28T15:00:09.439402-07:00 ERR chrome[728]: [728:728:0928/150009.439378:INFO:remote_commands_invalidator.cc(68)] Stopping RemoteCommandsInvalidator.
/var/log/messages.2:2017-09-28T15:00:09.439909-07:00 ERR chrome[728]: [728:728:0928/150009.439860:INFO:remote_commands_invalidator.cc(167)] Unregister RemoteCommandsInvalidator.
/var/log/messages.2:2017-09-28T15:00:09.647093-07:00 INFO session_manager[695]: [INFO:session_manager_service.cc(464)] SessionManagerService quitting run loop
/var/log/messages.2:2017-09-28T15:00:09.647170-07:00 INFO session_manager[695]: [INFO:session_manager_service.cc(206)] SessionManagerService exiting
/var/log/messages.2:2017-09-28T15:00:09.692699-07:00 INFO chapsd[859]: Token at /home/root/1a5811c0dd15b9ead8bf69e7b4b4ac6d139ec1da/chaps has been removed from slot 1
/var/log/messages.2:2017-09-28T15:00:09.722631-07:00 INFO chapsd[859]: Unloaded keys for slot 1
/var/log/messages.2:2017-09-28T15:00:09.723452-07:00 INFO kernel: [   50.060076] tpm_tis tpm_tis: command 0xba (size 18) returned code 0x0
/var/log/messages.2:2017-09-28T15:00:09.839821-07:00 WARNING chapsd[859]: Unload Token event received for unknown path: /home/root/1a5811c0dd15b9ead8bf69e7b4b4ac6d139ec1da/chaps

>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>>

/var/log/messages.2:2017-09-28T15:00:09.851115-07:00 NOTICE pre-shutdown[3806]: Shutting down for reboot: unknown-reason  <<<<<<<<<<<<<<<<<<<<<<
/var/log/messages.2:2017-09-28T15:00:09.865459-07:00 INFO kernel: [   50.202683] EXT4-fs (sda3): re-mounted. Opts: (null)  <<<<<<<<<<<<<<<<<<<<<

/var/log/messages.2:2017-09-28T15:01:35.028661-07:00 INFO kernel: [    0.000000] Initializing cgroup subsys cpuset
/var/log/messages.2:2017-09-28T15:01:35.028714-07:00 INFO kernel: [    0.000000] Initializing cgroup subsys cpu
/var/log/messages.2:2017-09-28T15:01:35.028716-07:00 INFO kernel: [    0.000000] Initializing cgroup subsys cpuacct
/var/log/messages.2:2017-09-28T15:01:35.028717-07:00 NOTICE kernel: [    0.000000] Linux version 3.14.0 (chrome-bot@cros-beefy212-c2) (gcc version 4.9.x 20150123 (prerelease) (4.9.2_cos_gg_4.9.2-r163-0c5a656a1322e137fa4a251f2ccc6c4022918c0a_4.9.2-r163) ) #1 SMP PREEMPT Tue Sep 26 23:42:02 PDT 2017
/var/log/messages.2:2017-09-28T15:01:35.028719-07:00 INFO kernel: [    0.000000] Command line: cros_secure console= loglevel=7 init=/sbin/init cros_secure oops=panic panic=-1 root=PARTUUID=ceb6c7d0-ef98-c247-bf52-112f2d7171b0/PARTNROFF=1 rootwait rw dm_verity.error_behavior=3 dm_verity.max_bios=-1 dm_verity.dev_wait=0 dm="1 vroot none ro 1,0 3584000 verity payload=ROOT_DEV hashtree=HASH_DEV hashstart=3584000 alg=sha1 root_hexdigest=c66cb7e12d0fe6cbb809997414b250f46404d5e6 salt=3e18dcadf8e0dc2e2228e7aa85f235838495cb61d327a115ed45fa3a7a62ad1a" noinitrd cros_debug vt.global_cursor_default=0 kern_guid=ceb6c7d0-ef98-c247-bf52-112f2d7171b0 add_efi_memmap boot=local noresume noswap i915.modeset=1 tpm_tis.force=1 tpm_tis.interrupts=0 nmi_watchdog=panic,lapic 
/var/log/messages.2:2017-09-28T15:01:35.028720-07:00 INFO kernel: [    0.000000] e820: BIOS-provided physical RAM map:
/var/log/messages.2:2017-09-28T15:01:35.028722-07:00 INFO kernel: [    0.000000] BIOS-e820: [mem 0x0000000000000000-0x0000000000000fff] type 16
/var/log/messages.2:2017-09-28T15:01:35.028725-07:00 INFO kernel: [    0.000000] BIOS-e820: [mem 0x0000000000001000-0x000000000009ffff] usable
/var/log/messages.2:2017-09-28T15:01:35.028726-07:00 INFO kernel: [    0.000000] BIOS-e820: [mem 0x00000000000a0000-0x00000000000fffff] reserved
/var/log/messages.2:2017-09-28T15:01:35.028727-07:00 INFO kernel: [    0.000000] BIOS-e820: [mem 0x0000000000100000-0x000000007ce3cfff] usable
/var/log/messages.2:2017-09-28T15:01:35.028728-07:00 INFO kernel: [    0.000000] BIOS-e820: [mem 0x000000007ce3d000-0x000000007cffffff] type 16
/var/log/messages.2:2017-09-28T15:01:35.028730-07:00 INFO kernel: [    0.000000] BIOS-e820: [mem 0x000000007d000000-0x000000007fffffff] reserved
/var/log/messages.2:2017-09-28T15:01:35.028731-07:00 INFO kernel: [    0.000000] BIOS-e820: [mem 0x00000000f0000000-0x00000000f3ffffff] reserved
/var/log/messages.2:2017-09-28T15:01:35.028732-07:00 INFO kernel: [    0.000000] BIOS-e820: [mem 0x00000000fed10000-0x00000000fed19fff] reserved
/var/log/messages.2:2017-09-28T15:01:35.028734-07:00 INFO kernel: [    0.000000] BIOS-e820: [mem 0x00000000fed80000-0x00000000fed84fff] reserved
/var/log/messages.2:2017-09-28T15:01:35.028735-07:00 INFO kernel: [    0.000000] BIOS-e820: [mem 0x0000000100000000-0x000000017effffff] usable
/var/log/messages.2:2017-09-28T15:01:35.028737-07:00 INFO kernel: [    0.000000] NX (Execute Disable) protection: active
/var/log/messages.2:2017-09-28T15:01:35.028738-07:00 INFO kernel: [    0.000000] SMBIOS 2.7 present.

For graphics-related bugs, please copy/paste the contents of the about:gpu
page at the end of this report.

 

Deleted: debug-logs_20170930-113650.tgz 10.8 MB

debug-logs_20170930-113650.tgz 10.8 MB Download