New issue
Advanced search Search tips
Note: Color blocks (like or ) mean that a user may not be available. Tooltip shows the reason.

Issue 700789 link

Starred by 0 users

Issue metadata

Status: Duplicate
Merged: issue 699389
Owner: ----
Closed: Mar 2017
Cc:
Components:
EstimatedDays: ----
NextAction: ----
OS: Chrome
Pri: 1
Type: Bug



Sign in to add a comment

Reef : Flakily failing to login Chrome OS in autotest lab (cryptohome decryption error)

Project Member Reported by kinaba@chromium.org, Mar 13 2017

Issue description

Automated CTS run started for N:
https://wmatrix.googleplex.com/platform/unfiltered?platforms=reef&suites=arc-cts&days_back=15&releases=tot&hide_missing=True
but is sometimes suffering from even starting.

It's failing to Chrome OS login, so it doesn't look specific to ARC.
One cause is  Bug 697094 , and the other is this failure pattern I'm now filing. An instance is:

https://wmatrix.googleplex.com/testrun/unfiltered?test_ids=448060923
> Test logs > `cd ..` > cheets_CTS_N.CtsSystemUiTestCases.tgz > cheets_CTSHelper/sysinfo/var/log/messages

or
https://wmatrix.googleplex.com/testrun/unfiltered?test_ids=448049211

2017-03-12T03:03:33.177269-07:00 INFO session_manager[9374]: [INFO:browser_job.cc(140)] Running child /opt/google/chrome/chrome --ppapi-flash-path=/opt/google/chrome/pepper/libpepflashplayer.so --ppapi-flash-version=25.0.0.127 --ui-prioritize-in-gpu-process --use-gl=egl --gpu-sandbox-failures-fatal=yes --arc-availability=officially-supported --enable-logging --log-level=1 --use-cras --enable-wayland-server --user-data-dir=/home/chronos --max-unused-resource-memory-usage-percentage=5 --system-developer-mode --login-profile=user --has-chromeos-keyboard --enable-touchview --default-wallpaper-large=/usr/share/chromeos-assets/wallpaper/default_large.jpg --default-wallpaper-small=/usr/share/chromeos-assets/wallpaper/default_small.jpg --child-wallpaper-large=/usr/share/chromeos-assets/wallpaper/child_large.jpg --child-wallpaper-small=/usr/share/chromeos-assets/wallpaper/child_small.jpg --guest-wallpaper-large=/usr/share/chromeos-assets/wallpaper/guest_large.jpg --guest-wallpaper-small=/usr/share/chromeos-assets/wallpaper/guest_small.jpg --enable-prefixed-encrypted-media --enterprise-enrollment-initial-modulus=15 --enterprise-enrollment-modulus-limit=19 --login-manager --vmodule=screen_locker=2,webui_screen_locker=2,lock_state_controller=2,webui_login_view=2,power_button_observer=2,*/ui/display/manager/chromeos/*=1,*/ui/ozone/*=1,*zygote*=1,*plugin*=2,auto_enrollment_controller=1,*chromeos/login/*=1,*arc/*=1 
2017-03-12T03:03:33.415848-07:00 ERR kernel: [  610.717354] BUG: sleeping function called from invalid context at /mnt/host/source/src/third_party/kernel/v4.4/fs/dcache.c:754
2017-03-12T03:03:33.415887-07:00 ERR kernel: [  610.717368] in_atomic(): 0, irqs_disabled(): 0, pid: 3620, name: MountThread
2017-03-12T03:03:33.415891-07:00 WARNING kernel: [  610.717378] CPU: 1 PID: 3620 Comm: MountThread Tainted: G     U          4.4.52-07400-g15b52ca #1
2017-03-12T03:03:33.415892-07:00 WARNING kernel: [  610.717383] Hardware name: Google Reef/Reef, BIOS Google_Reef.9042.50.0 02/16/2017
2017-03-12T03:03:33.415895-07:00 WARNING kernel: [  610.717390]  0000000000000286 00000000538125c8 ffff880166ac3be0 ffffffff8aa927ee
2017-03-12T03:03:33.415896-07:00 WARNING kernel: [  610.717403]  0000000000000e24 ffff880168817000 ffff880166ac3c00 ffffffff8a88b553
2017-03-12T03:03:33.415899-07:00 WARNING kernel: [  610.717416]  ffffffff8b1c04a0 00000000000002f2 ffff880166ac3c28 ffffffff8a88b5f7
2017-03-12T03:03:33.415900-07:00 WARNING kernel: [  610.717428] Call Trace:
2017-03-12T03:03:33.415903-07:00 WARNING kernel: [  610.717444]  [<ffffffff8aa927ee>] dump_stack+0x4d/0x63
2017-03-12T03:03:33.415905-07:00 WARNING kernel: [  610.717455]  [<ffffffff8a88b553>] ___might_sleep+0x149/0x14e
2017-03-12T03:03:33.415907-07:00 WARNING kernel: [  610.717463]  [<ffffffff8a88b5f7>] __might_sleep+0x9f/0xa6
2017-03-12T03:03:33.415909-07:00 WARNING kernel: [  610.717472]  [<ffffffff8a9775f5>] dput+0x2f/0x206
2017-03-12T03:03:33.415911-07:00 WARNING kernel: [  610.717482]  [<ffffffff8aa09584>] ext4_d_revalidate+0x6e/0x97
2017-03-12T03:03:33.415913-07:00 WARNING kernel: [  610.717489]  [<ffffffff8a96f2dd>] lookup_fast+0xb5/0x296
2017-03-12T03:03:33.415915-07:00 WARNING kernel: [  610.717496]  [<ffffffff8a9703ff>] path_openat+0x2b0/0xc56
2017-03-12T03:03:33.415917-07:00 WARNING kernel: [  610.717504]  [<ffffffff8aa0a0ab>] ? ext4_free_crypt_info+0x3b/0x3e
2017-03-12T03:03:33.415919-07:00 WARNING kernel: [  610.717512]  [<ffffffff8aa0a4bc>] ? _ext4_get_encryption_info+0x3d7/0x416
2017-03-12T03:03:33.415921-07:00 WARNING kernel: [  610.717520]  [<ffffffff8a972b1a>] do_filp_open+0x5c/0xc6
2017-03-12T03:03:33.415922-07:00 WARNING kernel: [  610.717530]  [<ffffffff8a956c78>] ? slab_pre_alloc_hook+0x29/0x2f
2017-03-12T03:03:33.415924-07:00 WARNING kernel: [  610.717540]  [<ffffffff8af0033c>] ? _raw_spin_unlock+0xe/0x20
2017-03-12T03:03:33.415926-07:00 WARNING kernel: [  610.717550]  [<ffffffff8a963d32>] do_sys_open+0x86/0x198
2017-03-12T03:03:33.415928-07:00 WARNING kernel: [  610.717557]  [<ffffffff8a963d32>] ? do_sys_open+0x86/0x198
2017-03-12T03:03:33.415930-07:00 WARNING kernel: [  610.717565]  [<ffffffff8a963e62>] SyS_open+0x1e/0x20
2017-03-12T03:03:33.415932-07:00 WARNING kernel: [  610.717573]  [<ffffffff8af00761>] entry_SYSCALL_64_fastpath+0x1c/0x74
2017-03-12T03:03:33.646041-07:00 INFO session_manager[9374]: [INFO:browser_job.cc(149)] Terminating process group: Restarting browser on-demand.
2017-03-12T03:03:33.646067-07:00 INFO session_manager[9374]: [INFO:system_utils_impl.cc(110)] Sending 9 to -9391 as 1000
2017-03-12T03:03:33.655341-07:00 INFO session_manager[9374]: [INFO:child_exit_handler.cc(77)] Handling 9391 exit.
2017-03-12T03:03:33.655507-07:00 ERR session_manager[9374]: [ERROR:child_exit_handler.cc(85)]   Exited with signal 9
2017-03-12T03:03:33.655585-07:00 INFO session_manager[9374]: [INFO:session_manager_service.cc(274)] Exiting process is chrome.
2017-03-12T03:03:33.655652-07:00 INFO session_manager[9374]: [INFO:browser_job.cc(149)] Terminating process group: Ensuring browser processes are gone.
2017-03-12T03:03:33.655771-07:00 INFO session_manager[9374]: [INFO:system_utils_impl.cc(110)] Sending 9 to -9391 as 1000
2017-03-12T03:03:33.656368-07:00 INFO session_manager[9374]: [INFO:browser_job.cc(140)] Running child /opt/google/chrome/chrome --ppapi-flash-path=/opt/google/chrome/pepper/libpepflashplayer.so --ppapi-flash-version=25.0.0.127 --ui-prioritize-in-gpu-process --use-gl=egl --gpu-sandbox-failures-fatal=yes --arc-availability=officially-supported --enable-logging --log-level=1 --use-cras --enable-wayland-server --user-data-dir=/home/chronos --max-unused-resource-memory-usage-percentage=5 --system-developer-mode --login-profile=user --has-chromeos-keyboard --enable-touchview --default-wallpaper-large=/usr/share/chromeos-assets/wallpaper/default_large.jpg --default-wallpaper-small=/usr/share/chromeos-assets/wallpaper/default_small.jpg --child-wallpaper-large=/usr/share/chromeos-assets/wallpaper/child_large.jpg --child-wallpaper-small=/usr/share/chromeos-assets/wallpaper/child_small.jpg --guest-wallpaper-large=/usr/share/chromeos-assets/wallpaper/guest_large.jpg --guest-wallpaper-small=/usr/share/chromeos-assets/wallpaper/guest_small.jpg --enable-prefixed-encrypted-media --enterprise-enrollment-initial-modulus=15 --enterprise-enrollment-modulus-limit=19 --login-manager --disable-arc-opt-in-verification --enable-net-benchmarking --metrics-recording-only --no-default-browser-check --no-first-run --enable-gpu-benchmarking --disable-background-networking --disable-component-extensions-with-background-pages --disable-default-apps --disable-search-geolocation-disclosure --enable-smooth-scrolling --enable-threaded-compositing --remote-debugging-port=57915 --start-maximized --ash-disable-system-sounds --allow-failed-policy-fetch-for-test --oobe-skip-postlogin --disable-gaia-services --testing-channel=NamedTestingInterface:/tmp/.org.chromium.Chromium.BfelkG/.org.chromium.Chromium.SXNOUt --vmodule=screen_locker=2,webui_screen_locker=2,lock_state_controller=2,webui_login_view=2,power_button_observer=2,*/ui/display/manager/chromeos/*=1,*/ui/ozone/*=1,*zygote*=1,*plugin*=2,auto_enrollment_controller=1,*chromeos/login/*=1,*arc/*=1,*/chromeos/net/*=2,*/chromeos/login/*=2,chrome_browser_main_posix=2 
2017-03-12T03:03:33.725314-07:00 ERR mtpd[2151]: LIBMTP_Detect_Raw_Devices failed with 5
2017-03-12T03:03:33.727677-07:00 ERR mtpd[2151]: message repeated 5 times: [ LIBMTP_Detect_Raw_Devices failed with 5]
2017-03-12T03:03:37.767598-07:00 WARNING cryptohomed[3561]: Could not load the device policy file.
2017-03-12T03:03:37.781693-07:00 ERR kernel: [  615.082611] i2c_designware i2c_designware.2: i2c_dw_handle_tx_abort: slave address not acknowledged (7bit mode)
2017-03-12T03:03:37.816260-07:00 WARNING cryptohomed[3561]: Could not load the device policy file.
2017-03-12T03:03:37.876515-07:00 WARNING cryptohomed[3561]: No valid keysets on disk for c513faa442802a92dfe650ea1a90c302f1ee39c2
2017-03-12T03:03:37.876547-07:00 ERR cryptohomed[3561]: Failed to decrypt any keysets for c513faa442802a92dfe650ea1a90c302f1ee39c2
2017-03-12T03:03:37.876562-07:00 ERR cryptohomed[3561]: Error, cryptohome must be re-created because of fatal error.
2017-03-12T03:03:37.877738-07:00 ERR kernel: [  615.178476] BUG: sleeping function called from invalid context at /mnt/host/source/src/third_party/kernel/v4.4/fs/dcache.c:754
2017-03-12T03:03:37.877761-07:00 ERR kernel: [  615.178484] in_atomic(): 0, irqs_disabled(): 0, pid: 3620, name: MountThread
2017-03-12T03:03:37.877762-07:00 WARNING kernel: [  615.178489] CPU: 0 PID: 3620 Comm: MountThread Tainted: G     U          4.4.52-07400-g15b52ca #1
2017-03-12T03:03:37.877763-07:00 WARNING kernel: [  615.178492] Hardware name: Google Reef/Reef, BIOS Google_Reef.9042.50.0 02/16/2017
2017-03-12T03:03:37.877764-07:00 WARNING kernel: [  615.178495]  0000000000000286 00000000538125c8 ffff880166ac3b78 ffffffff8aa927ee
2017-03-12T03:03:37.877765-07:00 WARNING kernel: [  615.178502]  0000000000000e24 ffff880168817000 ffff880166ac3b98 ffffffff8a88b553
2017-03-12T03:03:37.877766-07:00 WARNING kernel: [  615.178508]  ffffffff8b1c04a0 00000000000002f2 ffff880166ac3bc0 ffffffff8a88b5f7
2017-03-12T03:03:37.877767-07:00 WARNING kernel: [  615.178514] Call Trace:
2017-03-12T03:03:37.877768-07:00 WARNING kernel: [  615.178524]  [<ffffffff8aa927ee>] dump_stack+0x4d/0x63
2017-03-12T03:03:37.877769-07:00 WARNING kernel: [  615.178530]  [<ffffffff8a88b553>] ___might_sleep+0x149/0x14e
2017-03-12T03:03:37.877770-07:00 WARNING kernel: [  615.178533]  [<ffffffff8a88b5f7>] __might_sleep+0x9f/0xa6
2017-03-12T03:03:37.877771-07:00 WARNING kernel: [  615.178539]  [<ffffffff8a9775f5>] dput+0x2f/0x206
2017-03-12T03:03:37.877771-07:00 WARNING kernel: [  615.178543]  [<ffffffff8aa09584>] ext4_d_revalidate+0x6e/0x97
2017-03-12T03:03:37.877772-07:00 WARNING kernel: [  615.178547]  [<ffffffff8a96f2dd>] lookup_fast+0xb5/0x296
2017-03-12T03:03:37.877773-07:00 WARNING kernel: [  615.178550]  [<ffffffff8a96f894>] walk_component+0x5f/0x179
2017-03-12T03:03:37.877774-07:00 WARNING kernel: [  615.178553]  [<ffffffff8a96ff0f>] path_lookupat+0x82/0x103
2017-03-12T03:03:37.877775-07:00 WARNING kernel: [  615.178557]  [<ffffffff8a97174b>] filename_lookup+0x8c/0x11d
2017-03-12T03:03:37.877775-07:00 WARNING kernel: [  615.178562]  [<ffffffff8a956c78>] ? slab_pre_alloc_hook+0x29/0x2f
2017-03-12T03:03:37.877776-07:00 WARNING kernel: [  615.178566]  [<ffffffff8a9588ce>] ? kmem_cache_alloc+0x24/0x123
2017-03-12T03:03:37.877777-07:00 WARNING kernel: [  615.178569]  [<ffffffff8a97142c>] ? getname_flags+0x3d/0x194
2017-03-12T03:03:37.877778-07:00 WARNING kernel: [  615.178572]  [<ffffffff8a971568>] ? getname_flags+0x179/0x194
2017-03-12T03:03:37.877779-07:00 WARNING kernel: [  615.178575]  [<ffffffff8a9718aa>] user_path_at_empty+0x37/0x3d
2017-03-12T03:03:37.877779-07:00 WARNING kernel: [  615.178578]  [<ffffffff8a9718aa>] ? user_path_at_empty+0x37/0x3d
2017-03-12T03:03:37.877780-07:00 WARNING kernel: [  615.178582]  [<ffffffff8a968e32>] vfs_fstatat+0x60/0xaf
2017-03-12T03:03:37.877781-07:00 WARNING kernel: [  615.178586]  [<ffffffff8a968f5e>] vfs_lstat+0x1e/0x20
2017-03-12T03:03:37.877782-07:00 WARNING kernel: [  615.178589]  [<ffffffff8a968fd5>] SYSC_newlstat+0x24/0x51
2017-03-12T03:03:37.877783-07:00 WARNING kernel: [  615.178593]  [<ffffffff8a94066b>] ? __might_fault+0x35/0x37
2017-03-12T03:03:37.877783-07:00 WARNING kernel: [  615.178597]  [<ffffffff8a974b22>] ? SyS_getdents+0xeb/0x117
2017-03-12T03:03:37.877784-07:00 WARNING kernel: [  615.178600]  [<ffffffff8a974884>] ? iterate_dir+0x115/0x115
2017-03-12T03:03:37.877785-07:00 WARNING kernel: [  615.178603]  [<ffffffff8a9690a1>] SyS_newlstat+0xe/0x10
2017-03-12T03:03:37.877786-07:00 WARNING kernel: [  615.178609]  [<ffffffff8af00761>] entry_SYSCALL_64_fastpath+0x1c/0x74
2017-03-12T03:03:37.912472-07:00 ERR cryptohomed[3561]: Fatal decryption error, but unable to remove cryptohome.
2017-03-12T03:03:37.914999-07:00 WARNING cryptohomed[3561]: PKCS#11 initialization requested but cryptohome is not mounted.
 

Comment 1 by tfiga@chromium.org, Mar 13 2017

Possibly a dup of  issue 699389 .

Comment 2 by kinaba@chromium.org, Mar 13 2017

Mergedinto: 699389
Status: Duplicate (was: Available)
Likely. Thanks!

Sign in to add a comment