[08:17:58.309] New invocation is queued and will start shortly
[08:17:59.519] Starting the invocation (attempt 1)
[08:17:59.562] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[08:17:59.562] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[08:17:59.563] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9023030814483592752",
"parameters_json": "{\"builder_name\":\"linux-chromeos-dbg\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"fbc26cbe546f8f1d6c76064b739d24d5fd37dfb2\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-dbg",
"scheduler_invocation_id:9023030814483592752",
"scheduler_job_id:chromium/linux-chromeos-dbg",
"user_agent:luci-scheduler",
"buildset:commit/git/fbc26cbe546f8f1d6c76064b739d24d5fd37dfb2",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/fbc26cbe546f8f1d6c76064b739d24d5fd37dfb2",
"gitiles_ref:refs/heads/master"
]
}
[08:18:00.577] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1611130679654468",
"id": "8857600752409542336",
"parameters_json": "{\"builder_name\": \"linux-chromeos-dbg\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"fbc26cbe546f8f1d6c76064b739d24d5fd37dfb2\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1611130680289965",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-dbg/22268",
"builder:linux-chromeos-dbg",
"buildset:commit/git/fbc26cbe546f8f1d6c76064b739d24d5fd37dfb2",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/fbc26cbe546f8f1d6c76064b739d24d5fd37dfb2",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023030814483592752",
"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/8857600752409542336/+/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": "1611130680290087",
"url": "https://ci.chromium.org/b/8857600752409542336",
"utcnow_ts": "1611130680563359"
}
}
[08:18:00.577] Task URL: https://ci.chromium.org/b/8857600752409542336
[08:18:00.577] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:2:0) after 1m0s
[08:19:01.053] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:2:0)
[08:19:01.053] Timer tick, asking Buildbucket for the build status
[08:19:01.187] Build 8857600752409542336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:19:01.187] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:3:0) after 1m0s
[08:20:01.213] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:3:0)
[08:20:01.213] Timer tick, asking Buildbucket for the build status
[08:20:01.284] Build 8857600752409542336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:20:01.284] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:4:0) after 1m0s
[08:21:01.469] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:4:0)
[08:21:01.469] Timer tick, asking Buildbucket for the build status
[08:21:01.750] Build 8857600752409542336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:21:01.750] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:5:0) after 1m0s
[08:21:35.205] Received PubSub notification, asking Buildbucket for the build status
[08:21:35.554] Build 8857600752409542336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:22:02.014] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:5:0)
[08:22:02.014] Timer tick, asking Buildbucket for the build status
[08:22:02.131] Build 8857600752409542336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:22:02.132] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:7:0) after 1m0s
[08:23:02.202] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:7:0)
[08:23:02.202] Timer tick, asking Buildbucket for the build status
[08:23:02.518] Build 8857600752409542336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:23:02.518] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:8:0) after 1m0s
[08:24:02.584] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:8:0)
[08:24:02.584] Timer tick, asking Buildbucket for the build status
[08:24:02.659] Build 8857600752409542336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:24:02.659] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:9:0) after 1m0s
[08:25:02.684] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:9:0)
[08:25:02.684] Timer tick, asking Buildbucket for the build status
[08:25:02.751] Build 8857600752409542336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:25:02.751] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:10:0) after 1m0s
[08:26:02.785] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:10:0)
[08:26:02.785] Timer tick, asking Buildbucket for the build status
[08:26:03.209] Build 8857600752409542336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:26:03.209] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:11:0) after 1m0s
[08:27:03.291] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:11:0)
[08:27:03.291] Timer tick, asking Buildbucket for the build status
[08:27:03.707] Build 8857600752409542336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:27:03.707] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:12:0) after 1m0s
[08:28:03.768] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:12:0)
[08:28:03.768] Timer tick, asking Buildbucket for the build status
[08:28:04.283] Build 8857600752409542336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:28:04.283] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:13:0) after 1m0s
[08:29:04.311] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:13:0)
[08:29:04.311] Timer tick, asking Buildbucket for the build status
[08:29:04.457] Build 8857600752409542336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:29:04.457] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:14:0) after 1m0s
[08:30:04.482] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:14:0)
[08:30:04.482] Timer tick, asking Buildbucket for the build status
[08:30:04.563] Build 8857600752409542336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:30:04.563] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:15:0) after 1m0s
[08:31:04.622] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:15:0)
[08:31:04.622] Timer tick, asking Buildbucket for the build status
[08:31:04.753] Build 8857600752409542336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:31:04.753] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:16:0) after 1m0s
[08:32:04.782] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:16:0)
[08:32:04.782] Timer tick, asking Buildbucket for the build status
[08:32:05.061] Build 8857600752409542336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:32:05.061] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:17:0) after 1m0s
[08:33:05.131] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:17:0)
[08:33:05.131] Timer tick, asking Buildbucket for the build status
[08:33:05.281] Build 8857600752409542336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:33:05.281] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:18:0) after 1m0s
[08:34:05.273] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:18:0)
[08:34:05.273] Timer tick, asking Buildbucket for the build status
[08:34:05.463] Build 8857600752409542336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:34:05.463] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:19:0) after 1m0s
[08:35:05.619] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:19:0)
[08:35:05.619] Timer tick, asking Buildbucket for the build status
[08:35:06.597] Build 8857600752409542336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:35:06.597] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:20:0) after 1m0s
[08:36:06.650] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:20:0)
[08:36:06.650] Timer tick, asking Buildbucket for the build status
[08:36:06.920] Build 8857600752409542336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:36:06.920] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:21:0) after 1m0s
[08:37:06.977] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:21:0)
[08:37:06.977] Timer tick, asking Buildbucket for the build status
[08:37:07.134] Build 8857600752409542336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:37:07.134] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:22:0) after 1m0s
[08:38:07.179] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:22:0)
[08:38:07.179] Timer tick, asking Buildbucket for the build status
[08:38:07.565] Build 8857600752409542336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:38:07.565] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:23:0) after 1m0s
[08:39:07.618] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:23:0)
[08:39:07.618] Timer tick, asking Buildbucket for the build status
[08:39:07.712] Build 8857600752409542336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:39:07.712] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:24:0) after 1m0s
[08:40:07.918] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:24:0)
[08:40:07.918] Timer tick, asking Buildbucket for the build status
[08:40:07.980] Build 8857600752409542336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:40:07.980] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:25:0) after 1m0s
[08:41:08.110] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:25:0)
[08:41:08.110] Timer tick, asking Buildbucket for the build status
[08:41:08.220] Build 8857600752409542336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:41:08.220] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:26:0) after 1m0s
[08:42:08.346] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:26:0)
[08:42:08.346] Timer tick, asking Buildbucket for the build status
[08:42:08.415] Build 8857600752409542336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:42:08.415] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:27:0) after 1m0s
[08:43:08.450] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:27:0)
[08:43:08.450] Timer tick, asking Buildbucket for the build status
[08:43:08.576] Build 8857600752409542336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:43:08.576] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:28:0) after 1m0s
[08:44:08.688] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:28:0)
[08:44:08.688] Timer tick, asking Buildbucket for the build status
[08:44:08.753] Build 8857600752409542336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:44:08.753] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:29:0) after 1m0s
[08:45:08.786] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:29:0)
[08:45:08.786] Timer tick, asking Buildbucket for the build status
[08:45:09.151] Build 8857600752409542336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:45:09.151] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:30:0) after 1m0s
[08:46:09.250] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:30:0)
[08:46:09.250] Timer tick, asking Buildbucket for the build status
[08:46:09.312] Build 8857600752409542336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:46:09.312] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:31:0) after 1m0s
[08:47:09.456] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:31:0)
[08:47:09.456] Timer tick, asking Buildbucket for the build status
[08:47:09.510] Build 8857600752409542336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:47:09.510] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:32:0) after 1m0s
[08:48:09.544] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:32:0)
[08:48:09.544] Timer tick, asking Buildbucket for the build status
[08:48:09.632] Build 8857600752409542336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:48:09.633] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:33:0) after 1m0s
[08:49:09.729] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:33:0)
[08:49:09.729] Timer tick, asking Buildbucket for the build status
[08:49:09.791] Build 8857600752409542336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:49:09.791] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:34:0) after 1m0s
[08:50:09.853] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:34:0)
[08:50:09.853] Timer tick, asking Buildbucket for the build status
[08:50:09.946] Build 8857600752409542336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:50:09.946] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:35:0) after 1m0s
[08:51:09.971] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:35:0)
[08:51:09.971] Timer tick, asking Buildbucket for the build status
[08:51:10.037] Build 8857600752409542336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:51:10.037] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:36:0) after 1m0s
[08:52:10.066] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:36:0)
[08:52:10.066] Timer tick, asking Buildbucket for the build status
[08:52:10.438] Build 8857600752409542336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:52:10.438] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:37:0) after 1m0s
[08:53:10.474] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:37:0)
[08:53:10.474] Timer tick, asking Buildbucket for the build status
[08:53:10.570] Build 8857600752409542336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:53:10.570] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:38:0) after 1m0s
[08:54:10.616] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:38:0)
[08:54:10.616] Timer tick, asking Buildbucket for the build status
[08:54:12.025] Build 8857600752409542336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:54:12.025] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:39:0) after 1m0s
[08:55:12.054] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:39:0)
[08:55:12.054] Timer tick, asking Buildbucket for the build status
[08:55:12.168] Build 8857600752409542336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:55:12.168] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:40:0) after 1m0s
[08:56:12.210] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:40:0)
[08:56:12.210] Timer tick, asking Buildbucket for the build status
[08:56:12.306] Build 8857600752409542336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:56:12.306] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:41:0) after 1m0s
[08:57:12.333] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:41:0)
[08:57:12.333] Timer tick, asking Buildbucket for the build status
[08:57:12.834] Build 8857600752409542336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:57:12.834] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:42:0) after 1m0s
[08:58:12.909] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:42:0)
[08:58:12.909] Timer tick, asking Buildbucket for the build status
[08:58:13.232] Build 8857600752409542336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:58:13.232] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:43:0) after 1m0s
[08:59:13.305] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:43:0)
[08:59:13.305] Timer tick, asking Buildbucket for the build status
[08:59:13.397] Build 8857600752409542336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:59:13.397] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:44:0) after 1m0s
[09:00:13.535] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:44:0)
[09:00:13.535] Timer tick, asking Buildbucket for the build status
[09:00:14.008] Build 8857600752409542336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:00:14.008] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:45:0) after 1m0s
[09:01:14.082] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:45:0)
[09:01:14.082] Timer tick, asking Buildbucket for the build status
[09:01:14.144] Build 8857600752409542336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:01:14.144] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:46:0) after 1m0s
[09:02:14.247] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:46:0)
[09:02:14.247] Timer tick, asking Buildbucket for the build status
[09:02:14.385] Build 8857600752409542336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:02:14.385] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:47:0) after 1m0s
[09:03:14.408] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:47:0)
[09:03:14.409] Timer tick, asking Buildbucket for the build status
[09:03:14.994] Build 8857600752409542336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:03:14.994] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:48:0) after 1m0s
[09:04:14.999] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:48:0)
[09:04:14.999] Timer tick, asking Buildbucket for the build status
[09:04:15.169] Build 8857600752409542336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:04:15.169] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:49:0) after 1m0s
[09:05:15.182] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:49:0)
[09:05:15.182] Timer tick, asking Buildbucket for the build status
[09:05:16.277] Build 8857600752409542336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:05:16.277] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:50:0) after 1m0s
[09:06:16.384] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:50:0)
[09:06:16.384] Timer tick, asking Buildbucket for the build status
[09:06:16.668] Build 8857600752409542336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:06:16.668] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:51:0) after 1m0s
[09:07:16.683] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:51:0)
[09:07:16.683] Timer tick, asking Buildbucket for the build status
[09:07:16.899] Build 8857600752409542336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:07:16.899] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:52:0) after 1m0s
[09:08:16.955] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:52:0)
[09:08:16.955] Timer tick, asking Buildbucket for the build status
[09:08:17.084] Build 8857600752409542336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:08:17.084] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:53:0) after 1m0s
[09:09:17.121] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:53:0)
[09:09:17.121] Timer tick, asking Buildbucket for the build status
[09:09:17.240] Build 8857600752409542336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:09:17.240] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:54:0) after 1m0s
[09:10:17.242] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:54:0)
[09:10:17.242] Timer tick, asking Buildbucket for the build status
[09:10:17.448] Build 8857600752409542336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:10:17.448] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:55:0) after 1m0s
[09:11:17.471] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:55:0)
[09:11:17.471] Timer tick, asking Buildbucket for the build status
[09:11:17.537] Build 8857600752409542336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:11:17.537] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:56:0) after 1m0s
[09:12:17.576] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:56:0)
[09:12:17.576] Timer tick, asking Buildbucket for the build status
[09:12:17.648] Build 8857600752409542336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:12:17.649] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:57:0) after 1m0s
[09:13:17.741] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:57:0)
[09:13:17.742] Timer tick, asking Buildbucket for the build status
[09:13:17.858] Build 8857600752409542336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:13:17.858] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:58:0) after 1m0s
[09:14:17.882] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:58:0)
[09:14:17.882] Timer tick, asking Buildbucket for the build status
[09:14:17.950] Build 8857600752409542336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:14:17.950] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:59:0) after 1m0s
[09:15:18.042] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:59:0)
[09:15:18.042] Timer tick, asking Buildbucket for the build status
[09:15:18.174] Build 8857600752409542336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:15:18.174] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:60:0) after 1m0s
[09:16:18.302] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:60:0)
[09:16:18.302] Timer tick, asking Buildbucket for the build status
[09:16:18.535] Build 8857600752409542336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:16:18.535] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:61:0) after 1m0s
[09:17:18.654] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:61:0)
[09:17:18.654] Timer tick, asking Buildbucket for the build status
[09:17:18.813] Build 8857600752409542336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:17:18.813] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:62:0) after 1m0s
[09:18:18.855] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:62:0)
[09:18:18.855] Timer tick, asking Buildbucket for the build status
[09:18:18.995] Build 8857600752409542336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:18:18.995] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:63:0) after 1m0s
[09:19:19.048] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:63:0)
[09:19:19.048] Timer tick, asking Buildbucket for the build status
[09:19:19.117] Build 8857600752409542336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:19:19.117] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:64:0) after 1m0s
[09:20:19.187] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:64:0)
[09:20:19.187] Timer tick, asking Buildbucket for the build status
[09:20:20.004] Build 8857600752409542336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:20:20.004] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:65:0) after 1m0s
[09:21:20.026] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:65:0)
[09:21:20.026] Timer tick, asking Buildbucket for the build status
[09:21:20.173] Build 8857600752409542336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:21:20.173] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:66:0) after 1m0s
[09:22:20.275] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:66:0)
[09:22:20.275] Timer tick, asking Buildbucket for the build status
[09:22:20.347] Build 8857600752409542336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:22:20.347] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:67:0) after 1m0s
[09:23:20.483] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:67:0)
[09:23:20.483] Timer tick, asking Buildbucket for the build status
[09:23:20.645] Build 8857600752409542336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:23:20.645] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:68:0) after 1m0s
[09:24:20.671] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:68:0)
[09:24:20.671] Timer tick, asking Buildbucket for the build status
[09:24:20.951] Build 8857600752409542336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:24:20.951] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:69:0) after 1m0s
[09:25:21.019] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:69:0)
[09:25:21.019] Timer tick, asking Buildbucket for the build status
[09:25:21.225] Build 8857600752409542336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:25:21.225] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:70:0) after 1m0s
[09:26:21.237] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:70:0)
[09:26:21.238] Timer tick, asking Buildbucket for the build status
[09:26:21.616] Build 8857600752409542336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:26:21.616] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:71:0) after 1m0s
[09:27:21.726] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:71:0)
[09:27:21.726] Timer tick, asking Buildbucket for the build status
[09:27:22.170] Build 8857600752409542336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:27:22.170] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:72:0) after 1m0s
[09:28:22.186] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:72:0)
[09:28:22.186] Timer tick, asking Buildbucket for the build status
[09:28:22.635] Build 8857600752409542336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:28:22.635] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:73:0) after 1m0s
[09:29:22.681] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:73:0)
[09:29:22.681] Timer tick, asking Buildbucket for the build status
[09:29:22.754] Build 8857600752409542336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:29:22.754] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:74:0) after 1m0s
[09:30:22.765] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:74:0)
[09:30:22.765] Timer tick, asking Buildbucket for the build status
[09:30:22.836] Build 8857600752409542336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:30:22.836] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:75:0) after 1m0s
[09:31:22.875] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:75:0)
[09:31:22.875] Timer tick, asking Buildbucket for the build status
[09:31:22.940] Build 8857600752409542336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:31:22.940] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:76:0) after 1m0s
[09:32:22.993] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:76:0)
[09:32:22.993] Timer tick, asking Buildbucket for the build status
[09:32:23.076] Build 8857600752409542336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:32:23.076] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:77:0) after 1m0s
[09:33:23.112] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:77:0)
[09:33:23.112] Timer tick, asking Buildbucket for the build status
[09:33:23.332] Build 8857600752409542336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:33:23.332] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:78:0) after 1m0s
[09:34:23.386] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:78:0)
[09:34:23.386] Timer tick, asking Buildbucket for the build status
[09:34:23.450] Build 8857600752409542336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:34:23.450] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:79:0) after 1m0s
[09:35:23.582] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:79:0)
[09:35:23.582] Timer tick, asking Buildbucket for the build status
[09:35:23.759] Build 8857600752409542336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:35:23.760] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:80:0) after 1m0s
[09:36:23.827] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:80:0)
[09:36:23.827] Timer tick, asking Buildbucket for the build status
[09:36:23.961] Build 8857600752409542336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:36:23.961] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:81:0) after 1m0s
[09:37:24.089] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:81:0)
[09:37:24.089] Timer tick, asking Buildbucket for the build status
[09:37:24.336] Build 8857600752409542336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:37:24.336] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:82:0) after 1m0s
[09:38:24.427] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:82:0)
[09:38:24.427] Timer tick, asking Buildbucket for the build status
[09:38:24.530] Build 8857600752409542336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:38:24.530] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:83:0) after 1m0s
[09:39:24.619] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:83:0)
[09:39:24.619] Timer tick, asking Buildbucket for the build status
[09:39:25.297] Build 8857600752409542336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:39:25.297] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:84:0) after 1m0s
[09:40:25.465] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:84:0)
[09:40:25.465] Timer tick, asking Buildbucket for the build status
[09:40:25.531] Build 8857600752409542336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:40:25.531] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:85:0) after 1m0s
[09:41:25.637] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:85:0)
[09:41:25.637] Timer tick, asking Buildbucket for the build status
[09:41:25.744] Build 8857600752409542336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:41:25.744] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:86:0) after 1m0s
[09:42:25.847] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:86:0)
[09:42:25.847] Timer tick, asking Buildbucket for the build status
[09:42:25.976] Build 8857600752409542336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:42:25.976] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:87:0) after 1m0s
[09:43:26.111] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:87:0)
[09:43:26.111] Timer tick, asking Buildbucket for the build status
[09:43:27.533] Build 8857600752409542336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:43:27.533] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:88:0) after 1m0s
[09:44:27.721] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:88:0)
[09:44:27.721] Timer tick, asking Buildbucket for the build status
[09:44:27.783] Build 8857600752409542336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:44:27.783] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:89:0) after 1m0s
[09:45:27.892] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:89:0)
[09:45:27.892] Timer tick, asking Buildbucket for the build status
[09:45:29.483] Build 8857600752409542336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:45:29.483] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:90:0) after 1m0s
[09:46:29.601] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:90:0)
[09:46:29.601] Timer tick, asking Buildbucket for the build status
[09:46:29.675] Build 8857600752409542336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:46:29.675] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:91:0) after 1m0s
[09:47:29.772] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:91:0)
[09:47:29.772] Timer tick, asking Buildbucket for the build status
[09:47:29.914] Build 8857600752409542336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:47:29.914] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:92:0) after 1m0s
[09:48:30.093] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:92:0)
[09:48:30.093] Timer tick, asking Buildbucket for the build status
[09:48:30.387] Build 8857600752409542336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:48:30.387] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:93:0) after 1m0s
[09:49:30.397] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:93:0)
[09:49:30.397] Timer tick, asking Buildbucket for the build status
[09:49:30.491] Build 8857600752409542336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:49:30.491] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:94:0) after 1m0s
[09:50:30.521] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:94:0)
[09:50:30.521] Timer tick, asking Buildbucket for the build status
[09:50:30.598] Build 8857600752409542336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:50:30.598] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:95:0) after 1m0s
[09:51:30.666] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:95:0)
[09:51:30.666] Timer tick, asking Buildbucket for the build status
[09:51:31.127] Build 8857600752409542336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:51:31.127] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:96:0) after 1m0s
[09:52:31.174] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:96:0)
[09:52:31.174] Timer tick, asking Buildbucket for the build status
[09:52:31.688] Build 8857600752409542336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:52:31.688] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:97:0) after 1m0s
[09:53:31.685] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:97:0)
[09:53:31.685] Timer tick, asking Buildbucket for the build status
[09:53:31.983] Build 8857600752409542336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:53:31.983] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:98:0) after 1m0s
[09:54:32.077] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:98:0)
[09:54:32.077] Timer tick, asking Buildbucket for the build status
[09:54:32.156] Build 8857600752409542336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:54:32.156] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:99:0) after 1m0s
[09:55:32.132] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:99:0)
[09:55:32.132] Timer tick, asking Buildbucket for the build status
[09:55:32.204] Build 8857600752409542336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:55:32.204] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023030814483592752:100:0) after 1m0s
[09:56:17.736] Received PubSub notification, asking Buildbucket for the build status
[09:56:18.047] Build 8857600752409542336: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[09:56:18.047] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1611136576908130",
"created_by": "project:chromium",
"created_ts": "1611130679654468",
"failure_reason": "BUILD_FAILURE",
"id": "8857600752409542336",
"parameters_json": "{\"builder_name\": \"linux-chromeos-dbg\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"fbc26cbe546f8f1d6c76064b739d24d5fd37dfb2\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"1 Test Suite(s) failed.\\n\\n**browser_tests** failed because of:\\n\\n- WorkerTaskProviderBrowserTest.DestroyedProfile\"}, \"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\": \"fbc26cbe546f8f1d6c76064b739d24d5fd37dfb2\", \"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-86-wu7l\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-dbg\", \"buildnumber\": 22268, \"got_angle_revision\": \"192a0147c3fe465650b3a3ef45ea61c02b2de5a1\", \"got_dawn_revision\": \"4b316994ad01ab6b87032c5fd9b53c6d18956d8d\", \"got_nacl_revision\": \"cb31feffa500401c95c6c44d88b7c358236bca36\", \"got_revision\": \"fbc26cbe546f8f1d6c76064b739d24d5fd37dfb2\", \"got_revision_cp\": \"refs/heads/master@{#845099}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"89fd1480b47e8c8668dfd3c24302c53b76ba4c88\", \"got_v8_revision\": \"69c461e9bd40c5c45d553d3d19bb2771e32b4d64\", \"got_v8_revision_cp\": \"refs/heads/9.0.18@{#1}\", \"got_webrtc_revision\": \"5eb527cf7fb28e22d0687c0295cb9f1bb458160c\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33034}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mirrored_builders\": [\"tryserver.chromium.chromiumos:linux-chromeos-dbg\", \"tryserver.chromium.chromiumos:linux-chromeos-compile-dbg\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"fbc26cbe546f8f1d6c76064b739d24d5fd37dfb2\", \"swarm_hashes_refs/heads/master(at){#845099}_without_patch\": {\"absl_hardening_tests\": \"b38c5a1e6ea9522c7cda920227002932e0292334\", \"accessibility_unittests\": \"93b647066d82a284a15229ce5266b7b276e80a41\", \"angle_unittests\": \"85187433a8cd943258009187524890e167643415\", \"app_list_unittests\": \"5c4b79df6bb7b0ed8f5c1c4bc739005cd97acacb\", \"app_shell_unittests\": \"ef3348fd98226b30cf9be44803e494209ae7393d\", \"ash_unittests\": \"d90da3ed404de1484a6c5ab368a6df3d4befe133\", \"aura_unittests\": \"e807802f9a2c987e4e8d936b159a63565ad015c7\", \"base_unittests\": \"f4f2ec187087c36c68beddf1be7db900e63d6dea\", \"base_util_unittests\": \"9e1e8f0af3803c8fe3742264bb0bafff961e853b\", \"blink_common_unittests\": \"328de6f18e988b42a59a7f7f65b95578a9bd1d4a\", \"blink_fuzzer_unittests\": \"a3bcddee3dd2a72bef0399d79814ba247209d61e\", \"blink_heap_unittests\": \"6775a6c139ec6e208764bd2b7b0415c26282dc03\", \"blink_platform_unittests\": \"87dcd515e5c8ba014ff5aa4fac60fbb81b173e1b\", \"blink_unittests\": \"90a8655d0cfd8376aeae48feda0c184dd5bda429\", \"boringssl_crypto_tests\": \"4ce4ec94a0329ef222141e95c1b45b67c6fce3c4\", \"boringssl_ssl_tests\": \"251d936b8ae1f2c3578c24a68ca4f14b2264703c\", \"browser_tests\": \"b3381721c9fd3a1801f2d29180b24cae4699d825\", \"capture_unittests\": \"7edc45ba1b73493df4b0dc42a57c4e19266dc487\", \"cast_unittests\": \"c15ae9a34edcafa0e205fb7a083ecd654ace58f8\", \"cc_unittests\": \"f7d205736cb54fbf92b37069acb2f4e7795cd36d\", \"chrome_app_unittests\": \"c85b410c2c319c8ee4f2f7352d614bcc16aba7c1\", \"chromedriver_unittests\": \"c542642ea7b145299b0403622597b53911af5db5\", \"chromeos_components_unittests\": \"19c73380590a3bd972d913ca72e29f0b92da598a\", \"chromeos_unittests\": \"af38587e857c940131853f808ae97c0a35687d43\", \"components_browsertests\": \"c143dc7ac3d8987fa5dea60624a96906244d9349\", \"components_unittests\": \"bf683ab644a07b22f80b3a7524cc7cf4061f1956\", \"compositor_unittests\": \"009f7ecc5eca285e1262a7d1110e5441cb20198a\", \"content_browsertests\": \"63e2ce020ab790c2c8203a49762423ffd17372ee\", \"content_unittests\": \"0de96713e90ff90d9b95a4c8efe94b770c9d82b3\", \"crashpad_tests\": \"64589c46f47671586a98d511d77d776dfa4bfcd0\", \"crypto_unittests\": \"624a342762e311fa3eacfbfb6aff98a448b9b1ad\", \"dbus_unittests\": \"8fbb413bbf52b9e51cb46e3939d4bb9854b73e04\", \"device_unittests\": \"f49c090643d16d8c25e52869db36524d14674f89\", \"display_unittests\": \"b1a9eab755bd626f4228b28607d3d8a5e11a8949\", \"events_unittests\": \"0c24446ca9b3506c19bea1910563691bf7621c48\", \"exo_unittests\": \"7aad6a4b6c2d4c0a5759b25f20656551e6f46b0f\", \"extensions_browsertests\": \"b6ece076027e4dcf360eef1a395c0fcf59689545\", \"extensions_unittests\": \"d1f496f7e7ceb2f4c956e9916f6f4d761e1ea81f\", \"filesystem_service_unittests\": \"92cf28619d73b992701ecdc6b1dd9a613ef0fd75\", \"gcm_unit_tests\": \"deb47716630c1d0d91b308d4fed5ced0850f1eff\", \"gfx_unittests\": \"93c4be4abe0318160ad4276e11883c7c5789d85f\", \"gin_unittests\": \"e427f50f1225765963d7e163f983347c54a08c32\", \"gl_unittests_ozone\": \"95918a41e7eadd1470909bae28553942db4e62c6\", \"google_apis_unittests\": \"20cc269df496f41f7c2f7832fe64c38126ac4c0b\", \"gpu_unittests\": \"f572469832d966f93c763ccba757f6ee48927f12\", \"gwp_asan_unittests\": \"97dbafe68e1b1d3d785348389f2bc504a27d51b0\", \"interactive_ui_tests\": \"0299739c5c58413152ae939e2242fa59cb9450b2\", \"ipc_tests\": \"b2d2e75949cbbd92298b186967ae2188dff7d1f0\", \"jingle_unittests\": \"d73914674c8bcd8a4800c021e8cca1a63973331e\", \"keyboard_unittests\": \"b888648624fc0eb3b18c948398da4f46174eb150\", \"latency_unittests\": \"cf8c9ab30115b0efc7a53a9bec60449a33e3959c\", \"libjingle_xmpp_unittests\": \"19a84a7c5c608796a15071a3e3132f59772f1933\", \"liburlpattern_unittests\": \"0abd1dd5e19517ecf5d8a5193ee6db26058e545e\", \"media_blink_unittests\": \"8bb98197148bc83c9146b99767335f303ba06f30\", \"media_unittests\": \"8f2ba9bdb4bd162a94772f13ce9edef19d58f553\", \"message_center_unittests\": \"d402aec32739e3bfe5c46b7520fdc93e460ad689\", \"midi_unittests\": \"02eea339c6774b0e156d8eff4fb31b9b5f23c7c1\", \"mojo_core_unittests\": \"1d740dd6898fa392239a21e788b2abb826f7b865\", \"mojo_unittests\": \"bd3943d2a6f0af864f39f6fa9b9d0a2e561852ba\", \"nacl_helper_nonsfi_unittests\": \"a3ac96868ec35978743aa34e3966ba67b97fdc4c\", \"nacl_loader_unittests\": \"b56ecadf313f5730ce0e526afbeefa0068d235f3\", \"native_theme_unittests\": \"f98ab3d5f99c962dad0e2f9ef2d91fec3613825d\", \"net_unittests\": \"861d23c513443a8a1ccb5e33b0a46baa12e882e9\", \"ozone_gl_unittests\": \"fb4c7f05b7a72fe9cb789214768bbf528bcfa26c\", \"ozone_unittests\": \"b6dae1e3608d9f26c95f00e78bf95be6217ff5f3\", \"ozone_x11_unittests\": \"0aac1d024a76a43dd8356e254cbf6c52f05bde85\", \"pdf_unittests\": \"449fc731d46f93661eed340b0ed846445019b0e3\", \"perfetto_unittests\": \"e334d68cd0562a870ef2317056c1a0acfe91464b\", \"ppapi_unittests\": \"57d4424cd913ae1aff957474d8f356a36bdb7c55\", \"printing_unittests\": \"6b813fbff4e13f92b2cfab4340ca292c693d2d93\", \"remoting_unittests\": \"f10f4d00c80a36cf5c0098c8f49979fbb79aaee5\", \"sandbox_linux_unittests\": \"47e044d202f1c731f51b132a2a2b295eca58bcdd\", \"service_manager_unittests\": \"aa3f94134c0dfda03952136a7236500550ed81b0\", \"services_unittests\": \"08e9abafb44df606e4fc8e78665b57b07c9327ad\", \"shell_dialogs_unittests\": \"e75a70190b7d2cfeb7311962114edceb13ae83f5\", \"shell_encryption_unittests\": \"a66c0f654c172f2a376fcafd61c541a0f28c7d9b\", \"skia_unittests\": \"4b0caa4d610ce3e3746e49baae7684f2c6dd91c7\", \"snapshot_unittests\": \"5d5ae55aa7e5e32cbc591bbdae11ee0a678ce434\", \"sql_unittests\": \"0ef9a2402a685091a8b3f09203468cccf009b899\", \"storage_unittests\": \"a274d240957dcadef8b1258e9fa7a2e935f01d48\", \"sync_integration_tests\": \"da901337c754f1d49cb4a65f450c8ef7b4795baa\", \"traffic_annotation_auditor_unittests\": \"0a03978a61ffed450c2e791ecb2b97efe7577763\", \"ui_base_unittests\": \"a614a82e3d1fb55601c29961f46efd9fd8134266\", \"ui_chromeos_unittests\": \"4ff0c89cd992abc566267424ce50289bc58c4f44\", \"ui_touch_selection_unittests\": \"9334b9f003223ea91cec0689fb9b31940e0d4bbb\", \"unit_tests\": \"a13044df69b3c4429823076ceff1e2cb8c09951f\", \"url_unittests\": \"e3b7883150e247a03e44bd8f171994a67a0bb433\", \"usage_time_limit_unittests\": \"03abdb184cae268d3a1ee05da3bccf29922b8bed\", \"views_unittests\": \"9982400797871bd3bcc63a977a7c452654d64ce7\", \"viz_unittests\": \"598ae238a135890940748a08b2b2c03d09d91171\", \"wm_unittests\": \"c3b9ba74dac054d2df700e3958d6841273d6581c\", \"wtf_unittests\": \"ab95bf47ff3d59951ea2af6ad85580ab5273c176\", \"zlib_unittests\": \"b1ae641b9554bd30959a0816e416dbb6e7951427\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_4973d939604d11dcbaec68575e69c80c756ff63e355ab013b29b8bca26434b7c_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-86-wu7l\"], \"image\": [\"chrome-xenial-21011100-9488e34e558\"], \"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\": [\"5666-679c9bd\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}, \"ui\": {\"info\": \"1 Test Suite(s) failed.\\n\\n**browser_tests** failed because of:\\n\\n- WorkerTaskProviderBrowserTest.DestroyedProfile\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1611130888836809",
"status": "COMPLETED",
"status_changed_ts": "1611130889201443",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-dbg/22268",
"builder:linux-chromeos-dbg",
"buildset:commit/git/fbc26cbe546f8f1d6c76064b739d24d5fd37dfb2",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/fbc26cbe546f8f1d6c76064b739d24d5fd37dfb2",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023030814483592752",
"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/8857600752409542336/+/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:5137b0bd086da410",
"user_agent:luci-scheduler"
],
"updated_ts": "1611136577121546",
"url": "https://ci.chromium.org/b/8857600752409542336",
"utcnow_ts": "1611136578031836"
}
}
[09:56:18.047] Invocation finished in 1h38m19.755025218s with status FAILED