[08:14:10.672] New invocation is queued and will start shortly
[08:14:11.798] Starting the invocation (attempt 1)
[08:14:11.832] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[08:14:11.835] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[08:14:11.835] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9023665231940981584",
"parameters_json": "{\"builder_name\":\"linux-chromeos-dbg\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"8f00fbee5437131e0e60aa3513482944cfbaf173\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-dbg",
"scheduler_invocation_id:9023665231940981584",
"scheduler_job_id:chromium/linux-chromeos-dbg",
"user_agent:luci-scheduler",
"buildset:commit/git/8f00fbee5437131e0e60aa3513482944cfbaf173",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/8f00fbee5437131e0e60aa3513482944cfbaf173",
"gitiles_ref:refs/heads/master"
]
}
[08:14:13.945] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1610525651972051",
"id": "8858235169916056736",
"parameters_json": "{\"builder_name\": \"linux-chromeos-dbg\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"8f00fbee5437131e0e60aa3513482944cfbaf173\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1610525653335462",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-dbg/22179",
"builder:linux-chromeos-dbg",
"buildset:commit/git/8f00fbee5437131e0e60aa3513482944cfbaf173",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/8f00fbee5437131e0e60aa3513482944cfbaf173",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023665231940981584",
"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/8858235169916056736/+/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": "1610525653335584",
"url": "https://ci.chromium.org/b/8858235169916056736",
"utcnow_ts": "1610525653915510"
}
}
[08:14:13.945] Task URL: https://ci.chromium.org/b/8858235169916056736
[08:14:13.945] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:2:0) after 1m0s
[08:14:32.213] Received PubSub notification, asking Buildbucket for the build status
[08:14:32.284] Build 8858235169916056736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:15:14.042] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:2:0)
[08:15:14.042] Timer tick, asking Buildbucket for the build status
[08:15:14.248] Build 8858235169916056736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:15:14.248] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:4:0) after 1m0s
[08:16:14.259] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:4:0)
[08:16:14.259] Timer tick, asking Buildbucket for the build status
[08:16:14.731] Build 8858235169916056736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:16:14.731] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:5:0) after 1m0s
[08:17:14.746] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:5:0)
[08:17:14.746] Timer tick, asking Buildbucket for the build status
[08:17:14.845] Build 8858235169916056736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:17:14.845] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:6:0) after 1m0s
[08:18:14.880] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:6:0)
[08:18:14.880] Timer tick, asking Buildbucket for the build status
[08:18:14.990] Build 8858235169916056736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:18:14.990] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:7:0) after 1m0s
[08:19:14.986] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:7:0)
[08:19:14.986] Timer tick, asking Buildbucket for the build status
[08:19:15.116] Build 8858235169916056736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:19:15.116] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:8:0) after 1m0s
[08:20:15.215] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:8:0)
[08:20:15.215] Timer tick, asking Buildbucket for the build status
[08:20:15.577] Build 8858235169916056736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:20:15.577] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:9:0) after 1m0s
[08:21:15.761] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:9:0)
[08:21:15.761] Timer tick, asking Buildbucket for the build status
[08:21:15.914] Build 8858235169916056736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:21:15.914] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:10:0) after 1m0s
[08:22:16.008] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:10:0)
[08:22:16.008] Timer tick, asking Buildbucket for the build status
[08:22:16.068] Build 8858235169916056736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:22:16.068] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:11:0) after 1m0s
[08:23:16.164] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:11:0)
[08:23:16.164] Timer tick, asking Buildbucket for the build status
[08:23:16.269] Build 8858235169916056736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:23:16.269] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:12:0) after 1m0s
[08:24:16.322] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:12:0)
[08:24:16.322] Timer tick, asking Buildbucket for the build status
[08:24:16.433] Build 8858235169916056736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:24:16.433] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:13:0) after 1m0s
[08:25:16.527] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:13:0)
[08:25:16.527] Timer tick, asking Buildbucket for the build status
[08:25:16.590] Build 8858235169916056736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:25:16.590] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:14:0) after 1m0s
[08:26:16.646] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:14:0)
[08:26:16.646] Timer tick, asking Buildbucket for the build status
[08:26:16.709] Build 8858235169916056736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:26:16.709] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:15:0) after 1m0s
[08:27:16.951] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:15:0)
[08:27:16.951] Timer tick, asking Buildbucket for the build status
[08:27:17.747] Build 8858235169916056736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:27:17.747] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:16:0) after 1m0s
[08:28:17.877] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:16:0)
[08:28:17.877] Timer tick, asking Buildbucket for the build status
[08:28:17.954] Build 8858235169916056736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:28:17.954] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:17:0) after 1m0s
[08:29:18.069] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:17:0)
[08:29:18.069] Timer tick, asking Buildbucket for the build status
[08:29:18.382] Build 8858235169916056736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:29:18.382] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:18:0) after 1m0s
[08:30:18.499] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:18:0)
[08:30:18.499] Timer tick, asking Buildbucket for the build status
[08:30:18.664] Build 8858235169916056736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:30:18.664] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:19:0) after 1m0s
[08:31:18.685] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:19:0)
[08:31:18.685] Timer tick, asking Buildbucket for the build status
[08:31:18.759] Build 8858235169916056736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:31:18.759] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:20:0) after 1m0s
[08:32:18.787] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:20:0)
[08:32:18.787] Timer tick, asking Buildbucket for the build status
[08:32:18.860] Build 8858235169916056736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:32:18.860] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:21:0) after 1m0s
[08:33:18.865] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:21:0)
[08:33:18.865] Timer tick, asking Buildbucket for the build status
[08:33:18.924] Build 8858235169916056736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:33:18.924] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:22:0) after 1m0s
[08:34:18.939] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:22:0)
[08:34:18.939] Timer tick, asking Buildbucket for the build status
[08:34:19.086] Build 8858235169916056736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:34:19.086] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:23:0) after 1m0s
[08:35:19.119] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:23:0)
[08:35:19.119] Timer tick, asking Buildbucket for the build status
[08:35:19.181] Build 8858235169916056736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:35:19.181] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:24:0) after 1m0s
[08:36:19.200] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:24:0)
[08:36:19.200] Timer tick, asking Buildbucket for the build status
[08:36:19.283] Build 8858235169916056736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:36:19.283] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:25:0) after 1m0s
[08:37:19.298] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:25:0)
[08:37:19.298] Timer tick, asking Buildbucket for the build status
[08:37:19.402] Build 8858235169916056736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:37:19.402] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:26:0) after 1m0s
[08:38:19.481] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:26:0)
[08:38:19.481] Timer tick, asking Buildbucket for the build status
[08:38:19.563] Build 8858235169916056736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:38:19.563] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:27:0) after 1m0s
[08:39:19.603] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:27:0)
[08:39:19.603] Timer tick, asking Buildbucket for the build status
[08:39:19.756] Build 8858235169916056736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:39:19.756] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:28:0) after 1m0s
[08:40:19.836] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:28:0)
[08:40:19.836] Timer tick, asking Buildbucket for the build status
[08:40:19.896] Build 8858235169916056736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:40:19.896] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:29:0) after 1m0s
[08:41:19.973] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:29:0)
[08:41:19.973] Timer tick, asking Buildbucket for the build status
[08:41:20.051] Build 8858235169916056736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:41:20.051] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:30:0) after 1m0s
[08:42:20.124] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:30:0)
[08:42:20.124] Timer tick, asking Buildbucket for the build status
[08:42:20.189] Build 8858235169916056736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:42:20.189] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:31:0) after 1m0s
[08:43:20.223] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:31:0)
[08:43:20.223] Timer tick, asking Buildbucket for the build status
[08:43:20.302] Build 8858235169916056736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:43:20.302] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:32:0) after 1m0s
[08:44:20.341] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:32:0)
[08:44:20.341] Timer tick, asking Buildbucket for the build status
[08:44:20.535] Build 8858235169916056736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:44:20.535] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:33:0) after 1m0s
[08:45:20.633] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:33:0)
[08:45:20.633] Timer tick, asking Buildbucket for the build status
[08:45:20.757] Build 8858235169916056736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:45:20.757] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:34:0) after 1m0s
[08:46:20.825] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:34:0)
[08:46:20.825] Timer tick, asking Buildbucket for the build status
[08:46:20.886] Build 8858235169916056736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:46:20.886] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:35:0) after 1m0s
[08:47:21.016] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:35:0)
[08:47:21.016] Timer tick, asking Buildbucket for the build status
[08:47:21.083] Build 8858235169916056736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:47:21.083] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:36:0) after 1m0s
[08:48:21.440] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:36:0)
[08:48:21.440] Timer tick, asking Buildbucket for the build status
[08:48:21.524] Build 8858235169916056736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:48:21.524] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:37:0) after 1m0s
[08:49:21.578] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:37:0)
[08:49:21.578] Timer tick, asking Buildbucket for the build status
[08:49:21.700] Build 8858235169916056736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:49:21.700] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:38:0) after 1m0s
[08:50:21.756] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:38:0)
[08:50:21.756] Timer tick, asking Buildbucket for the build status
[08:50:22.316] Build 8858235169916056736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:50:22.316] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:39:0) after 1m0s
[08:51:22.436] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:39:0)
[08:51:22.436] Timer tick, asking Buildbucket for the build status
[08:51:22.670] Build 8858235169916056736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:51:22.670] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:40:0) after 1m0s
[08:52:22.707] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:40:0)
[08:52:22.707] Timer tick, asking Buildbucket for the build status
[08:52:23.140] Build 8858235169916056736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:52:23.140] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:41:0) after 1m0s
[08:53:23.209] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:41:0)
[08:53:23.209] Timer tick, asking Buildbucket for the build status
[08:53:23.267] Build 8858235169916056736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:53:23.267] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:42:0) after 1m0s
[08:54:23.301] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:42:0)
[08:54:23.301] Timer tick, asking Buildbucket for the build status
[08:54:23.613] Build 8858235169916056736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:54:23.613] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:43:0) after 1m0s
[08:55:23.701] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:43:0)
[08:55:23.701] Timer tick, asking Buildbucket for the build status
[08:55:23.806] Build 8858235169916056736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:55:23.806] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:44:0) after 1m0s
[08:56:23.861] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:44:0)
[08:56:23.861] Timer tick, asking Buildbucket for the build status
[08:56:23.918] Build 8858235169916056736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:56:23.918] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:45:0) after 1m0s
[08:57:24.006] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:45:0)
[08:57:24.006] Timer tick, asking Buildbucket for the build status
[08:57:24.106] Build 8858235169916056736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:57:24.106] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:46:0) after 1m0s
[08:58:24.232] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:46:0)
[08:58:24.232] Timer tick, asking Buildbucket for the build status
[08:58:24.302] Build 8858235169916056736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:58:24.302] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:47:0) after 1m0s
[08:59:24.322] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:47:0)
[08:59:24.322] Timer tick, asking Buildbucket for the build status
[08:59:24.473] Build 8858235169916056736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:59:24.473] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:48:0) after 1m0s
[09:00:24.539] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:48:0)
[09:00:24.539] Timer tick, asking Buildbucket for the build status
[09:00:24.671] Build 8858235169916056736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:00:24.671] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:49:0) after 1m0s
[09:01:24.661] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:49:0)
[09:01:24.661] Timer tick, asking Buildbucket for the build status
[09:01:24.890] Build 8858235169916056736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:01:24.890] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:50:0) after 1m0s
[09:02:24.922] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:50:0)
[09:02:24.922] Timer tick, asking Buildbucket for the build status
[09:02:25.053] Build 8858235169916056736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:02:25.053] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:51:0) after 1m0s
[09:03:25.089] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:51:0)
[09:03:25.089] Timer tick, asking Buildbucket for the build status
[09:03:25.184] Build 8858235169916056736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:03:25.184] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:52:0) after 1m0s
[09:04:25.208] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:52:0)
[09:04:25.208] Timer tick, asking Buildbucket for the build status
[09:04:25.322] Build 8858235169916056736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:04:25.323] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:53:0) after 1m0s
[09:05:25.346] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:53:0)
[09:05:25.346] Timer tick, asking Buildbucket for the build status
[09:05:25.480] Build 8858235169916056736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:05:25.481] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:54:0) after 1m0s
[09:06:25.506] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:54:0)
[09:06:25.506] Timer tick, asking Buildbucket for the build status
[09:06:25.595] Build 8858235169916056736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:06:25.595] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:55:0) after 1m0s
[09:07:25.618] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:55:0)
[09:07:25.618] Timer tick, asking Buildbucket for the build status
[09:07:25.748] Build 8858235169916056736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:07:25.748] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:56:0) after 1m0s
[09:08:25.747] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:56:0)
[09:08:25.747] Timer tick, asking Buildbucket for the build status
[09:08:25.847] Build 8858235169916056736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:08:25.847] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:57:0) after 1m0s
[09:09:25.871] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:57:0)
[09:09:25.871] Timer tick, asking Buildbucket for the build status
[09:09:26.085] Build 8858235169916056736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:09:26.085] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:58:0) after 1m0s
[09:10:26.089] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:58:0)
[09:10:26.089] Timer tick, asking Buildbucket for the build status
[09:10:26.158] Build 8858235169916056736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:10:26.158] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:59:0) after 1m0s
[09:11:26.147] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:59:0)
[09:11:26.147] Timer tick, asking Buildbucket for the build status
[09:11:26.304] Build 8858235169916056736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:11:26.304] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:60:0) after 1m0s
[09:12:26.316] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:60:0)
[09:12:26.316] Timer tick, asking Buildbucket for the build status
[09:12:26.379] Build 8858235169916056736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:12:26.379] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:61:0) after 1m0s
[09:13:26.409] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:61:0)
[09:13:26.409] Timer tick, asking Buildbucket for the build status
[09:13:26.589] Build 8858235169916056736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:13:26.589] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:62:0) after 1m0s
[09:14:26.617] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:62:0)
[09:14:26.617] Timer tick, asking Buildbucket for the build status
[09:14:26.728] Build 8858235169916056736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:14:26.728] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:63:0) after 1m0s
[09:15:26.743] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:63:0)
[09:15:26.743] Timer tick, asking Buildbucket for the build status
[09:15:27.003] Build 8858235169916056736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:15:27.004] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:64:0) after 1m0s
[09:16:27.046] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:64:0)
[09:16:27.046] Timer tick, asking Buildbucket for the build status
[09:16:27.154] Build 8858235169916056736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:16:27.154] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:65:0) after 1m0s
[09:17:27.195] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:65:0)
[09:17:27.195] Timer tick, asking Buildbucket for the build status
[09:17:27.885] Build 8858235169916056736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:17:27.885] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:66:0) after 1m0s
[09:18:28.005] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:66:0)
[09:18:28.005] Timer tick, asking Buildbucket for the build status
[09:18:28.144] Build 8858235169916056736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:18:28.144] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:67:0) after 1m0s
[09:19:28.288] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:67:0)
[09:19:28.288] Timer tick, asking Buildbucket for the build status
[09:19:28.457] Build 8858235169916056736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:19:28.457] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:68:0) after 1m0s
[09:20:28.483] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:68:0)
[09:20:28.483] Timer tick, asking Buildbucket for the build status
[09:20:28.538] Build 8858235169916056736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:20:28.538] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:69:0) after 1m0s
[09:21:28.662] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:69:0)
[09:21:28.662] Timer tick, asking Buildbucket for the build status
[09:21:28.756] Build 8858235169916056736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:21:28.756] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:70:0) after 1m0s
[09:22:28.898] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:70:0)
[09:22:28.898] Timer tick, asking Buildbucket for the build status
[09:22:29.187] Build 8858235169916056736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:22:29.187] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:71:0) after 1m0s
[09:23:29.416] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:71:0)
[09:23:29.416] Timer tick, asking Buildbucket for the build status
[09:23:30.985] Build 8858235169916056736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:23:30.985] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:72:0) after 1m0s
[09:24:31.091] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:72:0)
[09:24:31.091] Timer tick, asking Buildbucket for the build status
[09:24:31.196] Build 8858235169916056736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:24:31.196] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:73:0) after 1m0s
[09:25:31.260] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:73:0)
[09:25:31.260] Timer tick, asking Buildbucket for the build status
[09:25:31.332] Build 8858235169916056736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:25:31.332] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:74:0) after 1m0s
[09:26:31.480] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:74:0)
[09:26:31.480] Timer tick, asking Buildbucket for the build status
[09:26:31.560] Build 8858235169916056736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:26:31.560] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:75:0) after 1m0s
[09:27:31.586] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:75:0)
[09:27:31.586] Timer tick, asking Buildbucket for the build status
[09:27:31.737] Build 8858235169916056736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:27:31.737] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:76:0) after 1m0s
[09:28:31.842] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:76:0)
[09:28:31.842] Timer tick, asking Buildbucket for the build status
[09:28:31.946] Build 8858235169916056736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:28:31.946] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:77:0) after 1m0s
[09:29:32.036] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:77:0)
[09:29:32.036] Timer tick, asking Buildbucket for the build status
[09:29:32.175] Build 8858235169916056736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:29:32.175] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:78:0) after 1m0s
[09:30:32.263] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:78:0)
[09:30:32.263] Timer tick, asking Buildbucket for the build status
[09:30:32.391] Build 8858235169916056736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:30:32.391] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:79:0) after 1m0s
[09:31:32.522] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:79:0)
[09:31:32.522] Timer tick, asking Buildbucket for the build status
[09:31:32.657] Build 8858235169916056736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:31:32.657] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:80:0) after 1m0s
[09:32:32.680] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:80:0)
[09:32:32.681] Timer tick, asking Buildbucket for the build status
[09:32:32.776] Build 8858235169916056736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:32:32.776] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:81:0) after 1m0s
[09:33:32.847] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:81:0)
[09:33:32.847] Timer tick, asking Buildbucket for the build status
[09:33:32.905] Build 8858235169916056736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:33:32.905] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:82:0) after 1m0s
[09:34:32.954] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:82:0)
[09:34:32.954] Timer tick, asking Buildbucket for the build status
[09:34:33.061] Build 8858235169916056736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:34:33.061] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:83:0) after 1m0s
[09:35:33.150] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:83:0)
[09:35:33.150] Timer tick, asking Buildbucket for the build status
[09:35:33.412] Build 8858235169916056736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:35:33.412] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:84:0) after 1m0s
[09:36:33.392] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:84:0)
[09:36:33.392] Timer tick, asking Buildbucket for the build status
[09:36:33.463] Build 8858235169916056736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:36:33.463] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:85:0) after 1m0s
[09:37:33.504] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:85:0)
[09:37:33.504] Timer tick, asking Buildbucket for the build status
[09:37:33.579] Build 8858235169916056736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:37:33.579] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:86:0) after 1m0s
[09:38:33.606] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:86:0)
[09:38:33.606] Timer tick, asking Buildbucket for the build status
[09:38:33.852] Build 8858235169916056736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:38:33.852] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:87:0) after 1m0s
[09:39:34.015] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:87:0)
[09:39:34.015] Timer tick, asking Buildbucket for the build status
[09:39:34.166] Build 8858235169916056736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:39:34.166] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:88:0) after 1m0s
[09:40:34.281] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:88:0)
[09:40:34.281] Timer tick, asking Buildbucket for the build status
[09:40:34.346] Build 8858235169916056736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:40:34.346] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:89:0) after 1m0s
[09:41:34.438] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:89:0)
[09:41:34.438] Timer tick, asking Buildbucket for the build status
[09:41:34.523] Build 8858235169916056736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:41:34.523] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:90:0) after 1m0s
[09:42:34.865] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:90:0)
[09:42:34.865] Timer tick, asking Buildbucket for the build status
[09:42:34.987] Build 8858235169916056736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:42:34.987] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:91:0) after 1m0s
[09:43:35.168] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:91:0)
[09:43:35.168] Timer tick, asking Buildbucket for the build status
[09:43:35.404] Build 8858235169916056736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:43:35.404] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:92:0) after 1m0s
[09:44:35.430] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:92:0)
[09:44:35.431] Timer tick, asking Buildbucket for the build status
[09:44:35.564] Build 8858235169916056736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:44:35.564] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:93:0) after 1m0s
[09:45:35.619] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:93:0)
[09:45:35.619] Timer tick, asking Buildbucket for the build status
[09:45:35.744] Build 8858235169916056736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:45:35.744] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:94:0) after 1m0s
[09:46:35.719] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:94:0)
[09:46:35.719] Timer tick, asking Buildbucket for the build status
[09:46:35.829] Build 8858235169916056736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:46:35.829] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:95:0) after 1m0s
[09:47:35.889] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:95:0)
[09:47:35.889] Timer tick, asking Buildbucket for the build status
[09:47:36.128] Build 8858235169916056736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:47:36.128] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:96:0) after 1m0s
[09:48:36.145] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:96:0)
[09:48:36.145] Timer tick, asking Buildbucket for the build status
[09:48:36.208] Build 8858235169916056736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:48:36.208] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:97:0) after 1m0s
[09:49:36.270] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:97:0)
[09:49:36.270] Timer tick, asking Buildbucket for the build status
[09:49:36.611] Build 8858235169916056736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:49:36.611] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:98:0) after 1m0s
[09:50:36.725] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:98:0)
[09:50:36.725] Timer tick, asking Buildbucket for the build status
[09:50:36.822] Build 8858235169916056736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:50:36.822] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:99:0) after 1m0s
[09:51:36.871] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:99:0)
[09:51:36.871] Timer tick, asking Buildbucket for the build status
[09:51:37.435] Build 8858235169916056736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:51:37.435] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:100:0) after 1m0s
[09:52:37.499] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:100:0)
[09:52:37.499] Timer tick, asking Buildbucket for the build status
[09:52:37.630] Build 8858235169916056736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:52:37.630] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:101:0) after 1m0s
[09:53:37.665] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:101:0)
[09:53:37.665] Timer tick, asking Buildbucket for the build status
[09:53:37.786] Build 8858235169916056736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:53:37.786] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:102:0) after 1m0s
[09:54:37.845] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:102:0)
[09:54:37.845] Timer tick, asking Buildbucket for the build status
[09:54:37.995] Build 8858235169916056736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:54:37.995] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:103:0) after 1m0s
[09:55:38.013] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:103:0)
[09:55:38.013] Timer tick, asking Buildbucket for the build status
[09:55:38.159] Build 8858235169916056736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:55:38.159] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9023665231940981584:104:0) after 1m0s
[09:55:42.936] Received PubSub notification, asking Buildbucket for the build status
[09:55:43.024] Build 8858235169916056736: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[09:55:43.024] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1610531741423456",
"created_by": "project:chromium",
"created_ts": "1610525651972051",
"failure_reason": "BUILD_FAILURE",
"id": "8858235169916056736",
"parameters_json": "{\"builder_name\": \"linux-chromeos-dbg\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"8f00fbee5437131e0e60aa3513482944cfbaf173\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"1 Test Suite(s) failed.\\n\\n**browser_tests** failed because of:\\n\\n- WindowOpenApiTest.PopupBlockingExtension\"}, \"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\": \"8f00fbee5437131e0e60aa3513482944cfbaf173\", \"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-72-w0e6\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-dbg\", \"buildnumber\": 22179, \"got_angle_revision\": \"2ed9671a0dddaab9c2582b62d3e479895ee0d333\", \"got_dawn_revision\": \"7faa362ea9c2bffbb8e9cc2e2d9f4f4af2fe4dde\", \"got_nacl_revision\": \"d28e6c52e05ea2f1b3231de473b6dbfce59bce40\", \"got_revision\": \"8f00fbee5437131e0e60aa3513482944cfbaf173\", \"got_revision_cp\": \"refs/heads/master@{#842925}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"3549479dc4ccd92ef3b9e179080dcec1a430b01d\", \"got_v8_revision\": \"1e60bce4522aa7c92b1010988f8815f96c8243b7\", \"got_v8_revision_cp\": \"refs/heads/8.9.257@{#1}\", \"got_webrtc_revision\": \"1c5e63e5451374783aaf0259f5c23d3688a5b2ff\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32938}\", \"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\": \"8f00fbee5437131e0e60aa3513482944cfbaf173\", \"swarm_hashes_refs/heads/master(at){#842925}_without_patch\": {\"absl_hardening_tests\": \"ed3b7a7f6950283a16794127b449d6ca54efbfcb\", \"accessibility_unittests\": \"2a3dd0d5d71be17eef4a5080f83c3f04618fe08e\", \"angle_unittests\": \"1d6ab93402317a59d78c81c262a6efbb4d8d7960\", \"app_list_unittests\": \"e5a2dcf413d541537174855726c5199bb261fa4f\", \"app_shell_unittests\": \"625a66e9cb8b0e6e4eb74afb387d121366c0d9c1\", \"ash_unittests\": \"6f319afb2b6503de7ec48fb391462a43d9fdd18d\", \"aura_unittests\": \"7b136c0d35fc251af04735edf2c6a166b9ef4f48\", \"base_unittests\": \"0446a256d3de9a06b78111a6c83b5803c09f8130\", \"base_util_unittests\": \"1a7f9356734c7857a7edf68596f1f618e68d6cc9\", \"blink_common_unittests\": \"fd5e353adb5afff736fbbb342a3577267df0ea1a\", \"blink_fuzzer_unittests\": \"bc7d9b4803cac60b985fb8e04e4781d81eaf23c2\", \"blink_heap_unittests\": \"c2db6f302318c8295acea73e5966c3297c410d03\", \"blink_platform_unittests\": \"a38ca8bb638ff82d75e4e0e88768548114fe4b78\", \"blink_unittests\": \"5532114242345c113e0494aee7794992790bcd24\", \"boringssl_crypto_tests\": \"18cdbbc648274b0bf12e86af162b0e6dc36ca31e\", \"boringssl_ssl_tests\": \"9b1c90fd2d0f1cd5bd97de9474ca0ec3b790ae54\", \"browser_tests\": \"ed4f1a07047c263557e5f1b8e3641b73fbd2dc53\", \"capture_unittests\": \"caae8f35536630971279b6b8cd45739af5f5d637\", \"cast_unittests\": \"2174a5eadf9d1297a4a6e4e2d4ba8c431882b6ac\", \"cc_unittests\": \"6178f90e09099869f9a799314c22596e85ae65b1\", \"chrome_app_unittests\": \"b65297e60bbd71627cecaa0943cb9f6c8ac85345\", \"chromedriver_unittests\": \"61b40a27f181ffd6d73b6e15a16a3b29cd6d8dce\", \"chromeos_components_unittests\": \"9c29e7e68aaf4c88aaa30da4914cfd1091ac4d47\", \"chromeos_unittests\": \"912a63765c47322fbad8ec30142f72c97e677abc\", \"components_browsertests\": \"b95a493b03e8a731eeb492c5eee33a4ead09f015\", \"components_unittests\": \"272e31c4d0d0690ca191f2b87c4526578ba354dd\", \"compositor_unittests\": \"bd062141ef1b88da078a7adf92429928b8b76fd2\", \"content_browsertests\": \"e81de4263b88ae577e1a9d16d6761dcf28b30ea5\", \"content_unittests\": \"75c0fd76dd99e1dca2f9eed54f636ca0b1c70d2b\", \"crashpad_tests\": \"78cbba0d44a8dacde732d9d0cb2579564e8e731e\", \"crypto_unittests\": \"c46d831d75b87bd42be9b14e742c35e9a555f055\", \"dbus_unittests\": \"26aa3824cbe901c32e11954061b7d9a8eba4aba7\", \"device_unittests\": \"abd1e780e218d124ea964431517045a8748b354a\", \"display_unittests\": \"414b67afe4b6553c037b309fab543d2408354865\", \"events_unittests\": \"1d62ecbbb548f053d31a05f8077b77f57b5f9ba9\", \"exo_unittests\": \"9a6f7229d61eb8673209cc412dfe6caecb02e8f0\", \"extensions_browsertests\": \"5d64de19c9171ffd9bd4e3b04ae864913a5a0fb7\", \"extensions_unittests\": \"ebb1649010308fdd8b2c3cdd8be1cb1bbe74ef71\", \"filesystem_service_unittests\": \"d15e5eb93798ae9bcf6476101be0bc869ccc0e1b\", \"gcm_unit_tests\": \"660a106a8b33c610a2eedd201bac2e6cd60d8f40\", \"gfx_unittests\": \"a917882a7b6c0010d8bf08cda5a13103fda28df2\", \"gin_unittests\": \"6ece3ffa2908c996a454c43651a11f0fb56ae444\", \"gl_unittests_ozone\": \"678497d0ece41a6f4477655c1b542a1adae27145\", \"google_apis_unittests\": \"94fecd410f14f5d212e3a6959faf4498e5d6542b\", \"gpu_unittests\": \"4b0eb527816c9585b7994bb87c74f18b053663be\", \"gwp_asan_unittests\": \"5e56c4921bc6ed50cff3a5d2d886c11f764fb50c\", \"interactive_ui_tests\": \"9272109af9734e3adbe08506c2f53a063114a898\", \"ipc_tests\": \"d76301a661bdf9d157368945d70a785f1d06d938\", \"jingle_unittests\": \"561c015e267ee18258396ddf3f466a90824ed21b\", \"keyboard_unittests\": \"63c78cb470b3bfc4acc3f6f1e4e05052445fa014\", \"latency_unittests\": \"82f9aae60e1a2ce5e8bf050306965c55b269252a\", \"libjingle_xmpp_unittests\": \"7d210eb97b1d9cf276454836c434c42f10336162\", \"liburlpattern_unittests\": \"d282497065af286a77cba7648ad79f53b060b5c7\", \"media_blink_unittests\": \"5134059f2c9456f812f3f0b59daf12993eff602c\", \"media_unittests\": \"feb5ef16c14d27c01317e1a8b4f9edf9f9aa0a64\", \"message_center_unittests\": \"bf36b09e9aade61f6fc92f8d8a4d9e63b4acd38f\", \"midi_unittests\": \"bfdbd0ed9c052ff54be7ba9c4ad361a1b511849c\", \"mojo_core_unittests\": \"e6e9bfd431cee1b915c8e5b5eed7f86cf2b74343\", \"mojo_unittests\": \"eb0e318bdce97e2bb0a0661f08334c8878f15663\", \"nacl_helper_nonsfi_unittests\": \"3e144054649043bd34c27920b65f2044cb90e700\", \"nacl_loader_unittests\": \"cd1b908d843bed52e1becf1497b5e8f6bc232e72\", \"native_theme_unittests\": \"c2594ddd41f38d17435182fc190fa5e2dc23c16f\", \"net_unittests\": \"2d9143423a8fd0b6029690c7a98ad4d1099c1b14\", \"ozone_gl_unittests\": \"c620f6d3c18d2ec9415e26f6f018a5712dd13e46\", \"ozone_unittests\": \"a3140ec4aef7be3e1dd808fb43205b7f684f3ca7\", \"ozone_x11_unittests\": \"0601fed67f47e9a33f93ce763da3aa2a4b4446fe\", \"pdf_unittests\": \"63e0824b9400c5bbb85ffbc30a8aab7f0eeba25d\", \"perfetto_unittests\": \"b04ca1e4db234b61e59e7b9459ee0d7771e296e3\", \"ppapi_unittests\": \"c07e79ad74b81a9b413eb061f9dd0d690db9f114\", \"printing_unittests\": \"8dabc0243b409aca7800411c11bb3b14f7484e06\", \"remoting_unittests\": \"75a49517efe9f3e840d117d5d08acbcdae37bf59\", \"sandbox_linux_unittests\": \"adef25a79990d7223eca52d1db3e090ef92ba915\", \"service_manager_unittests\": \"34c34a55238dd985d30948b5ad562e5506a6dbe1\", \"services_unittests\": \"cce63dbda7d3f061fe0afda31092b4eb8f4251e5\", \"shell_dialogs_unittests\": \"3c0f183ce7a63a95611c91adbec3f7ad7256c2ea\", \"shell_encryption_unittests\": \"ad3c3f5a695138f8d8886cd278aeea0d6205b1bc\", \"skia_unittests\": \"d9da0ac4b23779c82c64c86a3e709cc2da4ca219\", \"snapshot_unittests\": \"a397232249f92906eff2530427228e3f81076c95\", \"sql_unittests\": \"d9a3e9e53a0494643f64f3bc5d30ca6c0fd78ab9\", \"storage_unittests\": \"a081d0f6266282399dd8f6bed66c5eb391df6786\", \"sync_integration_tests\": \"9321b77f50afa13bd054936d667ad0002fb54d8d\", \"traffic_annotation_auditor_unittests\": \"23ae18271287f534efe55fa0f753256e2e8d378c\", \"ui_base_unittests\": \"652d3d4a2dbb8776bc6ee24f47ad3d517af4ab26\", \"ui_chromeos_unittests\": \"540ed3f806f67226f62c19b236a8bc88db4468bf\", \"ui_touch_selection_unittests\": \"6043b4910f15f79eb2a3939eb40eb7f9d0c0da3b\", \"unit_tests\": \"988f2aea9e99c8635a0da1b4d78cca6407e77ee8\", \"url_unittests\": \"5724004d03b071ebe4d2cd9de86a856dc673a139\", \"usage_time_limit_unittests\": \"90667bd61584e17489a26a787c110d107ae416e8\", \"views_unittests\": \"5349c494b8fd7545e5a371021d1b533d656864b7\", \"viz_unittests\": \"212a3dd6e9c2dd2b1b51e883c63ae77d6da5e142\", \"wm_unittests\": \"826016ae9f5dd2993c39b732ec4fe3edb57057be\", \"wtf_unittests\": \"2be7965d28aeb432895cf9cff1a6a70e2de3aa57\", \"zlib_unittests\": \"ab55605e563163d6289cb419e67c738995468b3c\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_4973d939604d11dcbaec68575e69c80c756ff63e355ab013b29b8bca26434b7c_v2\", \"builder_65ed874bd033a5984c3cbb528261ef1b136b40627f077f473aaaca27b3fa3caf_v2\", \"builder_fac2959e29eeee13eb43de2791ff2fcb7aa7ab15952f0beca21f4615b55fa2a2_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-72-w0e6\"], \"image\": [\"chrome-xenial-21010300-f0aeabbfea7\"], \"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\": [\"5661-c6f6250\"], \"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- WindowOpenApiTest.PopupBlockingExtension\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1610525671134822",
"status": "COMPLETED",
"status_changed_ts": "1610525671889247",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-dbg/22179",
"builder:linux-chromeos-dbg",
"buildset:commit/git/8f00fbee5437131e0e60aa3513482944cfbaf173",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/8f00fbee5437131e0e60aa3513482944cfbaf173",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023665231940981584",
"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/8858235169916056736/+/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:5113a0c3a3bac710",
"user_agent:luci-scheduler"
],
"updated_ts": "1610531742642206",
"url": "https://ci.chromium.org/b/8858235169916056736",
"utcnow_ts": "1610531743011915"
}
}
[09:55:43.024] Invocation finished in 1h41m32.367734393s with status FAILED