Grunt: Android failed to boot: sys.boot_completed not set |
||||
Issue description2018/11/21 00:37:50 Started test arc.Boot 2018/11/21 00:37:50 [00:37:49.471] Restarting ui job 2018/11/21 00:37:51 [00:37:50.723] Waiting for org.chromium.SessionManager D-Bus service 2018/11/21 00:37:51 [00:37:50.748] Asking session_manager to enable Chrome testing 2018/11/21 00:37:51 [00:37:50.751] Waiting for Chrome to write its debugging port to /home/chronos/DevToolsActivePort 2018/11/21 00:37:52 [00:37:51.539] Removing cryptohome for testuser@gmail.com 2018/11/21 00:37:52 [00:37:51.713] Finding OOBE DevTools target 2018/11/21 00:37:52 [00:37:51.819] Connecting to Chrome at ws://127.0.0.1:37949/devtools/page/57BD50A5A14EAFF9011ACEA14FC29E02 2018/11/21 00:37:52 [00:37:52.156] Waiting for OOBE 2018/11/21 00:37:56 [00:37:55.566] Logging in as user "testuser@gmail.com" 2018/11/21 00:37:56 [00:37:55.934] Waiting for cryptohome for user "testuser@gmail.com" 2018/11/21 00:38:00 [00:37:59.856] Waiting for OOBE to be dismissed 2018/11/21 00:38:04 [00:38:03.595] Waiting for Android boot 2018/11/21 00:40:04 [00:40:03.589] Error at boot.go:35: Failed to start ARC: Android failed to boot: sys.boot_completed not set: context deadline exceeded; last error follows: exit status 1 2018/11/21 00:40:04 [00:40:03.589] Stack trace: Failed to start ARC at chromiumos/tast/local/bundles/cros/arc.Boot (boot.go:35) at chromiumos/tast/testing.(*Test).Run.func4 (test.go:195) at chromiumos/tast/testing.runStages.func1.1 (stage.go:39) at chromiumos/tast/testing.runAndRecover.func1 (stage.go:69) at runtime.goexit (asm_amd64.s:2361) Android failed to boot at chromiumos/tast/local/arc.diagnose (diagnose.go:46) at chromiumos/tast/local/arc.New (arc.go:86) at chromiumos/tast/local/bundles/cros/arc.Boot (boot.go:33) at chromiumos/tast/testing.(*Test).Run.func4 (test.go:195) at chromiumos/tast/testing.runStages.func1.1 (stage.go:39) at chromiumos/tast/testing.runAndRecover.func1 (stage.go:69) at runtime.goexit (asm_amd64.s:2361) sys.boot_completed not set at chromiumos/tast/local/arc.New (arc.go:86) at chromiumos/tast/local/bundles/cros/arc.Boot (boot.go:33) at chromiumos/tast/testing.(*Test).Run.func4 (test.go:195) at chromiumos/tast/testing.runStages.func1.1 (stage.go:39) at chromiumos/tast/testing.runAndRecover.func1 (stage.go:69) at runtime.goexit (asm_amd64.s:2361) context deadline exceeded; last error follows at chromiumos/tast/testing.Poll (poll.go:72) at chromiumos/tast/local/arc.waitProp (arc.go:174) at chromiumos/tast/local/arc.New (arc.go:85) at chromiumos/tast/local/bundles/cros/arc.Boot (boot.go:33) at chromiumos/tast/testing.(*Test).Run.func4 (test.go:195) at chromiumos/tast/testing.runStages.func1.1 (stage.go:39) at chromiumos/tast/testing.runAndRecover.func1 (stage.go:69) at runtime.goexit (asm_amd64.s:2361) exit status 1 at ??? 2018/11/21 00:40:04 Completed test arc.Boot in 2m14.239s with 1 error(s) Here is the link to the grunt-paladin failure: https://ci.chromium.org/p/chromeos/builders/luci.chromeos.general/CQ/b8929272071813040112
,
Nov 21
re-opening. derat@ suggested having the constable check out the failure to make sure it isn't more than a transient failure.
,
Nov 21
,
Nov 22
This happens in CQ, which we don't normally monitor. I see dump from cros_camera_service, and there are a few camera related changes in the build.
Anyway, for the record, here is the dump from the crash. Please re-open if happens in regular build (shouldn't since it won't pass CQ).
3 libbase-core-456626.so!logging::LogMessage::~LogMessage() [logging.cc : 774 + 0x5]
rbx = 0x0000000000000000 rbp = 0x00007ffd27dfd980
rsp = 0x00007ffd27dfd520 r12 = 0x00007ffd27dfd9b8
r13 = 0x00007ffd27dfd538 r14 = 0x00007ffd27dfd9c0
r15 = 0x00007ffd27dfd9c8 rip = 0x00007ae79d70f56e
Found by: call frame info
4 libbase-core-456626.so!base::internal::LockImpl::Lock() [lock_impl_posix.cc : 65 + 0x8]
rbx = 0x00007ae79d06d01b rbp = 0x00007ffd27dfdb00
rsp = 0x00007ffd27dfd990 r12 = 0x00007ae79af95fe8
r13 = 0x00007ffd27dfdcc0 r14 = 0x00007ffd27dfd9b8
r15 = 0x00007ffd27dfd9c0 rip = 0x00007ae79d76275e
Found by: call frame info
5 usb.so!cros::CameraMojoChannelManagerImpl::TearDownMojoEnv() [lock.h : 45 + 0xc]
rbx = 0x00007ae794004290 rbp = 0x00007ffd27dfdc70
rsp = 0x00007ffd27dfdb10 r12 = 0x00007ae79af95fe8
r13 = 0x00007ffd27dfdcc0 r14 = 0x00007ae79dc14000
r15 = 0x0000000000000000 rip = 0x00007ae79aed11a8
Found by: call frame info
6 ld-2.27.so!_dl_fini [dl-fini.c : 138 + 0x7]
rbx = 0x00007ae794004290 rbp = 0x00007ffd27dfde60
rsp = 0x00007ffd27dfdc80 r12 = 0x00007ae79af95fe8
r13 = 0x00007ffd27dfdcc0 r14 = 0x00007ae79dc14000
r15 = 0x0000000000000000 rip = 0x00007ae79d9fbf67
Found by: call frame info
7 libc-2.27.so!__run_exit_handlers [exit.c : 108 + 0x2]
rbx = 0x0000000000000001 rbp = 0x00007ffd27dfdeb0
rsp = 0x00007ffd27dfde70 r12 = 0x0000000000000068
r13 = 0x0000000000000391 r14 = 0x00007ae79d29fd80
r15 = 0x00007ae79d2a3988 rip = 0x00007ae79cf25f12
Found by: call frame info
8 libc-2.27.so!exit [exit.c : 139 + 0x5]
rbx = 0x00007ffd27dfeab0 rbp = 0x00007ffd27dfdec0
rsp = 0x00007ffd27dfdec0 r12 = 0x00007ffd27dfe0b0
r13 = 0x00007ae79d7dd0b0 r14 = 0x0000000000000068
r15 = 0x0000578671fd7c30 rip = 0x00007ae79cf25ffa
Found by: call frame info
9 cros_camera_service!cros::CameraHalServerImpl::ExitOnMainThread(int) [camera_hal_server_impl.cc : 205 + 0x8]
rbx = 0x00007ffd27dfeab0 rbp = 0x00007ffd27dfe010
rsp = 0x00007ffd27dfded0 r12 = 0x00007ffd27dfe0b0
r13 = 0x00007ae79d7dd0b0 r14 = 0x0000000000000068
r15 = 0x0000578671fd7c30 rip = 0x00005786719a08ad
Found by: call frame info
,
Nov 22
Thanks, Victor! |
||||
►
Sign in to add a comment |
||||
Comment 1 by gmeinke@google.com
, Nov 21