[17:31:42.673] New invocation is queued and will start shortly
[17:31:43.768] Starting the invocation (attempt 1)
[17:31:43.834] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[17:31:43.840] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[17:31:43.840] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9017922546094163376",
"parameters_json": "{\"builder_name\":\"linux-chromeos-rel\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"2f519680810816957f3a878a3b53fff735680046\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-rel",
"scheduler_invocation_id:9017922546094163376",
"scheduler_job_id:chromium/linux-chromeos-rel",
"user_agent:luci-scheduler",
"buildset:commit/git/2f519680810816957f3a878a3b53fff735680046",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/2f519680810816957f3a878a3b53fff735680046",
"gitiles_ref:refs/heads/master"
]
}
[17:31:45.098] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1616002303969663",
"id": "8852492484071270352",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"2f519680810816957f3a878a3b53fff735680046\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1616002304698724",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/46222",
"builder:linux-chromeos-rel",
"buildset:commit/git/2f519680810816957f3a878a3b53fff735680046",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/2f519680810816957f3a878a3b53fff735680046",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9017922546094163376",
"scheduler_job_id:chromium/linux-chromeos-rel",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8852492484071270352/+/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": "1616002304699081",
"url": "https://ci.chromium.org/b/8852492484071270352",
"utcnow_ts": "1616002305069815"
}
}
[17:31:45.098] Task URL: https://ci.chromium.org/b/8852492484071270352
[17:31:45.098] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:2:0) after 1m0s
[17:32:45.216] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:2:0)
[17:32:45.216] Timer tick, asking Buildbucket for the build status
[17:32:45.405] Build 8852492484071270352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:32:45.405] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:3:0) after 1m0s
[17:33:45.610] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:3:0)
[17:33:45.610] Timer tick, asking Buildbucket for the build status
[17:33:45.726] Build 8852492484071270352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:33:45.726] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:4:0) after 1m0s
[17:34:45.827] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:4:0)
[17:34:45.827] Timer tick, asking Buildbucket for the build status
[17:34:45.883] Build 8852492484071270352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:34:45.883] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:5:0) after 1m0s
[17:35:45.956] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:5:0)
[17:35:45.956] Timer tick, asking Buildbucket for the build status
[17:35:46.111] Build 8852492484071270352: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:35:46.111] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:6:0) after 1m0s
[17:36:24.473] Received PubSub notification, asking Buildbucket for the build status
[17:36:24.701] Build 8852492484071270352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:36:46.182] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:6:0)
[17:36:46.182] Timer tick, asking Buildbucket for the build status
[17:36:46.267] Build 8852492484071270352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:36:46.267] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:8:0) after 1m0s
[17:37:46.367] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:8:0)
[17:37:46.367] Timer tick, asking Buildbucket for the build status
[17:37:46.501] Build 8852492484071270352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:37:46.501] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:9:0) after 1m0s
[17:38:46.667] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:9:0)
[17:38:46.667] Timer tick, asking Buildbucket for the build status
[17:38:46.805] Build 8852492484071270352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:38:46.805] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:10:0) after 1m0s
[17:39:46.875] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:10:0)
[17:39:46.875] Timer tick, asking Buildbucket for the build status
[17:39:46.944] Build 8852492484071270352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:39:46.944] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:11:0) after 1m0s
[17:40:46.995] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:11:0)
[17:40:46.995] Timer tick, asking Buildbucket for the build status
[17:40:47.078] Build 8852492484071270352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:40:47.078] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:12:0) after 1m0s
[17:41:47.187] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:12:0)
[17:41:47.187] Timer tick, asking Buildbucket for the build status
[17:41:47.307] Build 8852492484071270352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:41:47.307] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:13:0) after 1m0s
[17:42:47.344] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:13:0)
[17:42:47.345] Timer tick, asking Buildbucket for the build status
[17:42:47.434] Build 8852492484071270352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:42:47.434] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:14:0) after 1m0s
[17:43:47.485] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:14:0)
[17:43:47.485] Timer tick, asking Buildbucket for the build status
[17:43:47.901] Build 8852492484071270352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:43:47.901] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:15:0) after 1m0s
[17:44:47.946] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:15:0)
[17:44:47.946] Timer tick, asking Buildbucket for the build status
[17:44:48.051] Build 8852492484071270352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:44:48.051] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:16:0) after 1m0s
[17:45:48.066] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:16:0)
[17:45:48.066] Timer tick, asking Buildbucket for the build status
[17:45:48.141] Build 8852492484071270352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:45:48.141] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:17:0) after 1m0s
[17:46:48.158] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:17:0)
[17:46:48.158] Timer tick, asking Buildbucket for the build status
[17:46:48.285] Build 8852492484071270352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:46:48.285] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:18:0) after 1m0s
[17:47:48.284] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:18:0)
[17:47:48.284] Timer tick, asking Buildbucket for the build status
[17:47:48.428] Build 8852492484071270352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:47:48.428] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:19:0) after 1m0s
[17:48:48.447] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:19:0)
[17:48:48.447] Timer tick, asking Buildbucket for the build status
[17:48:48.521] Build 8852492484071270352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:48:48.521] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:20:0) after 1m0s
[17:49:48.556] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:20:0)
[17:49:48.556] Timer tick, asking Buildbucket for the build status
[17:49:48.624] Build 8852492484071270352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:49:48.624] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:21:0) after 1m0s
[17:50:48.638] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:21:0)
[17:50:48.638] Timer tick, asking Buildbucket for the build status
[17:50:48.700] Build 8852492484071270352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:50:48.700] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:22:0) after 1m0s
[17:51:48.717] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:22:0)
[17:51:48.717] Timer tick, asking Buildbucket for the build status
[17:51:48.810] Build 8852492484071270352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:51:48.810] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:23:0) after 1m0s
[17:52:48.855] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:23:0)
[17:52:48.855] Timer tick, asking Buildbucket for the build status
[17:52:49.155] Build 8852492484071270352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:52:49.155] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:24:0) after 1m0s
[17:53:49.177] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:24:0)
[17:53:49.177] Timer tick, asking Buildbucket for the build status
[17:53:49.255] Build 8852492484071270352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:53:49.255] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:25:0) after 1m0s
[17:54:49.271] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:25:0)
[17:54:49.271] Timer tick, asking Buildbucket for the build status
[17:54:49.335] Build 8852492484071270352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:54:49.335] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:26:0) after 1m0s
[17:55:49.394] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:26:0)
[17:55:49.394] Timer tick, asking Buildbucket for the build status
[17:55:49.467] Build 8852492484071270352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:55:49.467] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:27:0) after 1m0s
[17:56:49.949] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:27:0)
[17:56:49.949] Timer tick, asking Buildbucket for the build status
[17:56:50.023] Build 8852492484071270352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:56:50.023] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:28:0) after 1m0s
[17:57:50.138] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:28:0)
[17:57:50.138] Timer tick, asking Buildbucket for the build status
[17:57:50.386] Build 8852492484071270352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:57:50.386] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:29:0) after 1m0s
[17:58:50.502] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:29:0)
[17:58:50.502] Timer tick, asking Buildbucket for the build status
[17:58:50.796] Build 8852492484071270352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:58:50.796] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:30:0) after 1m0s
[17:59:50.779] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:30:0)
[17:59:50.779] Timer tick, asking Buildbucket for the build status
[17:59:50.860] Build 8852492484071270352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:59:50.860] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:31:0) after 1m0s
[18:00:50.998] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:31:0)
[18:00:50.998] Timer tick, asking Buildbucket for the build status
[18:00:52.495] Build 8852492484071270352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:00:52.495] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:32:0) after 1m0s
[18:01:52.535] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:32:0)
[18:01:52.535] Timer tick, asking Buildbucket for the build status
[18:01:52.739] Build 8852492484071270352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:01:52.739] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:33:0) after 1m0s
[18:02:52.835] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:33:0)
[18:02:52.835] Timer tick, asking Buildbucket for the build status
[18:02:53.883] Build 8852492484071270352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:02:53.883] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:34:0) after 1m0s
[18:03:53.971] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:34:0)
[18:03:53.971] Timer tick, asking Buildbucket for the build status
[18:03:54.114] Build 8852492484071270352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:03:54.114] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:35:0) after 1m0s
[18:04:54.187] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:35:0)
[18:04:54.187] Timer tick, asking Buildbucket for the build status
[18:04:54.304] Build 8852492484071270352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:04:54.304] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:36:0) after 1m0s
[18:05:54.422] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:36:0)
[18:05:54.422] Timer tick, asking Buildbucket for the build status
[18:05:54.542] Build 8852492484071270352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:05:54.542] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:37:0) after 1m0s
[18:06:54.648] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:37:0)
[18:06:54.648] Timer tick, asking Buildbucket for the build status
[18:06:54.726] Build 8852492484071270352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:06:54.726] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:38:0) after 1m0s
[18:07:54.871] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:38:0)
[18:07:54.871] Timer tick, asking Buildbucket for the build status
[18:07:54.999] Build 8852492484071270352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:07:54.999] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:39:0) after 1m0s
[18:08:54.999] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:39:0)
[18:08:54.999] Timer tick, asking Buildbucket for the build status
[18:08:55.078] Build 8852492484071270352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:08:55.078] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:40:0) after 1m0s
[18:09:55.149] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:40:0)
[18:09:55.149] Timer tick, asking Buildbucket for the build status
[18:09:55.221] Build 8852492484071270352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:09:55.221] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:41:0) after 1m0s
[18:10:55.231] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:41:0)
[18:10:55.231] Timer tick, asking Buildbucket for the build status
[18:10:55.318] Build 8852492484071270352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:10:55.318] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:42:0) after 1m0s
[18:11:55.345] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:42:0)
[18:11:55.345] Timer tick, asking Buildbucket for the build status
[18:11:55.449] Build 8852492484071270352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:11:55.449] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:43:0) after 1m0s
[18:12:55.481] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:43:0)
[18:12:55.481] Timer tick, asking Buildbucket for the build status
[18:12:55.541] Build 8852492484071270352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:12:55.541] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:44:0) after 1m0s
[18:13:55.543] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:44:0)
[18:13:55.543] Timer tick, asking Buildbucket for the build status
[18:13:55.615] Build 8852492484071270352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:13:55.615] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:45:0) after 1m0s
[18:14:55.677] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:45:0)
[18:14:55.677] Timer tick, asking Buildbucket for the build status
[18:14:55.742] Build 8852492484071270352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:14:55.742] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:46:0) after 1m0s
[18:15:55.770] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:46:0)
[18:15:55.770] Timer tick, asking Buildbucket for the build status
[18:15:55.917] Build 8852492484071270352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:15:55.917] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:47:0) after 1m0s
[18:16:56.096] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:47:0)
[18:16:56.101] Timer tick, asking Buildbucket for the build status
[18:16:56.228] Build 8852492484071270352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:16:56.228] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:48:0) after 1m0s
[18:17:56.278] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:48:0)
[18:17:56.278] Timer tick, asking Buildbucket for the build status
[18:17:56.352] Build 8852492484071270352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:17:56.352] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:49:0) after 1m0s
[18:18:56.468] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:49:0)
[18:18:56.468] Timer tick, asking Buildbucket for the build status
[18:18:56.537] Build 8852492484071270352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:18:56.537] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:50:0) after 1m0s
[18:19:56.709] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:50:0)
[18:19:56.709] Timer tick, asking Buildbucket for the build status
[18:19:56.812] Build 8852492484071270352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:19:56.812] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:51:0) after 1m0s
[18:20:56.990] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:51:0)
[18:20:56.990] Timer tick, asking Buildbucket for the build status
[18:20:57.069] Build 8852492484071270352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:20:57.069] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:52:0) after 1m0s
[18:21:57.208] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:52:0)
[18:21:57.208] Timer tick, asking Buildbucket for the build status
[18:21:57.285] Build 8852492484071270352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:21:57.285] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:53:0) after 1m0s
[18:22:57.481] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:53:0)
[18:22:57.481] Timer tick, asking Buildbucket for the build status
[18:22:57.689] Build 8852492484071270352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:22:57.689] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:54:0) after 1m0s
[18:23:57.839] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:54:0)
[18:23:57.839] Timer tick, asking Buildbucket for the build status
[18:23:57.909] Build 8852492484071270352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:23:57.909] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:55:0) after 1m0s
[18:24:58.196] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:55:0)
[18:24:58.196] Timer tick, asking Buildbucket for the build status
[18:24:58.330] Build 8852492484071270352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:24:58.330] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:56:0) after 1m0s
[18:25:58.474] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:56:0)
[18:25:58.474] Timer tick, asking Buildbucket for the build status
[18:25:59.193] Build 8852492484071270352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:25:59.193] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:57:0) after 1m0s
[18:26:59.313] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:57:0)
[18:26:59.313] Timer tick, asking Buildbucket for the build status
[18:26:59.395] Build 8852492484071270352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:26:59.395] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:58:0) after 1m0s
[18:27:59.454] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:58:0)
[18:27:59.454] Timer tick, asking Buildbucket for the build status
[18:27:59.525] Build 8852492484071270352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:27:59.525] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:59:0) after 1m0s
[18:28:59.585] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:59:0)
[18:28:59.585] Timer tick, asking Buildbucket for the build status
[18:28:59.649] Build 8852492484071270352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:28:59.649] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:60:0) after 1m0s
[18:29:59.808] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:60:0)
[18:29:59.808] Timer tick, asking Buildbucket for the build status
[18:29:59.883] Build 8852492484071270352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:29:59.883] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:61:0) after 1m0s
[18:30:59.919] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:61:0)
[18:30:59.919] Timer tick, asking Buildbucket for the build status
[18:30:59.994] Build 8852492484071270352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:30:59.994] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:62:0) after 1m0s
[18:32:00.096] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:62:0)
[18:32:00.097] Timer tick, asking Buildbucket for the build status
[18:32:00.364] Build 8852492484071270352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:32:00.364] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:63:0) after 1m0s
[18:33:00.437] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:63:0)
[18:33:00.437] Timer tick, asking Buildbucket for the build status
[18:33:00.512] Build 8852492484071270352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:33:00.512] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:64:0) after 1m0s
[18:34:00.553] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:64:0)
[18:34:00.553] Timer tick, asking Buildbucket for the build status
[18:34:00.645] Build 8852492484071270352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:34:00.645] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:65:0) after 1m0s
[18:35:00.644] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:65:0)
[18:35:00.644] Timer tick, asking Buildbucket for the build status
[18:35:00.716] Build 8852492484071270352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:35:00.716] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:66:0) after 1m0s
[18:36:00.761] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:66:0)
[18:36:00.761] Timer tick, asking Buildbucket for the build status
[18:36:00.838] Build 8852492484071270352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:36:00.838] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:67:0) after 1m0s
[18:37:00.888] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:67:0)
[18:37:00.888] Timer tick, asking Buildbucket for the build status
[18:37:03.039] Build 8852492484071270352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:37:03.039] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:68:0) after 1m0s
[18:38:03.097] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:68:0)
[18:38:03.097] Timer tick, asking Buildbucket for the build status
[18:38:03.485] Build 8852492484071270352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:38:03.485] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:69:0) after 1m0s
[18:39:03.540] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:69:0)
[18:39:03.540] Timer tick, asking Buildbucket for the build status
[18:39:03.926] Build 8852492484071270352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:39:03.926] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:70:0) after 1m0s
[18:40:03.981] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:70:0)
[18:40:03.981] Timer tick, asking Buildbucket for the build status
[18:40:04.047] Build 8852492484071270352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:40:04.047] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:71:0) after 1m0s
[18:41:04.122] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:71:0)
[18:41:04.122] Timer tick, asking Buildbucket for the build status
[18:41:04.245] Build 8852492484071270352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:41:04.245] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:72:0) after 1m0s
[18:42:04.331] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:72:0)
[18:42:04.331] Timer tick, asking Buildbucket for the build status
[18:42:05.039] Build 8852492484071270352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:42:05.039] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:73:0) after 1m0s
[18:43:05.103] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:73:0)
[18:43:05.103] Timer tick, asking Buildbucket for the build status
[18:43:05.316] Build 8852492484071270352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:43:05.316] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:74:0) after 1m0s
[18:44:05.333] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:74:0)
[18:44:05.333] Timer tick, asking Buildbucket for the build status
[18:44:05.400] Build 8852492484071270352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:44:05.400] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:75:0) after 1m0s
[18:45:05.405] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:75:0)
[18:45:05.405] Timer tick, asking Buildbucket for the build status
[18:45:05.467] Build 8852492484071270352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:45:05.467] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:76:0) after 1m0s
[18:46:05.471] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:76:0)
[18:46:05.472] Timer tick, asking Buildbucket for the build status
[18:46:05.548] Build 8852492484071270352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:46:05.548] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:77:0) after 1m0s
[18:47:05.585] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:77:0)
[18:47:05.585] Timer tick, asking Buildbucket for the build status
[18:47:05.662] Build 8852492484071270352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:47:05.662] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:78:0) after 1m0s
[18:48:05.712] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:78:0)
[18:48:05.712] Timer tick, asking Buildbucket for the build status
[18:48:05.780] Build 8852492484071270352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:48:05.780] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:79:0) after 1m0s
[18:49:05.826] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:79:0)
[18:49:05.826] Timer tick, asking Buildbucket for the build status
[18:49:06.074] Build 8852492484071270352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:49:06.074] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:80:0) after 1m0s
[18:50:06.169] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:80:0)
[18:50:06.169] Timer tick, asking Buildbucket for the build status
[18:50:06.258] Build 8852492484071270352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:50:06.258] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:81:0) after 1m0s
[18:51:06.382] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:81:0)
[18:51:06.382] Timer tick, asking Buildbucket for the build status
[18:51:06.591] Build 8852492484071270352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:51:06.591] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:82:0) after 1m0s
[18:52:06.637] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:82:0)
[18:52:06.637] Timer tick, asking Buildbucket for the build status
[18:52:06.774] Build 8852492484071270352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:52:06.774] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:83:0) after 1m0s
[18:53:06.811] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:83:0)
[18:53:06.811] Timer tick, asking Buildbucket for the build status
[18:53:07.011] Build 8852492484071270352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:53:07.011] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:84:0) after 1m0s
[18:54:07.050] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:84:0)
[18:54:07.050] Timer tick, asking Buildbucket for the build status
[18:54:07.301] Build 8852492484071270352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:54:07.301] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:85:0) after 1m0s
[18:55:07.348] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:85:0)
[18:55:07.348] Timer tick, asking Buildbucket for the build status
[18:55:07.460] Build 8852492484071270352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:55:07.460] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:86:0) after 1m0s
[18:56:07.571] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:86:0)
[18:56:07.571] Timer tick, asking Buildbucket for the build status
[18:56:07.652] Build 8852492484071270352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:56:07.653] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:87:0) after 1m0s
[18:57:07.673] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:87:0)
[18:57:07.673] Timer tick, asking Buildbucket for the build status
[18:57:07.748] Build 8852492484071270352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:57:07.748] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:88:0) after 1m0s
[18:58:07.818] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:88:0)
[18:58:07.818] Timer tick, asking Buildbucket for the build status
[18:58:08.068] Build 8852492484071270352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:58:08.068] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:89:0) after 1m0s
[18:59:08.093] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:89:0)
[18:59:08.093] Timer tick, asking Buildbucket for the build status
[18:59:08.497] Build 8852492484071270352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:59:08.497] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:90:0) after 1m0s
[19:00:08.566] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:90:0)
[19:00:08.566] Timer tick, asking Buildbucket for the build status
[19:00:08.640] Build 8852492484071270352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:00:08.640] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:91:0) after 1m0s
[19:01:08.715] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:91:0)
[19:01:08.716] Timer tick, asking Buildbucket for the build status
[19:01:08.919] Build 8852492484071270352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:01:08.919] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:92:0) after 1m0s
[19:02:08.952] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:92:0)
[19:02:08.952] Timer tick, asking Buildbucket for the build status
[19:02:09.161] Build 8852492484071270352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:02:09.161] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:93:0) after 1m0s
[19:03:09.223] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:93:0)
[19:03:09.223] Timer tick, asking Buildbucket for the build status
[19:03:09.335] Build 8852492484071270352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:03:09.335] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:94:0) after 1m0s
[19:04:09.349] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:94:0)
[19:04:09.349] Timer tick, asking Buildbucket for the build status
[19:04:09.709] Build 8852492484071270352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:04:09.709] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:95:0) after 1m0s
[19:05:09.740] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:95:0)
[19:05:09.740] Timer tick, asking Buildbucket for the build status
[19:05:09.814] Build 8852492484071270352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:05:09.814] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:96:0) after 1m0s
[19:06:09.839] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:96:0)
[19:06:09.839] Timer tick, asking Buildbucket for the build status
[19:06:09.913] Build 8852492484071270352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:06:09.913] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:97:0) after 1m0s
[19:07:09.944] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:97:0)
[19:07:09.944] Timer tick, asking Buildbucket for the build status
[19:07:10.047] Build 8852492484071270352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:07:10.047] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:98:0) after 1m0s
[19:08:10.085] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:98:0)
[19:08:10.085] Timer tick, asking Buildbucket for the build status
[19:08:10.161] Build 8852492484071270352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:08:10.162] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:99:0) after 1m0s
[19:09:10.203] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:99:0)
[19:09:10.203] Timer tick, asking Buildbucket for the build status
[19:09:10.607] Build 8852492484071270352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:09:10.607] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:100:0) after 1m0s
[19:10:10.700] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:100:0)
[19:10:10.700] Timer tick, asking Buildbucket for the build status
[19:10:10.825] Build 8852492484071270352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:10:10.825] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:101:0) after 1m0s
[19:11:10.883] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:101:0)
[19:11:10.883] Timer tick, asking Buildbucket for the build status
[19:11:10.973] Build 8852492484071270352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:11:10.973] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:102:0) after 1m0s
[19:12:11.035] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:102:0)
[19:12:11.035] Timer tick, asking Buildbucket for the build status
[19:12:11.134] Build 8852492484071270352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:12:11.134] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:103:0) after 1m0s
[19:13:11.134] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:103:0)
[19:13:11.134] Timer tick, asking Buildbucket for the build status
[19:13:11.808] Build 8852492484071270352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:13:11.808] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:104:0) after 1m0s
[19:14:11.858] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:104:0)
[19:14:11.858] Timer tick, asking Buildbucket for the build status
[19:14:12.117] Build 8852492484071270352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:14:12.117] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:105:0) after 1m0s
[19:15:12.139] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:105:0)
[19:15:12.139] Timer tick, asking Buildbucket for the build status
[19:15:12.217] Build 8852492484071270352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:15:12.217] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:106:0) after 1m0s
[19:16:12.256] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:106:0)
[19:16:12.256] Timer tick, asking Buildbucket for the build status
[19:16:12.340] Build 8852492484071270352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:16:12.340] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:107:0) after 1m0s
[19:17:12.418] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:107:0)
[19:17:12.418] Timer tick, asking Buildbucket for the build status
[19:17:13.161] Build 8852492484071270352: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:17:13.161] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017922546094163376:108:0) after 1m0s
[19:17:54.138] Received PubSub notification, asking Buildbucket for the build status
[19:17:54.241] Build 8852492484071270352: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[19:17:54.241] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1616008673187249",
"created_by": "project:chromium",
"created_ts": "1616002303969663",
"id": "8852492484071270352",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"2f519680810816957f3a878a3b53fff735680046\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"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\": \"2f519680810816957f3a878a3b53fff735680046\", \"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-188-mw5o\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-rel\", \"buildnumber\": 46222, \"got_angle_revision\": \"3eace05cb270ea7f44ec1ce9dd002ebb8d4a587f\", \"got_dawn_revision\": \"3209df5a9cc2de5415688653d1142b1adda554db\", \"got_nacl_revision\": \"abf3277f295c6c87f0b103f291ed5e3e73933b76\", \"got_revision\": \"2f519680810816957f3a878a3b53fff735680046\", \"got_revision_cp\": \"refs/heads/master@{#863833}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"a98fb3f8380a3a5c9ae3e0175634ba88c85a434f\", \"got_v8_revision\": \"4f0f4e2a3c5ad9dc7312774da5197e0179c30863\", \"got_v8_revision_cp\": \"refs/heads/9.1.146@{#1}\", \"got_webrtc_revision\": \"417361423e789b798185d324241f4ac9bfd09066\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33487}\", \"is_cached\": false, \"isolate_server\": \"https://isolateserver.appspot.com\", \"mirrored_builders\": [\"tryserver.chromium.chromiumos:linux-chromeos-inverse-fieldtrials-fyi-rel\", \"tryserver.chromium.chromiumos:linux-chromeos-rel\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"2f519680810816957f3a878a3b53fff735680046\", \"swarm_hashes_refs/heads/master(at){#863833}_without_patch\": {\"absl_hardening_tests\": \"a3b32ebe0c192454fd9173a083cf5c009b62b50a\", \"accessibility_unittests\": \"f42ddbd345817253076ef0c444db4332e56aab6f\", \"angle_unittests\": \"61189a99e4d97b1aae19e74c8c5b4c7cb161a746\", \"app_list_unittests\": \"cd9bf34471da7a409278c1bc94bfff161da51a31\", \"app_shell_unittests\": \"45bca2863fcacdaa3680b1ffe97caca0d0b92502\", \"ash_unittests\": \"d680371a92f1d7f4f455014c3a350fdbd74d34fd\", \"aura_unittests\": \"c78a6845fd601eebb38e27ee7dc02d9ecc04e7e7\", \"base_unittests\": \"56c70bf5a3d609eb76699f7b3843ac197ad77c31\", \"base_util_unittests\": \"78ceeb44ea64fd50d4e9c91be89507d14e54ab73\", \"blink_common_unittests\": \"134c0d2fe270f2cb96039bd8cc068bdb8fa33948\", \"blink_fuzzer_unittests\": \"1c4f5a8717ce8f150eb3a6a0d0b1ca66004c3b7c\", \"blink_heap_unittests\": \"e48550223ec1bca75d736eabcfdc23592f6957ee\", \"blink_platform_unittests\": \"d920501d59216e1af251b7f7b5bdbd52c8576c89\", \"blink_unittests\": \"56b20bb039e5c430fa7e2c30c54b8dcf511d1c33\", \"boringssl_crypto_tests\": \"a145be11b1db1ebc7469dd4c9d1f41fcad89617d\", \"boringssl_ssl_tests\": \"6cba9e5e61dca7cda0a1c263e9f4162efc951b0b\", \"browser_tests\": \"c7e26c5ffdd4f017af8249d7624fe9f652409278\", \"capture_unittests\": \"963cb5226386e796b6057fdef602fe8dbc424068\", \"cast_unittests\": \"dd6ca3f96bc22c872ec809ff8fefd8085a69be68\", \"cc_unittests\": \"897fdfe5a43db6d21ad071838da2e1531669858b\", \"chrome_app_unittests\": \"e686bcf18da733bfa06bd741a5a87d941bfe00d0\", \"chromedriver_unittests\": \"92e62d2c046207fd2d9770cc44f81283726f01de\", \"chromeos_components_unittests\": \"1bfb6d1eeacd2f326e6889e4dbff198670b73fcb\", \"chromeos_unittests\": \"0796e5a06b9b135cc1249681c64a12e832c46277\", \"color_unittests\": \"f2e016254de63a61988f89d2eef6b279f3ce8362\", \"components_browsertests\": \"251215514f3cbff918f2c0b618a1b4f91a2ebd81\", \"components_unittests\": \"83792048dcb75fa8425965fa21a0b753a2e35546\", \"compositor_unittests\": \"487a6deb2dfc587da5c2b23d1a2a4bf9ef288b20\", \"content_browsertests\": \"957e80b2d57e1d21aa765e70d1ab73c492a52ae5\", \"content_unittests\": \"870f6904b9139f40bb7d07a26304596b5a7b90e7\", \"crashpad_tests\": \"6e0b16d86ac7c6b64a225dbe7c6d896d996bb36b\", \"crypto_unittests\": \"2eb7ef2e0bfee161f29a3995b7e4928b29012f1f\", \"dbus_unittests\": \"45b3bd069dd021745e1fb8d683e8a7e1cbb804d8\", \"device_unittests\": \"aecdff675c2b7ebee7bb411924715305fc3d1909\", \"display_unittests\": \"577613bd424cbea8e1d21570f2c4f2fe832898c5\", \"events_unittests\": \"f1f4821307090f9a6df612c228e29ce4d3f9dad6\", \"exo_unittests\": \"96c066427d7809a09b151297b58bf5c39232157e\", \"extensions_browsertests\": \"0ccf7f300261cfea3e27d397bbdcf51edd66ee34\", \"extensions_unittests\": \"87b0d3408a53c2114b2a15e21b7f8ebcfde353ce\", \"filesystem_service_unittests\": \"4d34daace545b3d20724d647b662b1be37c99750\", \"gcm_unit_tests\": \"4439b5fec6ba3c0c314ac06abd233c449315b158\", \"gfx_unittests\": \"2e14f1b59bd66ccb6e814338ad41507c8c59fa02\", \"gin_unittests\": \"259342abbec9ca5c2692144b39bdf6b3b3142361\", \"gl_unittests_ozone\": \"f0dcbead13a08287b861a37ffa9543aad283f98e\", \"google_apis_unittests\": \"08336a4879f4de3c6638e6bb928b106fd7bd79fc\", \"gpu_unittests\": \"b51c719dc586eff6327d1f8edb6557c99acab984\", \"gwp_asan_unittests\": \"10ff4cc325b7b3d923e4b813852bd56ea5b86458\", \"interactive_ui_tests\": \"6b7e41a52dfbf97b648fd98fb1e7131f0ac2f81a\", \"ipc_tests\": \"fef4652fa52598ec0f1832af3dc89becf6058537\", \"jingle_unittests\": \"a49311eb8b402097aa2cd3ed247685a1221b3c08\", \"keyboard_unittests\": \"f80e2508967b5dfcfdf5587314849e657e205458\", \"latency_unittests\": \"8942bed6cb0feb8cf608804fdc0f810d9d6aa331\", \"libjingle_xmpp_unittests\": \"6393300360d73d146a3e2246818e5c3b8bbf17d3\", \"liburlpattern_unittests\": \"4269e1b49f8ffc9b79cdd57036361c088fd39a9f\", \"media_blink_unittests\": \"8776ee1551486ce07cc66051b2b489eb153e4169\", \"media_unittests\": \"0462cacd83789eb3a296de6765c5318e599a0b40\", \"message_center_unittests\": \"e530da080c173e414a31af5db9f2cdee97655b81\", \"midi_unittests\": \"f84600fb3d9f40ff97057c539565dfab9c9e9c88\", \"mojo_core_unittests\": \"e8343b661454074908253e4977d9be6bbfc64374\", \"mojo_unittests\": \"7f52aefb572edd0b19454d0dc4ab15b973cec41d\", \"nacl_helper_nonsfi_unittests\": \"a527816f12204df55671e07dea71b86049606555\", \"nacl_loader_unittests\": \"b9fb48636cd6fc3d02cac09e892dafecad8fcd0f\", \"native_theme_unittests\": \"14f849c57beca648b340afc76bc20a8c98d18cf1\", \"net_unittests\": \"6db7b6929b13ba71020d459cf70c805dc43bfb1b\", \"ozone_gl_unittests\": \"6d63f168b36d230d6fb2135dbfc2398327841466\", \"ozone_unittests\": \"0f50d3b900f61b849eb1d5ad72d75035c85db267\", \"ozone_x11_unittests\": \"362ae0b9d2d2ff3a1f00c883dac47268a79d3bfa\", \"pdf_unittests\": \"776ffdad9e78850b98ab0ca46316de9b1916170a\", \"perfetto_unittests\": \"bb63d02325e6b7d1bad181485434f9bb6581ba0a\", \"ppapi_unittests\": \"6aad837129ace6afa87e4b1c7897a2707c1832de\", \"printing_unittests\": \"2bef0fef9cc1798d1a47291250f27032681afdd3\", \"remoting_unittests\": \"8243ef5d9b2469f75782d7b581e18dcda8e572c7\", \"sandbox_linux_unittests\": \"aeb965015e27a2235a1da735946767b40ae8420b\", \"service_manager_unittests\": \"5a524d7a64d5eb6282223bea202408a2b1d95d12\", \"services_unittests\": \"01f8e2d8418bead425b055efa01f035487cf0fb2\", \"shell_dialogs_unittests\": \"fa7149a739c07fa49ca57d6c67adcf06c2d202cf\", \"shell_encryption_unittests\": \"01f7f363ad224aef7febf77458551d92c15f3e8e\", \"skia_unittests\": \"7b85ffb11cdfd770b193308675f02910489a9105\", \"snapshot_unittests\": \"dea9512f96ac91ccd2bf02e5b34433cad43c7f4d\", \"sql_unittests\": \"c29abc8eb89f9cb76748e06a37380dbdb7ffbedf\", \"storage_unittests\": \"1013d3b3e6327f1b006ef643655a082cafe97fad\", \"sync_integration_tests\": \"e4f55e545fed8cedab774ac4bdbeabe488c12df1\", \"traffic_annotation_auditor_unittests\": \"f77057ba72c03e011c8c730ff1b1c047c6bb81c0\", \"ui_base_unittests\": \"1c393af8d50ffbaec793a02b9f8a8a1688776999\", \"ui_chromeos_unittests\": \"e80d51921bb7915891fc93b6a58c68284deea119\", \"ui_touch_selection_unittests\": \"4186e14ca6ee809231629561578afda03b54d4ac\", \"unit_tests\": \"50d62724928d6268e06b6811eb529c782413262e\", \"url_unittests\": \"6d0e8b08317a6bd4b4889351231ff4c1f0d384a2\", \"usage_time_limit_unittests\": \"5906a5d2f3268d8715fd86c12f7515fdb6351602\", \"views_unittests\": \"cf470ad3bc0b7fae48402dc5b0a0c6cd0363ba49\", \"viz_unittests\": \"b84390379722699326701dfa209ade77d2b73b90\", \"wayland_client_perftests\": \"d6ede4fc19c2030b5d92bdebe01e1243b681e373\", \"wayland_client_tests\": \"b052077c7f14087363c5bff7b641007022194008\", \"wm_unittests\": \"0cc65ab12cc914532b4488b290181c730fc57179\", \"wtf_unittests\": \"8ded1778c8ddc45218a914fa5144e74043aa3779\", \"zlib_unittests\": \"87f385f01169acfc3796ae7f2a2baf693343b7e7\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_1f6efd1a63ab00e2ded981a0276806cd2aaf7978081f3ef44b6752422059bb79_v2\", \"builder_32ded518776cda8cac391977d607693e2958c39df4b87a6f7ec8056ca6add5b1_v2\", \"builder_4a782824ba39c0c6a32b2128ebb0687e83cb58dc0cbed64e2911b02f7893cfe6_v2\", \"builder_a9a41095618141e4757e131cf792803c0a27ecf4e01eb5d0d372c002309e764f_v2\", \"builder_ce3f8692cf6150626c04c66ec3ab007d56fe2d8dc54cf437534d5f6df9aebada_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-188-mw5o\"], \"image\": [\"chrome-xenial-21011100-9488e34e558\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"e2-standard-8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16\", \"Ubuntu-16.04\", \"Ubuntu-16.04.6\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.12\"], \"server_version\": [\"5754-8c4f760\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1616002583723210",
"status": "COMPLETED",
"status_changed_ts": "1616008673663014",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/46222",
"builder:linux-chromeos-rel",
"buildset:commit/git/2f519680810816957f3a878a3b53fff735680046",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/2f519680810816957f3a878a3b53fff735680046",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9017922546094163376",
"scheduler_job_id:chromium/linux-chromeos-rel",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8852492484071270352/+/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:525a0fd86601ff10",
"user_agent:luci-scheduler"
],
"updated_ts": "1616008673667846",
"url": "https://ci.chromium.org/b/8852492484071270352",
"utcnow_ts": "1616008674227537"
}
}
[19:17:54.241] Invocation finished in 1h46m11.58354882s with status SUCCEEDED