My favorites | Sign in
Project Home Downloads Wiki Issues Code Search
New issue   Search
for
  Advanced search   Search tips   Subscriptions
Issue 202069: login_CryptohomeUnomounted timed out under mysterious circumstances
1 person starred this issue and may be notified of changes. Back to list
 
Project Member Reported by rochb...@chromium.org, Aug 10, 2011
The x86-mario-canary build failed login_CryptohomeUnmounted.

The stdio is here:
 http://chromeos-botmaster.mtv.corp.google.com:8026/builders/x86-mario%20canary/builds/844/steps/Test/logs/stdio

The relevant line is
06:54:51 INFO | 		ERROR	login_CryptohomeUnmounted	login_CryptohomeUnmounted	timestamp=1312984487	localtime=Aug 10 06:54:47	Timed out waiting for login

If you fetch the build artifacts:
   http://chromeosbuild7.mtv.corp.google.com/archive/x86-mario-release/0.15.877.0-a1-b844

there's a mysterious 26 second pause in the logs:
(from
/tmp/test_harness/testUpdateWipeStateful/4_verify/suite_Smoke/login_CryptohomeUnmounted/sysinfo/messages )

011-08-10T13:54:16.528422+00:00 localhost cryptohomed: Creating tracked subdirectories...
2011-08-10T13:54:16.528428+00:00 localhost cryptohomed: old: /home/chronos/user/Cache 0 new /home/.shadow/6bcfd453d49ef48b2c97ac5225bd3a61f45d524a/vault/Cache 0
2011-08-10T13:54:16.528433+00:00 localhost cryptohomed: old: /home/chronos/user/Downloads 1 new /home/.shadow/6bcfd453d49ef48b2c97ac5225bd3a61f45d524a/vault/Downloads 0
2011-08-10T13:54:16.528440+00:00 localhost cryptohomed: Migrating: /home/.shadow/6bcfd453d49ef48b2c97ac5225bd3a61f45d524a/mount -> /home/.shadow/6bcfd453d49ef48b2c97ac5225bd3a61f45d524a/mount/user
2011-08-10T13:54:16.528446+00:00 localhost cryptohomed: Bind: /home/.shadow/6bcfd453d49ef48b2c97ac5225bd3a61f45d524a/mount/user -> /home/chronos/user
2011-08-10T13:54:42.579271+00:00 localhost cryptohomed: An asynchronous mount request with sequence id: 48 finished.
2011-08-10T13:54:42.579292+00:00 localhost cryptohomed: Putting a Pkcs11_Initialize on the mount thread.
2011-08-10T13:54:43.457957+00:00 localhost flimflamd[10423]: Set inet for eth0: ipaddr 10.0.2.15 bcast 10.0.2.255 prefixlen 24 mtu 0
2011-08-10T13:54:43.458015+00:00 localhost flimflamd[10423]: Set default route for eth0: gw 10.0.2.2 metric 0

cmasone points out that troopers should be able to get you the /var/log/messages for the host then if you think that time gap is the result of the VM not running for a while.


Mar 16, 2013
#1 bugdroid1@chromium.org
(No comment was entered for this change.)
Mergedinto: chromium-os:19375
Aug 16, 2011
#2 gauravsh@chromium.org
Elly: The pause David mentions seems to happen right after the migration begins. Is that something which may have been a result of your migration change (which has since been reverted)?
Cc: ellyjo...@chromium.org
Aug 24, 2011
#3 kan...@chromium.org
(No comment was entered for this change.)
Status: Assigned
Cc: cmasone@chromium.org
Aug 24, 2011
#4 cmasone@chromium.org
?

I don't have any insight into why the VM hung for 26 seconds.  This seems like an intermittent failure that happened once.
Sep 2, 2011
#5 davidjames@google.com
The fix for  issue 19375  may have fixed this issue. VM tests may run slow if other stuff is running in parallel in the background. To work around the issue we prioritize the VM tests highly, so there should be less pauses.
Status: Duplicate
Mergedinto: 19375
Mar 6, 2013
#6 bugdroid1@chromium.org
(No comment was entered for this change.)
Labels: OS-Chrome
Mar 9, 2013
#7 bugdroid1@chromium.org
(No comment was entered for this change.)
Labels: -Area-OtherApps -TreeCloser Hotlist-TreeCloser Cr-Tests-Fails
Sign in to add a comment

Powered by Google Project Hosting