[16:05:54.876] New invocation is queued and will start shortly
[16:05:56.073] Starting the invocation (attempt 1)
[16:05:56.240] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[16:05:56.240] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[16:05:56.240] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9020645852941325104",
"parameters_json": "{\"builder_name\":\"linux-chromeos-rel\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"edb1e8d86e9c1d1e7d9ae94b6171526284f23680\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-rel",
"scheduler_invocation_id:9020645852941325104",
"scheduler_job_id:chromium/linux-chromeos-rel",
"user_agent:luci-scheduler",
"buildset:commit/git/edb1e8d86e9c1d1e7d9ae94b6171526284f23680",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/edb1e8d86e9c1d1e7d9ae94b6171526284f23680",
"gitiles_ref:refs/heads/master"
]
}
[16:05:58.226] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1613405156622597",
"id": "8855215790447778528",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"edb1e8d86e9c1d1e7d9ae94b6171526284f23680\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1613405157796221",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/45431",
"builder:linux-chromeos-rel",
"buildset:commit/git/edb1e8d86e9c1d1e7d9ae94b6171526284f23680",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/edb1e8d86e9c1d1e7d9ae94b6171526284f23680",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9020645852941325104",
"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/8855215790447778528/+/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": "1613405157798266",
"url": "https://ci.chromium.org/b/8855215790447778528",
"utcnow_ts": "1613405158194617"
}
}
[16:05:58.226] Task URL: https://ci.chromium.org/b/8855215790447778528
[16:05:58.226] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020645852941325104:2:0) after 1m0s
[16:06:38.851] Received PubSub notification, asking Buildbucket for the build status
[16:06:38.922] Build 8855215790447778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:06:58.278] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020645852941325104:2:0)
[16:06:58.278] Timer tick, asking Buildbucket for the build status
[16:06:58.396] Build 8855215790447778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:06:58.396] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020645852941325104:4:0) after 1m0s
[16:07:58.422] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020645852941325104:4:0)
[16:07:58.422] Timer tick, asking Buildbucket for the build status
[16:07:58.533] Build 8855215790447778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:07:58.533] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020645852941325104:5:0) after 1m0s
[16:08:58.630] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020645852941325104:5:0)
[16:08:58.630] Timer tick, asking Buildbucket for the build status
[16:08:58.714] Build 8855215790447778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:08:58.714] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020645852941325104:6:0) after 1m0s
[16:09:58.869] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020645852941325104:6:0)
[16:09:58.869] Timer tick, asking Buildbucket for the build status
[16:09:58.950] Build 8855215790447778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:09:58.950] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020645852941325104:7:0) after 1m0s
[16:10:59.102] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020645852941325104:7:0)
[16:10:59.102] Timer tick, asking Buildbucket for the build status
[16:10:59.340] Build 8855215790447778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:10:59.341] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020645852941325104:8:0) after 1m0s
[16:11:59.433] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020645852941325104:8:0)
[16:11:59.433] Timer tick, asking Buildbucket for the build status
[16:11:59.606] Build 8855215790447778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:11:59.606] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020645852941325104:9:0) after 1m0s
[16:12:59.821] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020645852941325104:9:0)
[16:12:59.821] Timer tick, asking Buildbucket for the build status
[16:12:59.905] Build 8855215790447778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:12:59.905] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020645852941325104:10:0) after 1m0s
[16:13:59.948] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020645852941325104:10:0)
[16:13:59.948] Timer tick, asking Buildbucket for the build status
[16:14:00.243] Build 8855215790447778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:14:00.243] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020645852941325104:11:0) after 1m0s
[16:15:00.298] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020645852941325104:11:0)
[16:15:00.298] Timer tick, asking Buildbucket for the build status
[16:15:00.388] Build 8855215790447778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:15:00.388] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020645852941325104:12:0) after 1m0s
[16:16:00.437] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020645852941325104:12:0)
[16:16:00.437] Timer tick, asking Buildbucket for the build status
[16:16:00.521] Build 8855215790447778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:16:00.521] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020645852941325104:13:0) after 1m0s
[16:17:00.656] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020645852941325104:13:0)
[16:17:00.656] Timer tick, asking Buildbucket for the build status
[16:17:00.790] Build 8855215790447778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:17:00.790] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020645852941325104:14:0) after 1m0s
[16:18:02.081] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020645852941325104:14:0)
[16:18:02.081] Timer tick, asking Buildbucket for the build status
[16:18:02.389] Build 8855215790447778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:18:02.389] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020645852941325104:15:0) after 1m0s
[16:19:02.485] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020645852941325104:15:0)
[16:19:02.485] Timer tick, asking Buildbucket for the build status
[16:19:02.572] Build 8855215790447778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:19:02.572] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020645852941325104:16:0) after 1m0s
[16:20:02.863] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020645852941325104:16:0)
[16:20:02.863] Timer tick, asking Buildbucket for the build status
[16:20:03.017] Build 8855215790447778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:20:03.017] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020645852941325104:17:0) after 1m0s
[16:21:03.435] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020645852941325104:17:0)
[16:21:03.435] Timer tick, asking Buildbucket for the build status
[16:21:03.563] Build 8855215790447778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:21:03.563] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020645852941325104:18:0) after 1m0s
[16:22:03.555] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020645852941325104:18:0)
[16:22:03.555] Timer tick, asking Buildbucket for the build status
[16:22:03.657] Build 8855215790447778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:22:03.657] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020645852941325104:19:0) after 1m0s
[16:23:03.760] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020645852941325104:19:0)
[16:23:03.760] Timer tick, asking Buildbucket for the build status
[16:23:03.946] Build 8855215790447778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:23:03.946] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020645852941325104:20:0) after 1m0s
[16:24:03.990] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020645852941325104:20:0)
[16:24:03.990] Timer tick, asking Buildbucket for the build status
[16:24:04.306] Build 8855215790447778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:24:04.306] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020645852941325104:21:0) after 1m0s
[16:25:04.333] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020645852941325104:21:0)
[16:25:04.333] Timer tick, asking Buildbucket for the build status
[16:25:04.416] Build 8855215790447778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:25:04.416] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020645852941325104:22:0) after 1m0s
[16:26:04.833] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020645852941325104:22:0)
[16:26:04.833] Timer tick, asking Buildbucket for the build status
[16:26:04.999] Build 8855215790447778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:26:04.999] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020645852941325104:23:0) after 1m0s
[16:27:05.124] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020645852941325104:23:0)
[16:27:05.124] Timer tick, asking Buildbucket for the build status
[16:27:05.200] Build 8855215790447778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:27:05.200] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020645852941325104:24:0) after 1m0s
[16:28:05.260] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020645852941325104:24:0)
[16:28:05.260] Timer tick, asking Buildbucket for the build status
[16:28:05.403] Build 8855215790447778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:28:05.403] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020645852941325104:25:0) after 1m0s
[16:29:05.580] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020645852941325104:25:0)
[16:29:05.580] Timer tick, asking Buildbucket for the build status
[16:29:05.672] Build 8855215790447778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:29:05.672] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020645852941325104:26:0) after 1m0s
[16:30:05.850] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020645852941325104:26:0)
[16:30:05.850] Timer tick, asking Buildbucket for the build status
[16:30:06.001] Build 8855215790447778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:30:06.001] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020645852941325104:27:0) after 1m0s
[16:31:06.207] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020645852941325104:27:0)
[16:31:06.207] Timer tick, asking Buildbucket for the build status
[16:31:06.605] Build 8855215790447778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:31:06.605] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020645852941325104:28:0) after 1m0s
[16:32:06.776] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020645852941325104:28:0)
[16:32:06.776] Timer tick, asking Buildbucket for the build status
[16:32:06.863] Build 8855215790447778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:32:06.863] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020645852941325104:29:0) after 1m0s
[16:33:06.865] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020645852941325104:29:0)
[16:33:06.865] Timer tick, asking Buildbucket for the build status
[16:33:07.009] Build 8855215790447778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:33:07.009] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020645852941325104:30:0) after 1m0s
[16:34:07.140] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020645852941325104:30:0)
[16:34:07.140] Timer tick, asking Buildbucket for the build status
[16:34:07.222] Build 8855215790447778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:34:07.222] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020645852941325104:31:0) after 1m0s
[16:35:07.261] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020645852941325104:31:0)
[16:35:07.261] Timer tick, asking Buildbucket for the build status
[16:35:07.363] Build 8855215790447778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:35:07.363] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020645852941325104:32:0) after 1m0s
[16:36:07.426] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020645852941325104:32:0)
[16:36:07.426] Timer tick, asking Buildbucket for the build status
[16:36:07.538] Build 8855215790447778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:36:07.538] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020645852941325104:33:0) after 1m0s
[16:37:07.551] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020645852941325104:33:0)
[16:37:07.551] Timer tick, asking Buildbucket for the build status
[16:37:07.640] Build 8855215790447778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:37:07.640] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020645852941325104:34:0) after 1m0s
[16:38:07.666] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020645852941325104:34:0)
[16:38:07.666] Timer tick, asking Buildbucket for the build status
[16:38:07.756] Build 8855215790447778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:38:07.756] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020645852941325104:35:0) after 1m0s
[16:39:07.736] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020645852941325104:35:0)
[16:39:07.736] Timer tick, asking Buildbucket for the build status
[16:39:07.827] Build 8855215790447778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:39:07.827] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020645852941325104:36:0) after 1m0s
[16:40:07.885] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020645852941325104:36:0)
[16:40:07.885] Timer tick, asking Buildbucket for the build status
[16:40:07.968] Build 8855215790447778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:40:07.968] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020645852941325104:37:0) after 1m0s
[16:41:08.051] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020645852941325104:37:0)
[16:41:08.051] Timer tick, asking Buildbucket for the build status
[16:41:08.245] Build 8855215790447778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:41:08.245] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020645852941325104:38:0) after 1m0s
[16:42:08.260] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020645852941325104:38:0)
[16:42:08.260] Timer tick, asking Buildbucket for the build status
[16:42:08.331] Build 8855215790447778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:42:08.331] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020645852941325104:39:0) after 1m0s
[16:43:08.392] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020645852941325104:39:0)
[16:43:08.392] Timer tick, asking Buildbucket for the build status
[16:43:08.645] Build 8855215790447778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:43:08.645] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020645852941325104:40:0) after 1m0s
[16:44:08.692] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020645852941325104:40:0)
[16:44:08.692] Timer tick, asking Buildbucket for the build status
[16:44:08.796] Build 8855215790447778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:44:08.796] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020645852941325104:41:0) after 1m0s
[16:45:08.819] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020645852941325104:41:0)
[16:45:08.819] Timer tick, asking Buildbucket for the build status
[16:45:09.092] Build 8855215790447778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:45:09.092] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020645852941325104:42:0) after 1m0s
[16:46:09.156] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020645852941325104:42:0)
[16:46:09.156] Timer tick, asking Buildbucket for the build status
[16:46:09.644] Build 8855215790447778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:46:09.644] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020645852941325104:43:0) after 1m0s
[16:47:09.688] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020645852941325104:43:0)
[16:47:09.688] Timer tick, asking Buildbucket for the build status
[16:47:09.779] Build 8855215790447778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:47:09.779] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020645852941325104:44:0) after 1m0s
[16:48:09.799] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020645852941325104:44:0)
[16:48:09.799] Timer tick, asking Buildbucket for the build status
[16:48:09.879] Build 8855215790447778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:48:09.879] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020645852941325104:45:0) after 1m0s
[16:49:10.141] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020645852941325104:45:0)
[16:49:10.141] Timer tick, asking Buildbucket for the build status
[16:49:10.281] Build 8855215790447778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:49:10.281] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020645852941325104:46:0) after 1m0s
[16:50:10.402] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020645852941325104:46:0)
[16:50:10.402] Timer tick, asking Buildbucket for the build status
[16:50:10.483] Build 8855215790447778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:50:10.483] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020645852941325104:47:0) after 1m0s
[16:51:10.608] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020645852941325104:47:0)
[16:51:10.608] Timer tick, asking Buildbucket for the build status
[16:51:10.695] Build 8855215790447778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:51:10.695] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020645852941325104:48:0) after 1m0s
[16:52:10.848] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020645852941325104:48:0)
[16:52:10.848] Timer tick, asking Buildbucket for the build status
[16:52:10.948] Build 8855215790447778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:52:10.948] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020645852941325104:49:0) after 1m0s
[16:53:11.145] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020645852941325104:49:0)
[16:53:11.145] Timer tick, asking Buildbucket for the build status
[16:53:11.238] Build 8855215790447778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:53:11.238] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020645852941325104:50:0) after 1m0s
[16:54:11.378] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020645852941325104:50:0)
[16:54:11.378] Timer tick, asking Buildbucket for the build status
[16:54:11.668] Build 8855215790447778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:54:11.668] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020645852941325104:51:0) after 1m0s
[16:55:11.756] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020645852941325104:51:0)
[16:55:11.756] Timer tick, asking Buildbucket for the build status
[16:55:11.843] Build 8855215790447778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:55:11.843] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020645852941325104:52:0) after 1m0s
[16:56:11.914] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020645852941325104:52:0)
[16:56:11.914] Timer tick, asking Buildbucket for the build status
[16:56:12.013] Build 8855215790447778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:56:12.013] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020645852941325104:53:0) after 1m0s
[16:57:12.166] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020645852941325104:53:0)
[16:57:12.166] Timer tick, asking Buildbucket for the build status
[16:57:13.550] Build 8855215790447778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:57:13.550] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020645852941325104:54:0) after 1m0s
[16:58:13.561] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020645852941325104:54:0)
[16:58:13.561] Timer tick, asking Buildbucket for the build status
[16:58:13.638] Build 8855215790447778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:58:13.638] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020645852941325104:55:0) after 1m0s
[16:59:13.774] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020645852941325104:55:0)
[16:59:13.774] Timer tick, asking Buildbucket for the build status
[16:59:13.893] Build 8855215790447778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:59:13.893] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020645852941325104:56:0) after 1m0s
[17:00:14.013] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020645852941325104:56:0)
[17:00:14.013] Timer tick, asking Buildbucket for the build status
[17:00:14.496] Build 8855215790447778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:00:14.496] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020645852941325104:57:0) after 1m0s
[17:01:14.660] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020645852941325104:57:0)
[17:01:14.660] Timer tick, asking Buildbucket for the build status
[17:01:14.808] Build 8855215790447778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:01:14.808] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020645852941325104:58:0) after 1m0s
[17:02:14.855] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020645852941325104:58:0)
[17:02:14.855] Timer tick, asking Buildbucket for the build status
[17:02:14.961] Build 8855215790447778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:02:14.961] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020645852941325104:59:0) after 1m0s
[17:03:15.045] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020645852941325104:59:0)
[17:03:15.045] Timer tick, asking Buildbucket for the build status
[17:03:15.306] Build 8855215790447778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:03:15.306] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020645852941325104:60:0) after 1m0s
[17:04:15.374] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020645852941325104:60:0)
[17:04:15.374] Timer tick, asking Buildbucket for the build status
[17:04:15.482] Build 8855215790447778528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:04:15.482] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9020645852941325104:61:0) after 1m0s
[17:04:38.691] Received PubSub notification, asking Buildbucket for the build status
[17:04:38.784] Build 8855215790447778528: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[17:04:38.785] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1613408677281243",
"created_by": "project:chromium",
"created_ts": "1613405156622597",
"id": "8855215790447778528",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"edb1e8d86e9c1d1e7d9ae94b6171526284f23680\"}}",
"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\": \"edb1e8d86e9c1d1e7d9ae94b6171526284f23680\", \"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-46-gttn\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-rel\", \"buildnumber\": 45431, \"got_angle_revision\": \"a8a2a71b3ab5e535def6239997f6f24da918556b\", \"got_dawn_revision\": \"e61c9d86884145733d5763ab23c15bf09acf358c\", \"got_nacl_revision\": \"8a92e6ba1b6d62631f8d3ba3f4f8872ae8b6cca1\", \"got_revision\": \"edb1e8d86e9c1d1e7d9ae94b6171526284f23680\", \"got_revision_cp\": \"refs/heads/master@{#854052}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"539ef8e9426e10d7611d433ee60b94de0737344a\", \"got_v8_revision\": \"ebacb645b20531cd78dc088730fb72269822eeb6\", \"got_v8_revision_cp\": \"refs/heads/9.0.172@{#2}\", \"got_webrtc_revision\": \"a24f3d035f7d9acee551d5547f4fb16838cf98d6\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33258}\", \"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\": \"edb1e8d86e9c1d1e7d9ae94b6171526284f23680\", \"swarm_hashes_refs/heads/master(at){#854052}_without_patch\": {\"absl_hardening_tests\": \"da9b2fb411fe3bc3257ea956d5510b798325a542\", \"accessibility_unittests\": \"5acddeeea900df3a6818eac688305afbed0b3710\", \"angle_unittests\": \"4209db2d5c615ec922dcd8cda0eeb9b6eb20f892\", \"app_list_unittests\": \"dd95707f8b912560608e7b1422ce4df19d1ab679\", \"app_shell_unittests\": \"1df7c4bff5fbb0f3b00434742f89961c898f6e66\", \"ash_unittests\": \"a3bd9616f20345d9f9ef450239db415a521fc92b\", \"aura_unittests\": \"7c5d2dc2321f7407f4e776ed49c524a49ebd6b3a\", \"base_unittests\": \"846dafdbfe332f7fc8c0dc9db5a40c5d9aa8fc27\", \"base_util_unittests\": \"9f2625b4f21434dbfe800ac69a9ad6daf7c695d0\", \"blink_common_unittests\": \"4c4d93d4ba2788ca137c118a38475b8232ccfb70\", \"blink_fuzzer_unittests\": \"0f5a52cc556ab63cd373ffd7294a6b9b2e1c3c00\", \"blink_heap_unittests\": \"5ca036a7466907397887fccadf5402db44e17a27\", \"blink_platform_unittests\": \"11be5f09ca05514a379efa59db8f9f72e95bba99\", \"blink_unittests\": \"4db27bbabcffa1be2e5da57f0bc04fbdd7b5400f\", \"boringssl_crypto_tests\": \"24957053378da4dc9f3486ad96558713bb0ce20f\", \"boringssl_ssl_tests\": \"870c831930e434835010dd5708208fb1b8e2d8d9\", \"browser_tests\": \"974a0814a046cb6a2755acd308b5e409475ca372\", \"capture_unittests\": \"027ef4ada37529e312455738f6495e83c3211c7a\", \"cast_unittests\": \"616af3bdcfd64f549690ba718ba4d39891753b2c\", \"cc_unittests\": \"6106d8fad80beb857449848c0b5434307d392c72\", \"chrome_app_unittests\": \"991cc2456e769b1426a6c271a2e227843bdae06c\", \"chromedriver_unittests\": \"5ac35b1affb3364e4dfb4f402c955c4e6f1d6241\", \"chromeos_components_unittests\": \"372830d5f06cbb1fc65ec82cec7d755bc86af632\", \"chromeos_unittests\": \"a1c2fbd0dde3b7d0d0e1455b3cec775828b16d3b\", \"components_browsertests\": \"221f41cda2bda28f5454431e2688d704715c947c\", \"components_unittests\": \"07189760f0c43b88354e04f8b08f5b31bde44fd1\", \"compositor_unittests\": \"28ae6ab181aa94c30ae58b0988e712a366d4041b\", \"content_browsertests\": \"7d6826bdc35bf8e41010a7f51ec98241d8d1d5fa\", \"content_unittests\": \"92f14654817fc6e1efec78baff9f5345f9806110\", \"crashpad_tests\": \"3917eb46eeef7f7c2a2eb77efb4cce4efdb5bcd3\", \"crypto_unittests\": \"03bc7fc0db9920f9780d6b53ba040a0045a6980f\", \"dbus_unittests\": \"a87bff0dde5d7e5a14bf28eb07c5d3950d70435d\", \"device_unittests\": \"3c351b3a9fd530dfbf985cf88a32fa48278b0128\", \"display_unittests\": \"ff4db0e26f7a3517bf8bf163a4e8be54d5eb6fbb\", \"events_unittests\": \"e1be3ab9d4305d204debb0d00dfe4be20ba554a4\", \"exo_unittests\": \"7c497bab8ab12e60eb7f7c448ce64e4c6993baa4\", \"extensions_browsertests\": \"4c52efc49a8e8e6ec082c93cfa87004ec5c16d07\", \"extensions_unittests\": \"451d187c9ba6e6ce99e0daf70151a78fb4f01f7c\", \"filesystem_service_unittests\": \"c73fd39a08e34b0acbb456d83436a322d39c8762\", \"gcm_unit_tests\": \"b516e7d0be2940180a6cb3f417d3ae233f52b774\", \"gfx_unittests\": \"9b195e3a7973d94d9236644892db7f66011166ac\", \"gin_unittests\": \"88df55f33d179ca7580aba624e55c5c3c3767b13\", \"gl_unittests_ozone\": \"777bd71e169a7519d56dd79fd98fe21bf447e9eb\", \"google_apis_unittests\": \"ba1cfe9e9a5f575dd226a4248ac53310344db190\", \"gpu_unittests\": \"4e8249b902944318fc597e856638fc0a6cb8da03\", \"gwp_asan_unittests\": \"b092f4b004cdc9353637c31378ce7feb5cd01fd2\", \"interactive_ui_tests\": \"70b0cfeebb4fd5abc85c58ed5738beb1c89612a8\", \"ipc_tests\": \"4f7eea6c6e0fc8df8b16120803ac05655b48230f\", \"jingle_unittests\": \"49e4328a44ff76e6870753361e17f9c345c3e45b\", \"keyboard_unittests\": \"64919ab71028ea1309bd45a3a9c2dce170495aa0\", \"latency_unittests\": \"86afcbffbceefd4e313216812b3ddd12481754da\", \"libjingle_xmpp_unittests\": \"93bc1576d404a27112e6d67eb5b6fc115ee7799c\", \"liburlpattern_unittests\": \"607279cdf42e8239103863d029609e168e8d2d52\", \"media_blink_unittests\": \"bcc77f1f4d820cca769c0651a4f98147375a7f60\", \"media_unittests\": \"8adfc7ef9038e9d8088618a52619652683f27348\", \"message_center_unittests\": \"fe7df255c172e261a95a2bccf231ffdf6dd8ea6f\", \"midi_unittests\": \"9da667fd40f04d5bdfc9604f856ed5184ded1ed2\", \"mojo_core_unittests\": \"fdcfc6d82b7fe14643ed2fb8b0d094a8eba4fba8\", \"mojo_unittests\": \"5cdf9b3ef9eb5ae5513f9cb22294de2cd940ccf3\", \"nacl_helper_nonsfi_unittests\": \"5394d16e22e12df85a32d9090ab89ff429393b3d\", \"nacl_loader_unittests\": \"7cc1b40ae3bd81d69f32f5b941cb2dd61621c00f\", \"native_theme_unittests\": \"3a343a7f512f0448178ca877d817cb65638b5567\", \"net_unittests\": \"e63197844c203a46635a4c798d35e6d705ae9e85\", \"ozone_gl_unittests\": \"7beba6c75f79f680488e1b2c6d22401ae96fa49b\", \"ozone_unittests\": \"475f685ba9bd61a79315f9b20b06f4a4170f7ff6\", \"ozone_x11_unittests\": \"cdaa5f01189a546036215e3e550fd0eb0e18a609\", \"pdf_unittests\": \"dc24642e0010565a74eb93dbcb176a35ef9cef8a\", \"perfetto_unittests\": \"cf5c50e6d17ced0aa244bf394b6ac4283cfc47ef\", \"ppapi_unittests\": \"6b8e5d27310b6de7ac6555aa44d6c658f88265b3\", \"printing_unittests\": \"bbaab5cfde661f744cfb74d1d3d2d72423959e57\", \"remoting_unittests\": \"2f5e9403f1588660f1268e496d40c63e0e97bd58\", \"sandbox_linux_unittests\": \"c24f3b7abeaa3d4698a3d872614507b108aadf1b\", \"service_manager_unittests\": \"ade41a12472ed3d2e2a46faad6084d8ef15c0e86\", \"services_unittests\": \"898b2e3f3817b6d42306d9ee98dd26777ffe3689\", \"shell_dialogs_unittests\": \"8e2fe99a1dd83bb19eb0909738d849c9fde84901\", \"shell_encryption_unittests\": \"a5570fe4e4acc822b469c8fa776f09db764253ed\", \"skia_unittests\": \"870b4a00905eeda3b2e5ee96e333ac48bc6cd05c\", \"snapshot_unittests\": \"742c69d2c327f83a0fe54e75ddc3309fe3616788\", \"sql_unittests\": \"aca7fd358678b4c584ce079df8f89f3ec5867777\", \"storage_unittests\": \"5660f12609457ca0a92a5391fc62951fe582ee1d\", \"sync_integration_tests\": \"feb4451a8dcc0e3277cbe96754520cf28a85a3a9\", \"traffic_annotation_auditor_unittests\": \"979ed14280fb852b0fb179700c3405fd6d9e8361\", \"ui_base_unittests\": \"e148b6453314d45908a5f3c8931a21bbfed112fc\", \"ui_chromeos_unittests\": \"f575b1e7256831f328d74922f1824a52bc781098\", \"ui_touch_selection_unittests\": \"7388367a99e8738cf0dbd656989c979e357f2a94\", \"unit_tests\": \"d13ee121c117d19e045ce31f91b2daebf2bb835f\", \"url_unittests\": \"6973d9784fe53ae9cd3142d4bd10d612d7ce90d7\", \"usage_time_limit_unittests\": \"b4b5061ef1934b03504a2426b10735daa4c5126b\", \"views_unittests\": \"f362d47378cf43979e734a1add61eeb5f3cd39c3\", \"viz_unittests\": \"8ff499d265014f4f108e3508ab65ce251e186c55\", \"wayland_client_perftests\": \"31b26035b5c89e59047f2b653bea7175c101bc19\", \"wm_unittests\": \"e70b3465bf9014a40c27a0f00079a70345284bdb\", \"wtf_unittests\": \"2ee49e3af0ce6527fe5035b222683c5bfe0f3402\", \"zlib_unittests\": \"a8f4485d114f0347a162106d247151bcc274cba1\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_45c5b54513fd8d251ddf48af87bd70552077e6f51953669d04eaa4296b96a344_v2\", \"builder_61626c7085ee1f9730db9e25e8ceef3d7b395de70268d432aed853b8a16eb295_v2\", \"builder_800f6f487d95d787b5e050c93b44c7c7276a85aefb5f9344834f1f03ffd0e322_v2\", \"builder_94fa3119a28f4651cdd4fc5b981a64c69b26c151212072333439362290628993_v2\", \"builder_fac2959e29eeee13eb43de2791ff2fcb7aa7ab15952f0beca21f4615b55fa2a2_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-xenial-us-central1-b-8-46-gttn\"], \"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\": [\"5696-2a606bc\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1613405198175816",
"status": "COMPLETED",
"status_changed_ts": "1613408678048952",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/45431",
"builder:linux-chromeos-rel",
"buildset:commit/git/edb1e8d86e9c1d1e7d9ae94b6171526284f23680",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/edb1e8d86e9c1d1e7d9ae94b6171526284f23680",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9020645852941325104",
"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/8855215790447778528/+/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:51bf4282bb33ff10",
"user_agent:luci-scheduler"
],
"updated_ts": "1613408678058674",
"url": "https://ci.chromium.org/b/8855215790447778528",
"utcnow_ts": "1613408678766161"
}
}
[17:04:38.786] Invocation finished in 58m43.925231832s with status SUCCEEDED