[15:54:49.566] New invocation is queued and will start shortly
[15:54:50.772] Starting the invocation (attempt 1)
[15:54:50.820] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[15:54:50.823] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[15:54:50.823] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9031065201708256960",
"parameters_json": "{\"builder_name\":\"linux-chromeos-dbg\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"5ff062f5e55c5b351413655a331677cab93234c2\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-dbg",
"scheduler_invocation_id:9031065201708256960",
"scheduler_job_id:chromium/linux-chromeos-dbg",
"user_agent:luci-scheduler",
"buildset:commit/git/5ff062f5e55c5b351413655a331677cab93234c2",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5ff062f5e55c5b351413655a331677cab93234c2",
"gitiles_ref:refs/heads/master"
]
}
[15:54:52.008] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1603468490937941",
"id": "8865635139605771952",
"parameters_json": "{\"builder_name\": \"linux-chromeos-dbg\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"5ff062f5e55c5b351413655a331677cab93234c2\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1603468491723269",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-dbg/20980",
"builder:linux-chromeos-dbg",
"buildset:commit/git/5ff062f5e55c5b351413655a331677cab93234c2",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5ff062f5e55c5b351413655a331677cab93234c2",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9031065201708256960",
"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/8865635139605771952/+/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": "1603468491724261",
"url": "https://ci.chromium.org/b/8865635139605771952",
"utcnow_ts": "1603468491996547"
}
}
[15:54:52.008] Task URL: https://ci.chromium.org/b/8865635139605771952
[15:54:52.008] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:2:0) after 1m0s
[15:55:52.086] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:2:0)
[15:55:52.086] Timer tick, asking Buildbucket for the build status
[15:55:52.205] Build 8865635139605771952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:55:52.205] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:3:0) after 1m0s
[15:56:52.322] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:3:0)
[15:56:52.322] Timer tick, asking Buildbucket for the build status
[15:56:52.405] Build 8865635139605771952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:56:52.405] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:4:0) after 1m0s
[15:57:52.510] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:4:0)
[15:57:52.510] Timer tick, asking Buildbucket for the build status
[15:57:52.614] Build 8865635139605771952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:57:52.614] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:5:0) after 1m0s
[15:58:42.990] Received PubSub notification, asking Buildbucket for the build status
[15:58:43.127] Build 8865635139605771952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:58:52.638] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:5:0)
[15:58:52.638] Timer tick, asking Buildbucket for the build status
[15:58:52.729] Build 8865635139605771952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:58:52.729] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:7:0) after 1m0s
[15:59:52.761] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:7:0)
[15:59:52.761] Timer tick, asking Buildbucket for the build status
[15:59:52.871] Build 8865635139605771952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:59:52.871] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:8:0) after 1m0s
[16:00:52.927] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:8:0)
[16:00:52.927] Timer tick, asking Buildbucket for the build status
[16:00:52.986] Build 8865635139605771952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:00:52.986] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:9:0) after 1m0s
[16:01:53.073] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:9:0)
[16:01:53.073] Timer tick, asking Buildbucket for the build status
[16:01:53.248] Build 8865635139605771952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:01:53.248] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:10:0) after 1m0s
[16:02:53.532] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:10:0)
[16:02:53.532] Timer tick, asking Buildbucket for the build status
[16:02:53.655] Build 8865635139605771952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:02:53.655] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:11:0) after 1m0s
[16:03:53.782] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:11:0)
[16:03:53.782] Timer tick, asking Buildbucket for the build status
[16:03:53.877] Build 8865635139605771952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:03:53.877] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:12:0) after 1m0s
[16:04:53.997] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:12:0)
[16:04:53.997] Timer tick, asking Buildbucket for the build status
[16:04:54.123] Build 8865635139605771952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:04:54.123] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:13:0) after 1m0s
[16:05:54.261] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:13:0)
[16:05:54.262] Timer tick, asking Buildbucket for the build status
[16:05:54.365] Build 8865635139605771952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:05:54.365] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:14:0) after 1m0s
[16:06:54.441] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:14:0)
[16:06:54.441] Timer tick, asking Buildbucket for the build status
[16:06:54.547] Build 8865635139605771952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:06:54.547] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:15:0) after 1m0s
[16:07:54.637] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:15:0)
[16:07:54.637] Timer tick, asking Buildbucket for the build status
[16:07:54.822] Build 8865635139605771952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:07:54.822] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:16:0) after 1m0s
[16:08:54.923] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:16:0)
[16:08:54.923] Timer tick, asking Buildbucket for the build status
[16:08:55.142] Build 8865635139605771952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:08:55.142] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:17:0) after 1m0s
[16:09:55.183] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:17:0)
[16:09:55.183] Timer tick, asking Buildbucket for the build status
[16:09:55.368] Build 8865635139605771952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:09:55.368] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:18:0) after 1m0s
[16:10:55.427] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:18:0)
[16:10:55.427] Timer tick, asking Buildbucket for the build status
[16:10:55.524] Build 8865635139605771952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:10:55.524] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:19:0) after 1m0s
[16:11:55.513] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:19:0)
[16:11:55.513] Timer tick, asking Buildbucket for the build status
[16:11:55.596] Build 8865635139605771952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:11:55.596] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:20:0) after 1m0s
[16:12:55.596] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:20:0)
[16:12:55.596] Timer tick, asking Buildbucket for the build status
[16:12:55.683] Build 8865635139605771952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:12:55.683] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:21:0) after 1m0s
[16:13:55.749] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:21:0)
[16:13:55.749] Timer tick, asking Buildbucket for the build status
[16:13:55.818] Build 8865635139605771952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:13:55.818] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:22:0) after 1m0s
[16:14:55.838] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:22:0)
[16:14:55.838] Timer tick, asking Buildbucket for the build status
[16:14:56.478] Build 8865635139605771952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:14:56.478] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:23:0) after 1m0s
[16:15:56.535] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:23:0)
[16:15:56.535] Timer tick, asking Buildbucket for the build status
[16:15:56.653] Build 8865635139605771952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:15:56.653] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:24:0) after 1m0s
[16:16:56.717] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:24:0)
[16:16:56.717] Timer tick, asking Buildbucket for the build status
[16:16:56.820] Build 8865635139605771952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:16:56.820] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:25:0) after 1m0s
[16:17:58.825] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:25:0)
[16:17:58.825] Timer tick, asking Buildbucket for the build status
[16:17:58.903] Build 8865635139605771952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:17:58.903] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:26:0) after 1m0s
[16:18:58.929] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:26:0)
[16:18:58.929] Timer tick, asking Buildbucket for the build status
[16:18:59.006] Build 8865635139605771952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:18:59.006] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:27:0) after 1m0s
[16:19:59.069] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:27:0)
[16:19:59.069] Timer tick, asking Buildbucket for the build status
[16:19:59.166] Build 8865635139605771952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:19:59.166] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:28:0) after 1m0s
[16:20:59.321] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:28:0)
[16:20:59.321] Timer tick, asking Buildbucket for the build status
[16:20:59.415] Build 8865635139605771952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:20:59.415] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:29:0) after 1m0s
[16:21:59.515] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:29:0)
[16:21:59.515] Timer tick, asking Buildbucket for the build status
[16:21:59.601] Build 8865635139605771952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:21:59.601] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:30:0) after 1m0s
[16:22:59.684] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:30:0)
[16:22:59.684] Timer tick, asking Buildbucket for the build status
[16:22:59.770] Build 8865635139605771952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:22:59.770] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:31:0) after 1m0s
[16:23:59.884] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:31:0)
[16:23:59.884] Timer tick, asking Buildbucket for the build status
[16:23:59.958] Build 8865635139605771952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:23:59.958] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:32:0) after 1m0s
[16:24:59.986] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:32:0)
[16:24:59.986] Timer tick, asking Buildbucket for the build status
[16:25:00.256] Build 8865635139605771952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:25:00.257] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:33:0) after 1m0s
[16:26:00.358] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:33:0)
[16:26:00.358] Timer tick, asking Buildbucket for the build status
[16:26:00.472] Build 8865635139605771952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:26:00.472] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:34:0) after 1m0s
[16:27:00.570] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:34:0)
[16:27:00.570] Timer tick, asking Buildbucket for the build status
[16:27:01.403] Build 8865635139605771952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:27:01.403] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:35:0) after 1m0s
[16:28:01.648] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:35:0)
[16:28:01.649] Timer tick, asking Buildbucket for the build status
[16:28:01.729] Build 8865635139605771952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:28:01.729] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:36:0) after 1m0s
[16:29:01.714] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:36:0)
[16:29:01.714] Timer tick, asking Buildbucket for the build status
[16:29:01.972] Build 8865635139605771952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:29:01.972] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:37:0) after 1m0s
[16:30:02.407] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:37:0)
[16:30:02.407] Timer tick, asking Buildbucket for the build status
[16:30:02.741] Build 8865635139605771952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:30:02.742] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:38:0) after 1m0s
[16:31:02.754] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:38:0)
[16:31:02.754] Timer tick, asking Buildbucket for the build status
[16:31:02.836] Build 8865635139605771952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:31:02.836] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:39:0) after 1m0s
[16:32:02.937] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:39:0)
[16:32:02.937] Timer tick, asking Buildbucket for the build status
[16:32:03.053] Build 8865635139605771952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:32:03.053] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:40:0) after 1m0s
[16:33:03.079] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:40:0)
[16:33:03.079] Timer tick, asking Buildbucket for the build status
[16:33:03.208] Build 8865635139605771952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:33:03.208] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:41:0) after 1m0s
[16:34:03.232] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:41:0)
[16:34:03.232] Timer tick, asking Buildbucket for the build status
[16:34:03.705] Build 8865635139605771952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:34:03.705] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:42:0) after 1m0s
[16:35:03.754] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:42:0)
[16:35:03.754] Timer tick, asking Buildbucket for the build status
[16:35:03.912] Build 8865635139605771952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:35:03.912] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:43:0) after 1m0s
[16:36:04.010] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:43:0)
[16:36:04.010] Timer tick, asking Buildbucket for the build status
[16:36:04.110] Build 8865635139605771952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:36:04.110] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:44:0) after 1m0s
[16:37:04.141] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:44:0)
[16:37:04.141] Timer tick, asking Buildbucket for the build status
[16:37:04.674] Build 8865635139605771952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:37:04.674] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:45:0) after 1m0s
[16:38:04.745] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:45:0)
[16:38:04.745] Timer tick, asking Buildbucket for the build status
[16:38:04.893] Build 8865635139605771952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:38:04.893] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:46:0) after 1m0s
[16:39:04.921] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:46:0)
[16:39:04.921] Timer tick, asking Buildbucket for the build status
[16:39:05.103] Build 8865635139605771952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:39:05.103] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:47:0) after 1m0s
[16:40:05.226] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:47:0)
[16:40:05.226] Timer tick, asking Buildbucket for the build status
[16:40:05.348] Build 8865635139605771952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:40:05.348] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:48:0) after 1m0s
[16:41:05.669] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:48:0)
[16:41:05.669] Timer tick, asking Buildbucket for the build status
[16:41:05.797] Build 8865635139605771952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:41:05.797] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:49:0) after 1m0s
[16:42:05.885] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:49:0)
[16:42:05.885] Timer tick, asking Buildbucket for the build status
[16:42:06.054] Build 8865635139605771952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:42:06.054] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:50:0) after 1m0s
[16:43:06.141] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:50:0)
[16:43:06.141] Timer tick, asking Buildbucket for the build status
[16:43:06.289] Build 8865635139605771952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:43:06.289] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:51:0) after 1m0s
[16:44:06.405] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:51:0)
[16:44:06.405] Timer tick, asking Buildbucket for the build status
[16:44:06.807] Build 8865635139605771952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:44:06.807] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:52:0) after 1m0s
[16:45:06.963] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:52:0)
[16:45:06.963] Timer tick, asking Buildbucket for the build status
[16:45:07.295] Build 8865635139605771952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:45:07.295] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:53:0) after 1m0s
[16:46:07.382] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:53:0)
[16:46:07.382] Timer tick, asking Buildbucket for the build status
[16:46:07.494] Build 8865635139605771952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:46:07.494] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:54:0) after 1m0s
[16:47:07.714] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:54:0)
[16:47:07.714] Timer tick, asking Buildbucket for the build status
[16:47:07.792] Build 8865635139605771952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:47:07.792] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:55:0) after 1m0s
[16:48:07.950] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:55:0)
[16:48:07.950] Timer tick, asking Buildbucket for the build status
[16:48:08.058] Build 8865635139605771952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:48:08.058] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:56:0) after 1m0s
[16:49:08.178] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:56:0)
[16:49:08.178] Timer tick, asking Buildbucket for the build status
[16:49:08.649] Build 8865635139605771952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:49:08.649] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:57:0) after 1m0s
[16:50:08.701] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:57:0)
[16:50:08.701] Timer tick, asking Buildbucket for the build status
[16:50:08.841] Build 8865635139605771952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:50:08.841] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:58:0) after 1m0s
[16:51:08.857] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:58:0)
[16:51:08.857] Timer tick, asking Buildbucket for the build status
[16:51:08.944] Build 8865635139605771952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:51:08.944] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:59:0) after 1m0s
[16:52:09.046] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:59:0)
[16:52:09.046] Timer tick, asking Buildbucket for the build status
[16:52:09.134] Build 8865635139605771952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:52:09.134] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:60:0) after 1m0s
[16:53:09.219] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:60:0)
[16:53:09.219] Timer tick, asking Buildbucket for the build status
[16:53:09.283] Build 8865635139605771952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:53:09.283] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:61:0) after 1m0s
[16:54:09.296] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:61:0)
[16:54:09.296] Timer tick, asking Buildbucket for the build status
[16:54:09.391] Build 8865635139605771952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:54:09.391] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:62:0) after 1m0s
[16:55:09.428] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:62:0)
[16:55:09.428] Timer tick, asking Buildbucket for the build status
[16:55:09.709] Build 8865635139605771952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:55:09.709] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:63:0) after 1m0s
[16:56:09.810] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:63:0)
[16:56:09.811] Timer tick, asking Buildbucket for the build status
[16:56:09.965] Build 8865635139605771952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:56:09.965] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:64:0) after 1m0s
[16:57:10.077] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:64:0)
[16:57:10.077] Timer tick, asking Buildbucket for the build status
[16:57:10.266] Build 8865635139605771952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:57:10.266] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:65:0) after 1m0s
[16:58:10.563] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:65:0)
[16:58:10.563] Timer tick, asking Buildbucket for the build status
[16:58:10.716] Build 8865635139605771952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:58:10.717] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:66:0) after 1m0s
[16:59:10.860] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:66:0)
[16:59:10.860] Timer tick, asking Buildbucket for the build status
[16:59:10.941] Build 8865635139605771952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:59:10.941] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:67:0) after 1m0s
[17:00:10.988] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:67:0)
[17:00:10.988] Timer tick, asking Buildbucket for the build status
[17:00:11.151] Build 8865635139605771952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:00:11.151] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:68:0) after 1m0s
[17:01:11.276] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:68:0)
[17:01:11.276] Timer tick, asking Buildbucket for the build status
[17:01:11.384] Build 8865635139605771952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:01:11.384] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:69:0) after 1m0s
[17:02:11.440] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:69:0)
[17:02:11.440] Timer tick, asking Buildbucket for the build status
[17:02:11.766] Build 8865635139605771952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:02:11.766] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:70:0) after 1m0s
[17:03:11.899] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:70:0)
[17:03:11.899] Timer tick, asking Buildbucket for the build status
[17:03:12.132] Build 8865635139605771952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:03:12.132] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:71:0) after 1m0s
[17:04:12.187] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:71:0)
[17:04:12.187] Timer tick, asking Buildbucket for the build status
[17:04:12.277] Build 8865635139605771952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:04:12.277] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:72:0) after 1m0s
[17:05:12.343] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:72:0)
[17:05:12.343] Timer tick, asking Buildbucket for the build status
[17:05:12.474] Build 8865635139605771952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:05:12.474] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:73:0) after 1m0s
[17:06:12.528] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:73:0)
[17:06:12.528] Timer tick, asking Buildbucket for the build status
[17:06:13.055] Build 8865635139605771952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:06:13.055] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:74:0) after 1m0s
[17:07:13.154] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:74:0)
[17:07:13.154] Timer tick, asking Buildbucket for the build status
[17:07:13.246] Build 8865635139605771952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:07:13.246] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:75:0) after 1m0s
[17:08:13.380] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:75:0)
[17:08:13.380] Timer tick, asking Buildbucket for the build status
[17:08:13.537] Build 8865635139605771952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:08:13.537] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:76:0) after 1m0s
[17:09:13.595] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:76:0)
[17:09:13.595] Timer tick, asking Buildbucket for the build status
[17:09:13.667] Build 8865635139605771952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:09:13.667] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:77:0) after 1m0s
[17:10:13.695] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:77:0)
[17:10:13.695] Timer tick, asking Buildbucket for the build status
[17:10:14.244] Build 8865635139605771952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:10:14.244] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:78:0) after 1m0s
[17:11:14.290] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:78:0)
[17:11:14.290] Timer tick, asking Buildbucket for the build status
[17:11:14.506] Build 8865635139605771952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:11:14.506] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:79:0) after 1m0s
[17:12:14.511] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:79:0)
[17:12:14.511] Timer tick, asking Buildbucket for the build status
[17:12:14.609] Build 8865635139605771952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:12:14.609] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:80:0) after 1m0s
[17:13:14.636] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:80:0)
[17:13:14.636] Timer tick, asking Buildbucket for the build status
[17:13:14.731] Build 8865635139605771952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:13:14.731] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:81:0) after 1m0s
[17:14:14.876] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:81:0)
[17:14:14.876] Timer tick, asking Buildbucket for the build status
[17:14:14.994] Build 8865635139605771952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:14:14.994] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:82:0) after 1m0s
[17:15:15.023] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:82:0)
[17:15:15.023] Timer tick, asking Buildbucket for the build status
[17:15:15.157] Build 8865635139605771952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:15:15.157] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:83:0) after 1m0s
[17:16:15.198] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:83:0)
[17:16:15.198] Timer tick, asking Buildbucket for the build status
[17:16:15.377] Build 8865635139605771952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:16:15.377] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:84:0) after 1m0s
[17:17:15.435] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:84:0)
[17:17:15.435] Timer tick, asking Buildbucket for the build status
[17:17:15.623] Build 8865635139605771952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:17:15.624] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:85:0) after 1m0s
[17:18:15.753] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:85:0)
[17:18:15.753] Timer tick, asking Buildbucket for the build status
[17:18:16.001] Build 8865635139605771952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:18:16.001] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:86:0) after 1m0s
[17:19:16.078] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:86:0)
[17:19:16.078] Timer tick, asking Buildbucket for the build status
[17:19:16.241] Build 8865635139605771952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:19:16.241] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:87:0) after 1m0s
[17:20:16.321] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:87:0)
[17:20:16.321] Timer tick, asking Buildbucket for the build status
[17:20:16.473] Build 8865635139605771952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:20:16.473] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:88:0) after 1m0s
[17:21:16.571] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:88:0)
[17:21:16.571] Timer tick, asking Buildbucket for the build status
[17:21:16.642] Build 8865635139605771952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:21:16.642] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:89:0) after 1m0s
[17:22:16.744] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:89:0)
[17:22:16.744] Timer tick, asking Buildbucket for the build status
[17:22:16.808] Build 8865635139605771952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:22:16.808] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:90:0) after 1m0s
[17:23:16.880] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:90:0)
[17:23:16.880] Timer tick, asking Buildbucket for the build status
[17:23:17.216] Build 8865635139605771952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:23:17.216] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:91:0) after 1m0s
[17:24:17.244] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:91:0)
[17:24:17.244] Timer tick, asking Buildbucket for the build status
[17:24:17.585] Build 8865635139605771952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:24:17.585] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:92:0) after 1m0s
[17:25:17.566] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:92:0)
[17:25:17.566] Timer tick, asking Buildbucket for the build status
[17:25:17.729] Build 8865635139605771952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:25:17.729] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:93:0) after 1m0s
[17:26:17.768] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:93:0)
[17:26:17.768] Timer tick, asking Buildbucket for the build status
[17:26:17.846] Build 8865635139605771952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:26:17.846] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:94:0) after 1m0s
[17:27:17.963] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:94:0)
[17:27:17.963] Timer tick, asking Buildbucket for the build status
[17:27:18.053] Build 8865635139605771952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:27:18.053] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:95:0) after 1m0s
[17:28:18.096] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:95:0)
[17:28:18.096] Timer tick, asking Buildbucket for the build status
[17:28:18.172] Build 8865635139605771952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:28:18.172] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:96:0) after 1m0s
[17:29:18.233] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:96:0)
[17:29:18.233] Timer tick, asking Buildbucket for the build status
[17:29:18.318] Build 8865635139605771952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:29:18.318] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:97:0) after 1m0s
[17:30:18.374] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:97:0)
[17:30:18.374] Timer tick, asking Buildbucket for the build status
[17:30:18.486] Build 8865635139605771952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:30:18.486] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:98:0) after 1m0s
[17:31:18.510] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:98:0)
[17:31:18.510] Timer tick, asking Buildbucket for the build status
[17:31:18.701] Build 8865635139605771952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:31:18.701] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:99:0) after 1m0s
[17:32:18.727] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:99:0)
[17:32:18.727] Timer tick, asking Buildbucket for the build status
[17:32:18.807] Build 8865635139605771952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:32:18.807] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:100:0) after 1m0s
[17:33:18.892] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:100:0)
[17:33:18.892] Timer tick, asking Buildbucket for the build status
[17:33:18.964] Build 8865635139605771952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:33:18.964] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:101:0) after 1m0s
[17:34:19.023] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:101:0)
[17:34:19.023] Timer tick, asking Buildbucket for the build status
[17:34:19.161] Build 8865635139605771952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:34:19.162] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:102:0) after 1m0s
[17:35:19.218] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:102:0)
[17:35:19.218] Timer tick, asking Buildbucket for the build status
[17:35:19.376] Build 8865635139605771952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:35:19.376] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:103:0) after 1m0s
[17:36:19.443] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:103:0)
[17:36:19.443] Timer tick, asking Buildbucket for the build status
[17:36:19.608] Build 8865635139605771952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:36:19.608] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:104:0) after 1m0s
[17:37:19.636] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:104:0)
[17:37:19.636] Timer tick, asking Buildbucket for the build status
[17:37:19.804] Build 8865635139605771952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:37:19.804] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9031065201708256960:105:0) after 1m0s
[17:37:34.128] Received PubSub notification, asking Buildbucket for the build status
[17:37:34.219] Build 8865635139605771952: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[17:37:34.219] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1603474653370935",
"created_by": "project:chromium",
"created_ts": "1603468490937941",
"failure_reason": "BUILD_FAILURE",
"id": "8865635139605771952",
"parameters_json": "{\"builder_name\": \"linux-chromeos-dbg\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"5ff062f5e55c5b351413655a331677cab93234c2\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"1 Test Suite(s) failed.\\n\\n**ash_unittests** failed because of:\\n\\n- CaptureModeTest.VideoRecordingUiBehavior\"}, \"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\": \"5ff062f5e55c5b351413655a331677cab93234c2\", \"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-9-csso\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-dbg\", \"buildnumber\": 20980, \"got_angle_revision\": \"4791cf2cde523160941d982df4b65786adbafc8c\", \"got_dawn_revision\": \"24e6369261071cb59f55de5f987f6fae5dd9ddde\", \"got_nacl_revision\": \"0fabd6dc4222dfae664e55a84326e0c904f5b62c\", \"got_revision\": \"5ff062f5e55c5b351413655a331677cab93234c2\", \"got_revision_cp\": \"refs/heads/master@{#820258}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"df17a76102dfabb3f1bd6e51449cece9f77b45e3\", \"got_v8_revision\": \"e323f0123c30356f22a162b838a040f88e451ef2\", \"got_v8_revision_cp\": \"refs/heads/8.8.168@{#1}\", \"got_webrtc_revision\": \"213fa85f73cdc7e794d8bf9c16aee40d98da11df\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32476}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"5ff062f5e55c5b351413655a331677cab93234c2\", \"swarm_hashes_refs/heads/master(at){#820258}_without_patch\": {\"absl_hardening_tests\": \"16caf85bf5f5a8a73905cd21976a008ca4b866a7\", \"accessibility_unittests\": \"bff08809df74348c45003d3f5520a494ed2dd818\", \"app_list_unittests\": \"28a140732774ffccf2ff8fc849dd3a147f6e2b66\", \"app_shell_unittests\": \"4e6a6b1ebd8fa875399c492babf8b8c360fac26d\", \"ash_unittests\": \"1ef0081d07a57566596e5e6b37bb883f1b06c451\", \"aura_unittests\": \"c24cc06a54951781d1e928b2c9c7bd3c24796c2c\", \"base_unittests\": \"19a614cd2c86d1908fc228cd204a9abd84bd5c9b\", \"base_util_unittests\": \"d18e8ec2610c1a784170e500f43e054deb8a60a0\", \"blink_common_unittests\": \"e2b6934eb5702a6f785d8e9940d620ca40153460\", \"blink_fuzzer_unittests\": \"44d5cd7b7141a83bb5fd1910fad66cec95ceb4ab\", \"blink_heap_unittests\": \"e42d16d0fb990363a32d06f1da10755d10f76dcc\", \"blink_platform_unittests\": \"99be7e80e5b613fa2e5b6d109ccc483eea6764cb\", \"blink_unittests\": \"10c4a6a0fa43e8cf44708a1646017bae721570a4\", \"boringssl_crypto_tests\": \"4d9d6fbee0456e9a7295c7851340c2488af2ce50\", \"boringssl_ssl_tests\": \"8fa5e9ee06b4a9ddbfaf1964f503937f35075cb9\", \"browser_tests\": \"34ddf49cdfffb22c76b02817f8d4f9182e0a8134\", \"capture_unittests\": \"cbdbed36ca95217d165c3a4feb8cb5fc5abd1331\", \"cast_unittests\": \"406d5c5ae8067cbf93928d5627381202674fc6fc\", \"cc_unittests\": \"0a2d3cb480cff174f6c94f55242b2d51f00a20db\", \"chrome_app_unittests\": \"e5506353e6ed7ae0ae92bfcdaafb4eb9ff868f24\", \"chromedriver_unittests\": \"8be6af3275e13c858f4a54713c861c527f67b3ad\", \"chromeos_components_unittests\": \"d4ead2ab747df6193011092183592fd64fc5c83d\", \"chromeos_unittests\": \"75f51a3dcf2011846a76960e19cb07e4cdddf917\", \"components_browsertests\": \"81e8e4f423b548c8d021b3d9b020d9676d183021\", \"components_unittests\": \"e23e3617a3eff953b912213a91d1e5e6c1add8cb\", \"compositor_unittests\": \"d73aaf04eca42a38921d079a4fb4087ae07cda0b\", \"content_browsertests\": \"7bb62b3ff749cffb7ddaa2e406df22f531b6e6bd\", \"content_unittests\": \"23e8383a35479e76bcc96225c566806c71e0e642\", \"crypto_unittests\": \"4060f9df65f2e0a0dda79f76a7ba0436a738ca46\", \"dbus_unittests\": \"dca21454152ad6b2d7eec3b44a44cc2a77c92da4\", \"device_unittests\": \"95a02553a2d3f88f9aea921989d587ac8c36721d\", \"display_unittests\": \"7b2ae88d5e757d1a6400983b2d68180ff96eec7f\", \"events_unittests\": \"0f1c0253f4dc32ae7b8a55c499bda50164d5e936\", \"exo_unittests\": \"e06ee196cf8d41ffd5b93328c93e94edde2e60e1\", \"extensions_browsertests\": \"deb0983e9ad570d7152c1b6145e74d1bdf1a6d7c\", \"extensions_unittests\": \"c701a5596c319d58746dc63dfe59af77baeb0e7b\", \"filesystem_service_unittests\": \"bd32ece5d736abc3d6f44966f1a12b1019109cd6\", \"gcm_unit_tests\": \"34af562d511c6e3ea19378790df359f746a489cd\", \"gfx_unittests\": \"3b6b069b5cbaf3cb02134cbc0fe82e688969862e\", \"gin_unittests\": \"1bfdf9073a709c548653f6ff43410b6232d96bbf\", \"gl_unittests_ozone\": \"c6f76632420fe9bfd7647b88c420519b121c75d6\", \"google_apis_unittests\": \"d88620dd85cc10a6e07c8de369753e3d8ccbf11b\", \"gpu_unittests\": \"cfe6346700cee49f91fb6afd6f61b1d78f03d828\", \"gwp_asan_unittests\": \"17219b6d90c17dc10dbd41c2282a88c62ea59b93\", \"interactive_ui_tests\": \"c5ebeefd7ed4b6c4d3cb3b7b0c476fae304c26dc\", \"ipc_tests\": \"a841c5d5d69324e1d82690db50b474193fb2831d\", \"jingle_unittests\": \"5f78fb0fc900daf692249509339c72aa67a98003\", \"keyboard_unittests\": \"41b20f2c591d8b10ab03356f365edce31fed8f63\", \"latency_unittests\": \"82412828ba1f3c73a95a589d9b6a92fd7defe7c3\", \"libjingle_xmpp_unittests\": \"14d6dcd23624bdc657a60b39e8d7ee66f80d0d4e\", \"media_blink_unittests\": \"98d4576ec657a120e229de398aca82221f09b25d\", \"media_unittests\": \"bfd2e94b040cf79e702f404f3e56c22d7731cbfa\", \"message_center_unittests\": \"30fc7e3cab37b36c333b5af32d61b98cdaa36943\", \"midi_unittests\": \"48c166c80926da9bb6ca92e138f14d70b8cd87a7\", \"mojo_core_unittests\": \"5f8c4ba84001889911ca5dc9ff1571f445f65393\", \"mojo_unittests\": \"ba30477c61a5e0d2b2ab9382ee28b475f65666b9\", \"nacl_helper_nonsfi_unittests\": \"10773dae3e5c9836f3dd63893d5d68a3ecfe239f\", \"nacl_loader_unittests\": \"f3312988ba02e181a4ca685b64cc6cfab7067ec0\", \"native_theme_unittests\": \"0ee1901e0dfce80c2cf350ec04e15431d2d93021\", \"net_unittests\": \"81319578a7966aa3da99dab0789b5c474b2b16f1\", \"ozone_gl_unittests\": \"fe82f42e8f3017a6b14a1e73c9f541c4931edc6c\", \"ozone_unittests\": \"035da2d49762823c67c2fb1abfeed8bde0eda9a9\", \"ozone_x11_unittests\": \"12557f5b03bad024afe2e17144ff3e84ebb49e2a\", \"pdf_unittests\": \"54d173879d27a930f2527966527b98bf09060bee\", \"perfetto_unittests\": \"6c6c15be6772103d5b645fee1ddf06a5b45e7ef2\", \"ppapi_unittests\": \"caa1893fd8c5a76605c77ccacbcb6a4fd0ad3694\", \"printing_unittests\": \"48944fe9a4f4ec026e502aaa950ff6aa9a92babf\", \"remoting_unittests\": \"8c20e069d2442e197f5ed044d258880d91f99f95\", \"sandbox_linux_unittests\": \"52c7af5db43195c7ac04b40de889bcb2ce036b99\", \"service_manager_unittests\": \"1683cfbde4f93a1db4cdc2da8082d8c1b58b78cf\", \"services_unittests\": \"dc5b62a91bde3f31048f97bc797ad8fc8ea0fb9b\", \"shell_dialogs_unittests\": \"f1d51c65f45bfcfa3af7a16f120a5dec5623138b\", \"skia_unittests\": \"d43516ed7766f0da685849c25fa75b3b71dbd839\", \"snapshot_unittests\": \"168aa63fddfe69539802aa6f95467b98e64603cd\", \"sql_unittests\": \"dc6626976a394b02e242d6ce1e978af9ae50d15c\", \"standalone_angle_unittests\": \"d96096cc981107635730be62be7729c9049d83e8\", \"storage_unittests\": \"6fc2c38a8bdc9f87ebe5bc4207dacf443465e7ad\", \"sync_integration_tests\": \"18338d77d3755baa90ac5f22c725e797c3251e26\", \"traffic_annotation_auditor_unittests\": \"d28a5548fb02f29ec3a7334920072aeb3c24f637\", \"ui_base_unittests\": \"e80bd152efc3872428be9408c173ac4350b3086b\", \"ui_chromeos_unittests\": \"8ef255d562f5a0da88f5ae69c68b0eef83c7963d\", \"ui_touch_selection_unittests\": \"98bf8c12f0ea330b68536a6d78aed03ccccccfb7\", \"unit_tests\": \"0628c5bf596d2b8c7d32a4d8c5533d4cbe909614\", \"url_unittests\": \"09c1445b4cabf02071e57e5ef33dc2e1a1ac91c3\", \"usage_time_limit_unittests\": \"e3eb83f800fd49b611b82d3efa00a9a5394cc076\", \"views_unittests\": \"788661c4cf60126cbb6e154cb64dbc64018e12fa\", \"viz_unittests\": \"9c1d6d7c76651598255558c1baf5b6b1d682f31e\", \"wm_unittests\": \"6ec5dbe77fda38f863779e128c1e323500f2091c\", \"wtf_unittests\": \"66c6116ddee09487c21177d72ff3a2f2062bffde\", \"zlib_unittests\": \"fad59f5b9b76ef65eb05ffcd31752ed233478dbd\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_4973d939604d11dcbaec68575e69c80c756ff63e355ab013b29b8bca26434b7c_v2\", \"builder_a1b9c6892397d61c3624774723419f5ae48fcf776e7d2304bd4682bf5cd4c049_v2\", \"builder_dacf669b49e3ec352df995227b58e62a545590985cca62e76e0831f920af4ccd_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-9-csso\"], \"image\": [\"chrome-xenial-20101800-2d400e1627f\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"e2-standard-8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16\", \"Ubuntu-16.04\", \"Ubuntu-16.04.6\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.12\"], \"server_version\": [\"5612-26d29a7\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}, \"ui\": {\"info\": \"1 Test Suite(s) failed.\\n\\n**ash_unittests** failed because of:\\n\\n- CaptureModeTest.VideoRecordingUiBehavior\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1603468722059621",
"status": "COMPLETED",
"status_changed_ts": "1603468722477142",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-dbg/20980",
"builder:linux-chromeos-dbg",
"buildset:commit/git/5ff062f5e55c5b351413655a331677cab93234c2",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5ff062f5e55c5b351413655a331677cab93234c2",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9031065201708256960",
"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/8865635139605771952/+/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:4f6efd059492d610",
"user_agent:luci-scheduler"
],
"updated_ts": "1603474653712297",
"url": "https://ci.chromium.org/b/8865635139605771952",
"utcnow_ts": "1603474654209214"
}
}
[17:37:34.219] Invocation finished in 1h42m44.671311603s with status FAILED