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

Issue 714332 link

Starred by 1 user

Issue metadata

Status: Archived
Owner: ----
Closed: Mar 2018
Cc:
Components:
EstimatedDays: ----
NextAction: ----
OS: Chrome
Pri: 2
Type: Bug



Sign in to add a comment

Tests were scheduled but aborted right after that.

Project Member Reported by nxia@chromium.org, Apr 21 2017

Issue description

https://luci-milo.appspot.com/buildbot/chromeos/kevin-paladin/856



04/19 03:31:13.663 DEBUG|             suite:1176| Scheduled 55 tests, writing the total to keyval.
04/19 03:31:13.663 DEBUG|     dynamic_suite:0606| Waiting on suite.
04/19 03:37:08.455 INFO |        server_job:0184| START	----	kevin-paladin/R60-9475.0.0-rc3/bvt-inline/platform_DMVerityBitCorruption	timestamp=1492598228	localtime=Apr 19 03:37:08	
04/19 03:37:08.456 INFO |        server_job:0184| 	ABORT	----	kevin-paladin/R60-9475.0.0-rc3/bvt-inline/platform_DMVerityBitCorruption	timestamp=1492598228	localtime=Apr 19 03:37:08	
04/19 03:37:08.456 INFO |        server_job:0184| END ABORT	----	kevin-paladin/R60-9475.0.0-rc3/bvt-inline/platform_DMVerityBitCorruption	timestamp=1492598228	localtime=Apr 19 03:37:08	
04/19 03:37:09.541 INFO |        server_job:0184| START	----	kevin-paladin/R60-9475.0.0-rc3/bvt-inline/platform_DMVerityCorruption	timestamp=1492598229	localtime=Apr 19 03:37:09	
04/19 03:37:09.541 INFO |        server_job:0184| 	ABORT	----	kevin-paladin/R60-9475.0.0-rc3/bvt-inline/platform_DMVerityCorruption	timestamp=1492598229	localtime=Apr 19 03:37:09	
04/19 03:37:09.541 INFO |        server_job:0184| END ABORT	----	kevin-paladin/R60-9475.0.0-rc3/bvt-inline/platform_DMVerityCorruption	timestamp=1492598229	localtime=Apr 19 03:37:09	
04/19 03:37:10.050 INFO |        server_job:0184| START	----	kevin-paladin/R60-9475.0.0-rc3/bvt-inline/provision_AutoUpdate.double	timestamp=1492598230	localtime=Apr 19 03:37:10	
04/19 03:37:10.050 INFO |        server_job:0184| 	ABORT	----	kevin-paladin/R60-9475.0.0-rc3/bvt-inline/provision_AutoUpdate.double	timestamp=1492598230	localtime=Apr 19 03:37:10	
04/19 03:37:10.051 INFO |        server_job:0184| END ABORT	----	kevin-paladin/R60-9475.0.0-rc3/bvt-inline/provision_AutoUpdate.double	timestamp=1492598230	localtime=Apr 19 03:37:10	
04/19 03:37:11.087 INFO |        server_job:0184| START	----	kevin-paladin/R60-9475.0.0-rc3/bvt-inline/security_ModuleLocking	timestamp=1492598231	localtime=Apr 19 03:37:11	
04/19 03:37:11.088 INFO |        server_job:0184| 	ABORT	----	kevin-paladin/R60-9475.0.0-rc3/bvt-inline/security_ModuleLocking	timestamp=1492598231	localtime=Apr 19 03:37:11	
04/19 03:37:11.088 INFO |        server_job:0184| END ABORT	----	kevin-paladin/R60-9475.0.0-rc3/bvt-inline/security_ModuleLocking	timestamp=1492598231	localtime=Apr 19 03:37:11	
04/19 03:37:12.279 INFO |        server_job:0184| START	----	kevin-paladin/R60-9475.0.0-rc3/bvt-inline/login_UserPolicyKeys	timestamp=1492598232	localtime=Apr 19 03:37:12	
04/19 03:37:12.284 INFO |        server_job:0184| 	ABORT	----	kevin-paladin/R60-9475.0.0-rc3/bvt-inline/login_UserPolicyKeys	timestamp=1492598232	localtime=Apr 19 03:37:12	
04/19 03:37:12.285 INFO |        server_job:0184| END ABORT	----	kevin-paladin/R60-9475.0.0-rc3/bvt-inline/login_UserPolicyKeys	timestamp=1492598232	localtime=Apr 19 03:37:12	
04/19 03:37:13.218 INFO |        server_job:0184| START	----	kevin-paladin/R60-9475.0.0-rc3/bvt-inline/security_ASLR	timestamp=1492598233	localtime=Apr 19 03:37:13	
04/19 03:37:13.219 INFO |        server_job:0184| 	ABORT	----	kevin-paladin/R60-9475.0.0-rc3/bvt-inline/security_ASLR	timestamp=1492598233	localtime=Apr 19 03:37:13	
04/19 03:37:13.219 INFO |        server_job:0184| END ABORT	----	kevin-paladin/R60-9475.0.0-rc3/bvt-inline/security_ASLR	timestamp=1492598233	localtime=Apr 19 03:37:13	
04/19 03:37:13.566 INFO |        server_job:0184| START	----	kevin-paladin/R60-9475.0.0-rc3/bvt-inline/login_OwnershipApi	timestamp=1492598233	localtime=Apr 19 03:37:13	
04/19 03:37:13.568 INFO |        server_job:0184| 	ABORT	----	kevin-paladin/R60-9475.0.0-rc3/bvt-inline/login_OwnershipApi	timestamp=1492598233	localtime=Apr 19 03:37:13	
04/19 03:37:13.569 INFO |        server_job:0184| END ABORT	----	kevin-paladin/R60-9475.0.0-rc3/bvt-inline/login_OwnershipApi	timestamp=1492598233	localtime=Apr 19 03:37:13	
04/19 03:37:14.662 INFO |        server_job:0184| START	----	kevin-paladin/R60-9475.0.0-rc3/bvt-inline/login_RemoteOwnership	timestamp=1492598234	localtime=Apr 19 03:37:14	
04/19 03:37:14.665 INFO |        server_job:0184| 	ABORT	----	kevin-paladin/R60-9475.0.0-rc3/bvt-inline/login_RemoteOwnership	timestamp=1492598234	localtime=Apr 19 03:37:14	
04/19 03:37:14.666 INFO |        server_job:0184| END ABORT	----	kevin-paladin/R60-9475.0.0-rc3/bvt-inline/login_RemoteOwnership	timestamp=1492598234	localtime=Apr 19 03:37:14	
04/19 03:37:15.018 INFO |        server_job:0184| START	----	kevin-paladin/R60-9475.0.0-rc3/bvt-inline/security_ChromiumOSLSM	timestamp=1492598235	localtime=Apr 19 03:37:15	
04/19 03:37:15.019 INFO |        server_job:0184| 	ABORT	----	kevin-paladin/R60-9475.0.0-rc3/bvt-inline/security_ChromiumOSLSM	timestamp=1492598235	localtime=Apr 19 03:37:15	
04/19 03:37:15.020 INFO |        server_job:0184| END ABORT	----	kevin-paladin/R60-9475.0.0-rc3/bvt-inline/security_ChromiumOSLSM	timestamp=1492598235	localtime=Apr 19 03:37:15	
04/19 03:37:15.334 INFO |        server_job:0184| START	----	kevin-paladin/R60-9475.0.0-rc3/bvt-inline/logging_CrashSender	timestamp=1492598235	localtime=Apr 19 03:37:15	
04/19 03:37:15.334 INFO |        server_job:0184| 	ABORT	----	kevin-paladin/R60-9475.0.0-rc3/bvt-inline/logging_CrashSender	timestamp=1492598235	localtime=Apr 19 03:37:15	
04/19 03:37:15.335 INFO |        server_job:0184| END ABORT	----	kevin-paladin/R60-9475.0.0-rc3/bvt-inline/logging_CrashSender	timestamp=1492598235	localtime=Apr 19 03:37:15	
04/19 03:37:15.672 INFO |        server_job:0184| START	----	kevin-paladin/R60-9475.0.0-rc3/bvt-inline/desktopui_KillRestart	timestamp=1492598235	localtime=Apr 19 03:37:15	
04/19 03:37:15.672 INFO |        server_job:0184| 	ABORT	----	kevin-paladin/R60-9475.0.0-rc3/bvt-inline/desktopui_KillRestart	timestamp=1492598235	localtime=Apr 19 03:37:15	
04/19 03:37:15.673 INFO |        server_job:0184| END ABORT	----	kevin-paladin/R60-9475.0.0-rc3/bvt-inline/desktopui_KillRestart	timestamp=1492598235	localtime=Apr 19 03:37:15	
04/19 03:37:21.039 INFO |        server_job:0184| START	----	kevin-paladin/R60-9475.0.0-rc3/bvt-inline/build_RootFilesystemSize	timestamp=1492598241	localtime=Apr 19 03:37:21	
04/19 03:37:21.039 INFO |        server_job:0184| 	ABORT	----	kevin-paladin/R60-9475.0.0-rc3/bvt-inline/build_RootFilesystemSize	timestamp=1492598241	localtime=Apr 19 03:37:21	
04/19 03:37:21.039 INFO |        server_job:0184| END ABORT	----	kevin-paladin/R60-9475.0.0-rc3/bvt-inline/build_RootFilesystemSize	timestamp=1492598241	localtime=Apr 19 03:37:21
 

Comment 1 by aut...@google.com, Apr 25 2017

Cc: chingcodes@chromium.org
+ deputy to keep an eye out for this

Comment 2 by aut...@google.com, May 16 2017

Status: Unconfirmed (was: Untriaged)
Cc: dgarr...@chromium.org vadimt@chromium.org deanliao@chromium.org dtor@chromium.org ecgh@chromium.org
Just saw this on veyron_minnie-tot-chrome-pfq-informational [1]. HWTest_bvt-inline failed with "Reason: Tests were aborted before running; suite must have timed out." But this happened after the HWTest step had been running for about an hour, well below the timeout of 180 minutes.

Autotest logs include:
12/11 14:33:26.473 INFO |        server_job:0218| START	----	veyron_minnie-tot-chrome-pfq-informational/R65-10206.0.0-b6824/bvt-inline/desktopui_KillRestart	timestamp=1513031606	localtime=Dec 11 14:33:26	
12/11 14:33:26.474 INFO |        server_job:0218| 	ABORT	----	veyron_minnie-tot-chrome-pfq-informational/R65-10206.0.0-b6824/bvt-inline/desktopui_KillRestart	timestamp=1513031606	localtime=Dec 11 14:33:26	
12/11 14:33:26.475 INFO |        server_job:0218| END ABORT	----	veyron_minnie-tot-chrome-pfq-informational/R65-10206.0.0-b6824/bvt-inline/desktopui_KillRestart	timestamp=1513031606	localtime=Dec 11 14:33:26	


[1] https://ci.chromium.org/buildbot/chromeos.chrome/veyron_minnie-tot-chrome-pfq-informational/6824

Cc: jrbarnette@chromium.org
The test in question was scheduled an hour before it started:

12/11 13:35:11.491 DEBUG|             suite:1120| Scheduling desktopui_KillRestart
12/11 13:35:11.926 DEBUG|             suite:1385| Adding job keyval for desktopui_KillRestart=161835816-chromeos-test


But took a long time to start, then was aborted right away:

12/11 14:33:26.473 INFO |        server_job:0218| START	----	veyron_minnie-tot-chrome-pfq-informational/R65-10206.0.0-b6824/bvt-inline/desktopui_KillRestart	timestamp=1513031606	localtime=Dec 11 14:33:26	
12/11 14:33:26.474 INFO |        server_job:0218| 	ABORT	----	veyron_minnie-tot-chrome-pfq-informational/R65-10206.0.0-b6824/bvt-inline/desktopui_KillRestart	timestamp=1513031606	localtime=Dec 11 14:33:26	
12/11 14:33:26.475 INFO |        server_job:0218| END ABORT	----	veyron_minnie-tot-chrome-pfq-informational/R65-10206.0.0-b6824/bvt-inline/desktopui_KillRestart	timestamp=1513031606	localtime=Dec 11 14:33:26	


However, there were plenty of other tests that were started even later and ran normally:

12/11 14:42:24.868 INFO |        server_job:0218| START	161835906-chromeos-test/chromeos2-row6-rack2-host3/security_RootCA	security_RootCA	timestamp=1513031858	localtime=Dec 11 14:37:38	
12/11 14:42:24.868 INFO |        server_job:0218| 	GOOD	161835906-chromeos-test/chromeos2-row6-rack2-host3/security_RootCA	security_RootCA	timestamp=1513031876	localtime=Dec 11 14:37:56	completed successfully
12/11 14:42:24.869 INFO |        server_job:0218| END GOOD	161835906-chromeos-test/chromeos2-row6-rack2-host3/security_RootCA	security_RootCA	
I don't understand what happened.
Status: Archived (was: Unconfirmed)
Bulk closing old unconfirmed issues.

Comment 8 by vadimt@chromium.org, Mar 31 2018

Cc: -vadimt@chromium.org

Sign in to add a comment