[06:16:06.888] New invocation is queued and will start shortly
[06:16:08.152] Starting the invocation (attempt 1)
[06:16:08.263] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[06:16:08.263] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[06:16:08.263] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9030739223213186544",
"parameters_json": "{\"builder_name\":\"linux-chromeos-dbg\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"41b69354b1701e4ae14ca44bd543373ae0558fd3\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-dbg",
"scheduler_invocation_id:9030739223213186544",
"scheduler_job_id:chromium/linux-chromeos-dbg",
"user_agent:luci-scheduler",
"buildset:commit/git/41b69354b1701e4ae14ca44bd543373ae0558fd3",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/41b69354b1701e4ae14ca44bd543373ae0558fd3",
"gitiles_ref:refs/heads/master"
]
}
[06:16:09.099] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1603779368394302",
"id": "8865309160965069600",
"parameters_json": "{\"builder_name\": \"linux-chromeos-dbg\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"41b69354b1701e4ae14ca44bd543373ae0558fd3\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1603779368854759",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-dbg/21025",
"builder:linux-chromeos-dbg",
"buildset:commit/git/41b69354b1701e4ae14ca44bd543373ae0558fd3",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/41b69354b1701e4ae14ca44bd543373ae0558fd3",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9030739223213186544",
"scheduler_job_id:chromium/linux-chromeos-dbg",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8865309160965069600/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:recipe_name:chromium",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:",
"user_agent:luci-scheduler"
],
"updated_ts": "1603779368854913",
"url": "https://ci.chromium.org/b/8865309160965069600",
"utcnow_ts": "1603779369085546"
}
}
[06:16:09.099] Task URL: https://ci.chromium.org/b/8865309160965069600
[06:16:09.099] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:2:0) after 1m0s
[06:16:36.009] Received PubSub notification, asking Buildbucket for the build status
[06:16:36.314] Build 8865309160965069600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:17:09.122] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:2:0)
[06:17:09.122] Timer tick, asking Buildbucket for the build status
[06:17:09.323] Build 8865309160965069600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:17:09.323] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:4:0) after 1m0s
[06:18:09.431] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:4:0)
[06:18:09.431] Timer tick, asking Buildbucket for the build status
[06:18:09.566] Build 8865309160965069600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:18:09.566] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:5:0) after 1m0s
[06:19:09.569] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:5:0)
[06:19:09.569] Timer tick, asking Buildbucket for the build status
[06:19:09.646] Build 8865309160965069600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:19:09.646] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:6:0) after 1m0s
[06:20:09.678] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:6:0)
[06:20:09.678] Timer tick, asking Buildbucket for the build status
[06:20:09.740] Build 8865309160965069600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:20:09.740] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:7:0) after 1m0s
[06:21:09.765] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:7:0)
[06:21:09.765] Timer tick, asking Buildbucket for the build status
[06:21:09.884] Build 8865309160965069600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:21:09.884] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:8:0) after 1m0s
[06:22:09.930] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:8:0)
[06:22:09.930] Timer tick, asking Buildbucket for the build status
[06:22:10.028] Build 8865309160965069600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:22:10.028] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:9:0) after 1m0s
[06:23:10.133] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:9:0)
[06:23:10.133] Timer tick, asking Buildbucket for the build status
[06:23:10.503] Build 8865309160965069600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:23:10.503] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:10:0) after 1m0s
[06:24:10.541] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:10:0)
[06:24:10.541] Timer tick, asking Buildbucket for the build status
[06:24:10.616] Build 8865309160965069600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:24:10.616] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:11:0) after 1m0s
[06:25:10.591] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:11:0)
[06:25:10.591] Timer tick, asking Buildbucket for the build status
[06:25:10.670] Build 8865309160965069600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:25:10.670] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:12:0) after 1m0s
[06:26:10.692] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:12:0)
[06:26:10.692] Timer tick, asking Buildbucket for the build status
[06:26:10.819] Build 8865309160965069600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:26:10.819] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:13:0) after 1m0s
[06:27:10.852] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:13:0)
[06:27:10.852] Timer tick, asking Buildbucket for the build status
[06:27:10.970] Build 8865309160965069600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:27:10.970] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:14:0) after 1m0s
[06:28:10.957] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:14:0)
[06:28:10.957] Timer tick, asking Buildbucket for the build status
[06:28:11.023] Build 8865309160965069600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:28:11.023] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:15:0) after 1m0s
[06:29:11.062] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:15:0)
[06:29:11.062] Timer tick, asking Buildbucket for the build status
[06:29:11.935] Build 8865309160965069600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:29:11.935] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:16:0) after 1m0s
[06:30:12.084] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:16:0)
[06:30:12.084] Timer tick, asking Buildbucket for the build status
[06:30:12.267] Build 8865309160965069600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:30:12.267] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:17:0) after 1m0s
[06:31:12.392] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:17:0)
[06:31:12.392] Timer tick, asking Buildbucket for the build status
[06:31:12.695] Build 8865309160965069600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:31:12.695] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:18:0) after 1m0s
[06:32:12.830] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:18:0)
[06:32:12.830] Timer tick, asking Buildbucket for the build status
[06:32:13.120] Build 8865309160965069600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:32:13.120] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:19:0) after 1m0s
[06:33:13.208] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:19:0)
[06:33:13.208] Timer tick, asking Buildbucket for the build status
[06:33:13.379] Build 8865309160965069600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:33:13.379] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:20:0) after 1m0s
[06:34:13.417] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:20:0)
[06:34:13.417] Timer tick, asking Buildbucket for the build status
[06:34:13.492] Build 8865309160965069600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:34:13.492] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:21:0) after 1m0s
[06:35:13.516] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:21:0)
[06:35:13.516] Timer tick, asking Buildbucket for the build status
[06:35:13.597] Build 8865309160965069600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:35:13.597] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:22:0) after 1m0s
[06:36:13.871] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:22:0)
[06:36:13.871] Timer tick, asking Buildbucket for the build status
[06:36:14.082] Build 8865309160965069600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:36:14.082] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:23:0) after 1m0s
[06:37:14.196] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:23:0)
[06:37:14.196] Timer tick, asking Buildbucket for the build status
[06:37:14.331] Build 8865309160965069600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:37:14.331] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:24:0) after 1m0s
[06:38:14.527] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:24:0)
[06:38:14.527] Timer tick, asking Buildbucket for the build status
[06:38:15.150] Build 8865309160965069600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:38:15.150] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:25:0) after 1m0s
[06:39:15.306] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:25:0)
[06:39:15.306] Timer tick, asking Buildbucket for the build status
[06:39:15.376] Build 8865309160965069600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:39:15.376] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:26:0) after 1m0s
[06:40:15.433] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:26:0)
[06:40:15.433] Timer tick, asking Buildbucket for the build status
[06:40:15.521] Build 8865309160965069600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:40:15.521] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:27:0) after 1m0s
[06:41:15.800] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:27:0)
[06:41:15.800] Timer tick, asking Buildbucket for the build status
[06:41:15.917] Build 8865309160965069600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:41:15.917] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:28:0) after 1m0s
[06:42:16.089] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:28:0)
[06:42:16.089] Timer tick, asking Buildbucket for the build status
[06:42:16.157] Build 8865309160965069600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:42:16.157] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:29:0) after 1m0s
[06:43:16.576] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:29:0)
[06:43:16.576] Timer tick, asking Buildbucket for the build status
[06:43:16.640] Build 8865309160965069600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:43:16.640] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:30:0) after 1m0s
[06:44:16.698] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:30:0)
[06:44:16.698] Timer tick, asking Buildbucket for the build status
[06:44:16.776] Build 8865309160965069600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:44:16.776] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:31:0) after 1m0s
[06:45:16.919] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:31:0)
[06:45:16.919] Timer tick, asking Buildbucket for the build status
[06:45:17.092] Build 8865309160965069600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:45:17.092] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:32:0) after 1m0s
[06:46:17.103] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:32:0)
[06:46:17.103] Timer tick, asking Buildbucket for the build status
[06:46:17.217] Build 8865309160965069600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:46:17.217] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:33:0) after 1m0s
[06:47:17.302] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:33:0)
[06:47:17.302] Timer tick, asking Buildbucket for the build status
[06:47:17.391] Build 8865309160965069600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:47:17.391] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:34:0) after 1m0s
[06:48:17.473] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:34:0)
[06:48:17.473] Timer tick, asking Buildbucket for the build status
[06:48:17.561] Build 8865309160965069600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:48:17.561] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:35:0) after 1m0s
[06:49:17.622] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:35:0)
[06:49:17.622] Timer tick, asking Buildbucket for the build status
[06:49:17.709] Build 8865309160965069600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:49:17.709] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:36:0) after 1m0s
[06:50:17.813] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:36:0)
[06:50:17.813] Timer tick, asking Buildbucket for the build status
[06:50:17.898] Build 8865309160965069600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:50:17.898] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:37:0) after 1m0s
[06:51:17.995] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:37:0)
[06:51:17.995] Timer tick, asking Buildbucket for the build status
[06:51:18.490] Build 8865309160965069600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:51:18.490] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:38:0) after 1m0s
[06:52:18.528] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:38:0)
[06:52:18.528] Timer tick, asking Buildbucket for the build status
[06:52:18.716] Build 8865309160965069600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:52:18.716] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:39:0) after 1m0s
[06:53:18.891] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:39:0)
[06:53:18.891] Timer tick, asking Buildbucket for the build status
[06:53:18.956] Build 8865309160965069600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:53:18.956] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:40:0) after 1m0s
[06:54:19.075] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:40:0)
[06:54:19.075] Timer tick, asking Buildbucket for the build status
[06:54:19.167] Build 8865309160965069600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:54:19.167] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:41:0) after 1m0s
[06:55:19.235] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:41:0)
[06:55:19.235] Timer tick, asking Buildbucket for the build status
[06:55:19.353] Build 8865309160965069600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:55:19.353] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:42:0) after 1m0s
[06:56:19.375] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:42:0)
[06:56:19.375] Timer tick, asking Buildbucket for the build status
[06:56:19.496] Build 8865309160965069600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:56:19.496] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:43:0) after 1m0s
[06:57:19.585] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:43:0)
[06:57:19.585] Timer tick, asking Buildbucket for the build status
[06:57:19.657] Build 8865309160965069600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:57:19.657] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:44:0) after 1m0s
[06:58:19.879] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:44:0)
[06:58:19.879] Timer tick, asking Buildbucket for the build status
[06:58:20.101] Build 8865309160965069600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:58:20.101] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:45:0) after 1m0s
[06:59:20.188] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:45:0)
[06:59:20.188] Timer tick, asking Buildbucket for the build status
[06:59:20.340] Build 8865309160965069600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:59:20.340] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:46:0) after 1m0s
[07:00:20.472] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:46:0)
[07:00:20.472] Timer tick, asking Buildbucket for the build status
[07:00:20.719] Build 8865309160965069600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:00:20.719] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:47:0) after 1m0s
[07:01:20.753] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:47:0)
[07:01:20.753] Timer tick, asking Buildbucket for the build status
[07:01:21.082] Build 8865309160965069600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:01:21.082] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:48:0) after 1m0s
[07:02:21.195] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:48:0)
[07:02:21.195] Timer tick, asking Buildbucket for the build status
[07:02:21.678] Build 8865309160965069600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:02:21.678] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:49:0) after 1m0s
[07:03:21.722] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:49:0)
[07:03:21.722] Timer tick, asking Buildbucket for the build status
[07:03:22.015] Build 8865309160965069600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:03:22.015] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:50:0) after 1m0s
[07:04:22.041] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:50:0)
[07:04:22.041] Timer tick, asking Buildbucket for the build status
[07:04:22.156] Build 8865309160965069600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:04:22.156] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:51:0) after 1m0s
[07:05:22.264] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:51:0)
[07:05:22.265] Timer tick, asking Buildbucket for the build status
[07:05:22.597] Build 8865309160965069600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:05:22.597] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:52:0) after 1m0s
[07:06:22.991] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:52:0)
[07:06:22.991] Timer tick, asking Buildbucket for the build status
[07:06:23.411] Build 8865309160965069600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:06:23.411] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:53:0) after 1m0s
[07:07:23.588] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:53:0)
[07:07:23.588] Timer tick, asking Buildbucket for the build status
[07:07:23.818] Build 8865309160965069600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:07:23.818] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:54:0) after 1m0s
[07:08:23.960] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:54:0)
[07:08:23.960] Timer tick, asking Buildbucket for the build status
[07:08:24.043] Build 8865309160965069600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:08:24.043] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:55:0) after 1m0s
[07:09:24.131] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:55:0)
[07:09:24.131] Timer tick, asking Buildbucket for the build status
[07:09:24.265] Build 8865309160965069600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:09:24.265] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:56:0) after 1m0s
[07:10:24.394] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:56:0)
[07:10:24.394] Timer tick, asking Buildbucket for the build status
[07:10:24.675] Build 8865309160965069600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:10:24.675] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:57:0) after 1m0s
[07:11:24.733] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:57:0)
[07:11:24.733] Timer tick, asking Buildbucket for the build status
[07:11:25.095] Build 8865309160965069600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:11:25.095] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:58:0) after 1m0s
[07:12:25.143] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:58:0)
[07:12:25.143] Timer tick, asking Buildbucket for the build status
[07:12:25.359] Build 8865309160965069600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:12:25.359] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:59:0) after 1m0s
[07:13:25.425] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:59:0)
[07:13:25.425] Timer tick, asking Buildbucket for the build status
[07:13:25.535] Build 8865309160965069600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:13:25.535] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:60:0) after 1m0s
[07:14:25.560] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:60:0)
[07:14:25.560] Timer tick, asking Buildbucket for the build status
[07:14:25.732] Build 8865309160965069600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:14:25.732] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:61:0) after 1m0s
[07:15:25.818] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:61:0)
[07:15:25.818] Timer tick, asking Buildbucket for the build status
[07:15:25.961] Build 8865309160965069600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:15:25.961] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:62:0) after 1m0s
[07:16:26.137] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:62:0)
[07:16:26.137] Timer tick, asking Buildbucket for the build status
[07:16:26.322] Build 8865309160965069600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:16:26.322] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:63:0) after 1m0s
[07:17:26.452] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:63:0)
[07:17:26.452] Timer tick, asking Buildbucket for the build status
[07:17:26.529] Build 8865309160965069600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:17:26.529] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:64:0) after 1m0s
[07:18:26.666] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:64:0)
[07:18:26.666] Timer tick, asking Buildbucket for the build status
[07:18:26.818] Build 8865309160965069600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:18:26.818] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:65:0) after 1m0s
[07:19:26.911] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:65:0)
[07:19:26.911] Timer tick, asking Buildbucket for the build status
[07:19:27.074] Build 8865309160965069600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:19:27.074] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:66:0) after 1m0s
[07:20:27.228] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:66:0)
[07:20:27.228] Timer tick, asking Buildbucket for the build status
[07:20:27.439] Build 8865309160965069600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:20:27.439] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:67:0) after 1m0s
[07:21:27.606] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:67:0)
[07:21:27.606] Timer tick, asking Buildbucket for the build status
[07:21:27.886] Build 8865309160965069600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:21:27.886] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:68:0) after 1m0s
[07:22:27.941] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:68:0)
[07:22:27.941] Timer tick, asking Buildbucket for the build status
[07:22:28.058] Build 8865309160965069600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:22:28.058] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:69:0) after 1m0s
[07:23:28.087] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:69:0)
[07:23:28.087] Timer tick, asking Buildbucket for the build status
[07:23:28.238] Build 8865309160965069600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:23:28.238] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:70:0) after 1m0s
[07:24:28.349] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:70:0)
[07:24:28.350] Timer tick, asking Buildbucket for the build status
[07:24:28.587] Build 8865309160965069600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:24:28.588] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:71:0) after 1m0s
[07:25:28.686] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:71:0)
[07:25:28.686] Timer tick, asking Buildbucket for the build status
[07:25:28.881] Build 8865309160965069600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:25:28.881] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:72:0) after 1m0s
[07:26:28.940] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:72:0)
[07:26:28.940] Timer tick, asking Buildbucket for the build status
[07:26:29.065] Build 8865309160965069600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:26:29.065] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:73:0) after 1m0s
[07:27:29.169] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:73:0)
[07:27:29.169] Timer tick, asking Buildbucket for the build status
[07:27:29.236] Build 8865309160965069600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:27:29.236] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:74:0) after 1m0s
[07:28:29.380] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:74:0)
[07:28:29.380] Timer tick, asking Buildbucket for the build status
[07:28:29.660] Build 8865309160965069600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:28:29.660] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:75:0) after 1m0s
[07:29:30.021] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:75:0)
[07:29:30.021] Timer tick, asking Buildbucket for the build status
[07:29:30.093] Build 8865309160965069600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:29:30.093] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:76:0) after 1m0s
[07:30:30.145] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:76:0)
[07:30:30.145] Timer tick, asking Buildbucket for the build status
[07:30:30.218] Build 8865309160965069600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:30:30.219] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:77:0) after 1m0s
[07:31:30.263] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:77:0)
[07:31:30.263] Timer tick, asking Buildbucket for the build status
[07:31:30.441] Build 8865309160965069600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:31:30.441] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:78:0) after 1m0s
[07:32:30.580] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:78:0)
[07:32:30.580] Timer tick, asking Buildbucket for the build status
[07:32:31.361] Build 8865309160965069600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:32:31.361] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:79:0) after 1m0s
[07:33:31.374] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:79:0)
[07:33:31.374] Timer tick, asking Buildbucket for the build status
[07:33:31.549] Build 8865309160965069600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:33:31.549] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:80:0) after 1m0s
[07:34:31.582] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:80:0)
[07:34:31.582] Timer tick, asking Buildbucket for the build status
[07:34:32.201] Build 8865309160965069600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:34:32.201] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:81:0) after 1m0s
[07:35:32.266] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:81:0)
[07:35:32.266] Timer tick, asking Buildbucket for the build status
[07:35:32.503] Build 8865309160965069600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:35:32.503] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:82:0) after 1m0s
[07:36:32.612] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:82:0)
[07:36:32.612] Timer tick, asking Buildbucket for the build status
[07:36:32.974] Build 8865309160965069600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:36:32.974] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:83:0) after 1m0s
[07:37:33.000] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:83:0)
[07:37:33.000] Timer tick, asking Buildbucket for the build status
[07:37:33.220] Build 8865309160965069600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:37:33.220] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:84:0) after 1m0s
[07:38:33.283] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:84:0)
[07:38:33.283] Timer tick, asking Buildbucket for the build status
[07:38:33.545] Build 8865309160965069600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:38:33.546] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:85:0) after 1m0s
[07:39:33.543] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:85:0)
[07:39:33.543] Timer tick, asking Buildbucket for the build status
[07:39:34.242] Build 8865309160965069600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:39:34.242] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:86:0) after 1m0s
[07:40:34.285] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:86:0)
[07:40:34.285] Timer tick, asking Buildbucket for the build status
[07:40:35.657] Build 8865309160965069600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:40:35.657] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:87:0) after 1m0s
[07:41:35.670] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:87:0)
[07:41:35.670] Timer tick, asking Buildbucket for the build status
[07:41:35.930] Build 8865309160965069600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:41:35.930] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:88:0) after 1m0s
[07:42:36.016] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:88:0)
[07:42:36.016] Timer tick, asking Buildbucket for the build status
[07:42:36.115] Build 8865309160965069600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:42:36.115] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:89:0) after 1m0s
[07:43:36.159] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:89:0)
[07:43:36.159] Timer tick, asking Buildbucket for the build status
[07:43:36.432] Build 8865309160965069600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:43:36.432] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:90:0) after 1m0s
[07:44:36.458] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:90:0)
[07:44:36.458] Timer tick, asking Buildbucket for the build status
[07:44:36.589] Build 8865309160965069600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:44:36.589] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:91:0) after 1m0s
[07:45:36.654] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:91:0)
[07:45:36.654] Timer tick, asking Buildbucket for the build status
[07:45:36.723] Build 8865309160965069600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:45:36.723] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:92:0) after 1m0s
[07:46:36.820] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:92:0)
[07:46:36.820] Timer tick, asking Buildbucket for the build status
[07:46:36.973] Build 8865309160965069600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:46:36.973] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:93:0) after 1m0s
[07:47:37.001] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:93:0)
[07:47:37.001] Timer tick, asking Buildbucket for the build status
[07:47:37.077] Build 8865309160965069600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:47:37.077] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:94:0) after 1m0s
[07:48:37.108] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:94:0)
[07:48:37.108] Timer tick, asking Buildbucket for the build status
[07:48:37.242] Build 8865309160965069600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:48:37.242] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:95:0) after 1m0s
[07:49:37.274] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:95:0)
[07:49:37.274] Timer tick, asking Buildbucket for the build status
[07:49:37.365] Build 8865309160965069600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:49:37.365] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:96:0) after 1m0s
[07:50:37.390] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:96:0)
[07:50:37.390] Timer tick, asking Buildbucket for the build status
[07:50:37.481] Build 8865309160965069600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:50:37.481] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:97:0) after 1m0s
[07:51:37.611] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:97:0)
[07:51:37.611] Timer tick, asking Buildbucket for the build status
[07:51:37.745] Build 8865309160965069600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:51:37.745] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:98:0) after 1m0s
[07:52:37.782] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:98:0)
[07:52:37.782] Timer tick, asking Buildbucket for the build status
[07:52:37.990] Build 8865309160965069600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:52:37.990] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:99:0) after 1m0s
[07:53:38.017] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:99:0)
[07:53:38.017] Timer tick, asking Buildbucket for the build status
[07:53:38.242] Build 8865309160965069600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:53:38.242] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030739223213186544:100:0) after 1m0s
[07:53:59.126] Received PubSub notification, asking Buildbucket for the build status
[07:53:59.206] Build 8865309160965069600: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[07:53:59.206] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1603785237516171",
"created_by": "project:chromium",
"created_ts": "1603779368394302",
"failure_reason": "BUILD_FAILURE",
"id": "8865309160965069600",
"parameters_json": "{\"builder_name\": \"linux-chromeos-dbg\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"41b69354b1701e4ae14ca44bd543373ae0558fd3\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"1 Test Suite(s) failed.\\n\\n**ash_unittests** failed because of:\\n\\n- HoldingSpaceTrayTest.ShowTrayButtonOnFirstUse\"}, \"properties\": {\"$build/goma\": {\"enable_ats\": true, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\", \"use_luci_auth\": true}, \"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/milo/blamelist_pins\": [{\"host\": \"chromium.googlesource.com\", \"id\": \"41b69354b1701e4ae14ca44bd543373ae0558fd3\", \"project\": \"chromium/src\"}], \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-chromium-ci-xenial-us-central1-b-8-70-a1u5\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-dbg\", \"buildnumber\": 21025, \"got_angle_revision\": \"b5ba693316fdd5e680f2bb4cf1a2f9bed589d8e2\", \"got_dawn_revision\": \"41c24933da1e6c5ec111f824bfc5977be244d3e4\", \"got_nacl_revision\": \"0fabd6dc4222dfae664e55a84326e0c904f5b62c\", \"got_revision\": \"41b69354b1701e4ae14ca44bd543373ae0558fd3\", \"got_revision_cp\": \"refs/heads/master@{#821107}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"df17a76102dfabb3f1bd6e51449cece9f77b45e3\", \"got_v8_revision\": \"af722073314e07138cd3ef04751dc7ce1035219c\", \"got_v8_revision_cp\": \"refs/heads/8.8.178@{#1}\", \"got_webrtc_revision\": \"0fb0eb3e80c09e0483c4dccdaa472e7e81f182fe\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32491}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"41b69354b1701e4ae14ca44bd543373ae0558fd3\", \"swarm_hashes_refs/heads/master(at){#821107}_without_patch\": {\"absl_hardening_tests\": \"f2cbd4382bd560a9136aa3da745b566179858622\", \"accessibility_unittests\": \"4c3fa78a8ff22c396a94a89c52981a0143ae7a39\", \"angle_unittests\": \"b6fbf7e70390f2e9f2c6ba61ab740817ef505202\", \"app_list_unittests\": \"7c497683c07065ca08ac573f83b24768514f45a7\", \"app_shell_unittests\": \"e3f9c1854335ad0bc878b57e916f6b51d63d3036\", \"ash_unittests\": \"54b809df8d73cce8b7538c0ab1d30f1032c2457e\", \"aura_unittests\": \"1ffed54317ef12068167ae2d5d1c7a6d38558a9e\", \"base_unittests\": \"7e125bfa708ec23dd3e231483c41e611ae085a61\", \"base_util_unittests\": \"d5e970ed2a10c7fde329965a43cccfd03438c2a8\", \"blink_common_unittests\": \"34f634f24d37a2a078a8be79144efb68254126d7\", \"blink_fuzzer_unittests\": \"f3e952f949619c16a9fdf6276eb7089e6261766f\", \"blink_heap_unittests\": \"b6d081311c333e302d4232a9b24ff0ec5203a6cb\", \"blink_platform_unittests\": \"65c92d0836bd83060104ade3c4cebf668a72b2db\", \"blink_unittests\": \"cb9a54a2457496aff07a8dd8488c8b2f587ba7b2\", \"boringssl_crypto_tests\": \"b2676948b609eb0e86c93c89aa3349b953a229b6\", \"boringssl_ssl_tests\": \"ac4fbc9f60bc66cb4dfe3324e91b286fd00e3a31\", \"browser_tests\": \"f62fa97dcd9eaf59afa7d6e042099bd029f6266b\", \"capture_unittests\": \"879623ceb3bf0df5cd1155d1f279c24647e55c99\", \"cast_unittests\": \"3d235828759b39b6feae03c20069a389d28e1e35\", \"cc_unittests\": \"7ace30fe3b5803069b6321e7c5b8a71be627d15e\", \"chrome_app_unittests\": \"d4cd1a294aaf142db29913d628fa5210f67bb90b\", \"chromedriver_unittests\": \"174ba9990f07e2a7d55903546885890515276323\", \"chromeos_components_unittests\": \"fec9041a032fea3e1f333c43c0988f6e128f0e21\", \"chromeos_unittests\": \"bf825400e8855373d0eb28352894b93de7b4bad7\", \"components_browsertests\": \"ed89c6e78cf69168fa7c4cba1ee1d510a7219cbe\", \"components_unittests\": \"6c0aaee062fe505be0cd066332e6cda575ca12e5\", \"compositor_unittests\": \"da900fe925d536981809cfa8478fd6120011601d\", \"content_browsertests\": \"4781ab65680a0a55b5e36c3e710a33fabc5e2b23\", \"content_unittests\": \"1c70fb7ac0262e5ac2bea116837de411a5be4f13\", \"crypto_unittests\": \"e94fbb18ee379cc7ac0973f0cd5af09407e3aca0\", \"dbus_unittests\": \"65aac1ca2bc4ed4dcdca755948e2835fb78908b6\", \"device_unittests\": \"4c7b35caf977ed8bf3d1a06a1ef2fb1b17104ae2\", \"display_unittests\": \"78d358178b70b2d33988eafc212915a7418921c0\", \"events_unittests\": \"78dc0bd365731244ce93b82cf64fa2423e2a60d4\", \"exo_unittests\": \"2e2cc295889562ad021460df490d00701548faed\", \"extensions_browsertests\": \"bb5b455df63f051e7564a44e76f2273f6b5f6049\", \"extensions_unittests\": \"3865b06de8f53d0d5990fb0c258f2bfc47913146\", \"filesystem_service_unittests\": \"0b4a6fe6480fa84a92bc232fdbde2279c64d728f\", \"gcm_unit_tests\": \"aa7cebfd822d73d60bdc5fb6865c5f1b8bccd6d4\", \"gfx_unittests\": \"fec7febe2c2a7a649883ece91aac6f61eb14b090\", \"gin_unittests\": \"28b50d5534cfdd19fd3d00f9f688c6ab08d3fe07\", \"gl_unittests_ozone\": \"c6a17cbab9a854fd7b6116aeaf58ff03f301b652\", \"google_apis_unittests\": \"ee231088e624d81ddfd14a4cba78df917cd65173\", \"gpu_unittests\": \"031194f12d27f6a363129546289a4efe6232be75\", \"gwp_asan_unittests\": \"1d6dc1ea2654d463ac31db46bd355d71105351df\", \"interactive_ui_tests\": \"71a7433f36a3245bfd33ae8d5fa77222059f7eb2\", \"ipc_tests\": \"cdee81ee40423178693f5b4986cf7131f285bcca\", \"jingle_unittests\": \"221a9fe5c68f30ab0dce3253aba6c43638e58a81\", \"keyboard_unittests\": \"3664a4bee25e82accd126571c45ffaddb18283ec\", \"latency_unittests\": \"27d3b2b1e7a3d531c8eac4c261e070ad851afe6a\", \"libjingle_xmpp_unittests\": \"42bd3118faf3f1eabf4ea9b0624d97d8df129fe3\", \"media_blink_unittests\": \"ad084a0e11e0dab4cd50ddc1b07ac901ade69a69\", \"media_unittests\": \"75d625275b9dc71eb7628995650dc6590aca4cdc\", \"message_center_unittests\": \"edc5e8ddc7e65946718479ed3aa5f991c41bcf9f\", \"midi_unittests\": \"6cbad00397b3995492da04e024516f88acb638fe\", \"mojo_core_unittests\": \"85497d70bac064bb8727ef65e5ffb35ca712c926\", \"mojo_unittests\": \"b28b63c62f799b7e624c74fec1e8f2eccc0d1b0f\", \"nacl_helper_nonsfi_unittests\": \"a256de5a5ea7d05e8f1fb67f1bb49c4ce3405be7\", \"nacl_loader_unittests\": \"fc0d2c41f610233bd994a08f6abac0c4675a76c0\", \"native_theme_unittests\": \"76edbf256b46007b739ca19d193861f13099f579\", \"net_unittests\": \"8b0be02c5f4b20ad1dc5ae619c71cd2b3b02b6f7\", \"ozone_gl_unittests\": \"5079055c65dd3e0caf0367eaf8097354b43a63a8\", \"ozone_unittests\": \"8452c2a84bfa9813aee443e43152a50692b39cdc\", \"ozone_x11_unittests\": \"c0d7a782688b348fdb575616e18fe21e80c8c6d2\", \"pdf_unittests\": \"ad9b69a1ddd2a8e26c6b2f7ec1a457afbf9b569e\", \"perfetto_unittests\": \"0e83fc4a4b1740f1d628ca59cf7c019a9e29c99b\", \"ppapi_unittests\": \"5a06a6dbf75855cf4488bda931ae65482b45ee6a\", \"printing_unittests\": \"d17e006a7863f26086bbfe8876c075896be2c771\", \"remoting_unittests\": \"c60bee45e4b1b6a0dfbf8eff80b66efa9616fb8c\", \"sandbox_linux_unittests\": \"d803d272e629f82d285b306d3e0e48b8961c771f\", \"service_manager_unittests\": \"c6739e007bce9fcd75107e2f6de70da33c2c436d\", \"services_unittests\": \"1e35c24c26a5dce4f46c77891441e3eabe9d6b93\", \"shell_dialogs_unittests\": \"5c12904c4234252da7d76ec6b9921fc8035776b8\", \"skia_unittests\": \"3e3eafa6b6b48864288e2212239bf3cb18cda501\", \"snapshot_unittests\": \"611e52de899c7a0d6dbcf1e7d34f9acac29237bd\", \"sql_unittests\": \"44d7d03235e89b6dec09fc93578b2f7486d65388\", \"storage_unittests\": \"73434471b549ffd4a8e7e59d1f1aa5e49888de4c\", \"sync_integration_tests\": \"1c577e5acb5d097dec9d98fe3b927820123180d7\", \"traffic_annotation_auditor_unittests\": \"0eaa1df16702b4093e0823b3ce35883d9aef055b\", \"ui_base_unittests\": \"f8972a86bf8cd7fc240d3ab7c178a02b412c4183\", \"ui_chromeos_unittests\": \"b0f3138911cc558fd6e695570ecac3cde2d417bb\", \"ui_touch_selection_unittests\": \"95de17bbce62c72fa887a64298f0b8a777991630\", \"unit_tests\": \"34ed94775f1eac2a1fc3d090d52c8a0f38410167\", \"url_unittests\": \"bdd26d788c2e495e84b6d7a993b0cf9bb1949d0e\", \"usage_time_limit_unittests\": \"5e40845b08833ee2601d599f08ac0296d7599fbc\", \"views_unittests\": \"8d51a86c71aef2cda18acb42571938fdf9614e63\", \"viz_unittests\": \"9c1648dadadd6917359a6e901ba53e17b342e20c\", \"wm_unittests\": \"58f5b8fd0895d39fdf346b2e7070f4079fe2d744\", \"wtf_unittests\": \"baca2e065b449a91570a4f228f2b1cc6ff6c0575\", \"zlib_unittests\": \"d5f2a93f71cd2509b7a3508631d0bd8be897dcb3\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_4973d939604d11dcbaec68575e69c80c756ff63e355ab013b29b8bca26434b7c_v2\", \"builder_b944d05f04021c9e96cb2c0814a44822758db0ee628018e3c9fb8f4cebb05c9d_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-xenial-us-central1-b-8-70-a1u5\"], \"image\": [\"chrome-xenial-20101800-2d400e1627f\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"e2-standard-8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16\", \"Ubuntu-16.04\", \"Ubuntu-16.04.6\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.12\"], \"server_version\": [\"5613-2fddb4d\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}, \"ui\": {\"info\": \"1 Test Suite(s) failed.\\n\\n**ash_unittests** failed because of:\\n\\n- HoldingSpaceTrayTest.ShowTrayButtonOnFirstUse\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1603779395437670",
"status": "COMPLETED",
"status_changed_ts": "1603779395780533",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-dbg/21025",
"builder:linux-chromeos-dbg",
"buildset:commit/git/41b69354b1701e4ae14ca44bd543373ae0558fd3",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/41b69354b1701e4ae14ca44bd543373ae0558fd3",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9030739223213186544",
"scheduler_job_id:chromium/linux-chromeos-dbg",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8865309160965069600/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:os:Linux",
"swarming_tag:recipe_name:chromium",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:4f8184a06be2e610",
"user_agent:luci-scheduler"
],
"updated_ts": "1603785238775088",
"url": "https://ci.chromium.org/b/8865309160965069600",
"utcnow_ts": "1603785239193085"
}
}
[07:53:59.206] Invocation finished in 1h37m52.339076021s with status FAILED