[11:08:12.491] New invocation is queued and will start shortly
[11:08:13.564] Starting the invocation (attempt 1)
[11:08:13.605] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[11:08:13.607] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[11:08:13.607] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9028184131252732272",
"parameters_json": "{\"builder_name\":\"linux-chromeos-rel\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"38bc3423773325414081578b544ea6711a26dec3\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-rel",
"scheduler_invocation_id:9028184131252732272",
"scheduler_job_id:chromium/linux-chromeos-rel",
"user_agent:luci-scheduler",
"buildset:commit/git/38bc3423773325414081578b544ea6711a26dec3",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/38bc3423773325414081578b544ea6711a26dec3",
"gitiles_ref:refs/heads/master"
]
}
[11:08:14.345] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1606216093719979",
"id": "8862754069270562832",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"38bc3423773325414081578b544ea6711a26dec3\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1606216094197800",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/43121",
"builder:linux-chromeos-rel",
"buildset:commit/git/38bc3423773325414081578b544ea6711a26dec3",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/38bc3423773325414081578b544ea6711a26dec3",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9028184131252732272",
"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/8862754069270562832/+/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": "1606216094197909",
"url": "https://ci.chromium.org/b/8862754069270562832",
"utcnow_ts": "1606216094328128"
}
}
[11:08:14.345] Task URL: https://ci.chromium.org/b/8862754069270562832
[11:08:14.345] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028184131252732272:2:0) after 1m0s
[11:08:15.674] Received PubSub notification, asking Buildbucket for the build status
[11:08:16.062] Build 8862754069270562832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:09:14.531] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028184131252732272:2:0)
[11:09:14.531] Timer tick, asking Buildbucket for the build status
[11:09:14.656] Build 8862754069270562832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:09:14.656] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028184131252732272:4:0) after 1m0s
[11:10:14.782] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028184131252732272:4:0)
[11:10:14.782] Timer tick, asking Buildbucket for the build status
[11:10:14.855] Build 8862754069270562832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:10:14.855] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028184131252732272:5:0) after 1m0s
[11:11:14.934] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028184131252732272:5:0)
[11:11:14.934] Timer tick, asking Buildbucket for the build status
[11:11:15.047] Build 8862754069270562832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:11:15.047] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028184131252732272:6:0) after 1m0s
[11:12:15.186] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028184131252732272:6:0)
[11:12:15.186] Timer tick, asking Buildbucket for the build status
[11:12:15.328] Build 8862754069270562832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:12:15.328] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028184131252732272:7:0) after 1m0s
[11:13:15.414] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028184131252732272:7:0)
[11:13:15.414] Timer tick, asking Buildbucket for the build status
[11:13:15.537] Build 8862754069270562832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:13:15.537] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028184131252732272:8:0) after 1m0s
[11:14:15.612] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028184131252732272:8:0)
[11:14:15.612] Timer tick, asking Buildbucket for the build status
[11:14:15.722] Build 8862754069270562832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:14:15.722] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028184131252732272:9:0) after 1m0s
[11:15:15.953] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028184131252732272:9:0)
[11:15:15.953] Timer tick, asking Buildbucket for the build status
[11:15:16.046] Build 8862754069270562832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:15:16.046] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028184131252732272:10:0) after 1m0s
[11:16:16.214] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028184131252732272:10:0)
[11:16:16.214] Timer tick, asking Buildbucket for the build status
[11:16:16.325] Build 8862754069270562832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:16:16.325] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028184131252732272:11:0) after 1m0s
[11:17:16.528] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028184131252732272:11:0)
[11:17:16.528] Timer tick, asking Buildbucket for the build status
[11:17:16.819] Build 8862754069270562832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:17:16.819] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028184131252732272:12:0) after 1m0s
[11:18:16.860] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028184131252732272:12:0)
[11:18:16.860] Timer tick, asking Buildbucket for the build status
[11:18:16.980] Build 8862754069270562832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:18:16.980] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028184131252732272:13:0) after 1m0s
[11:19:16.953] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028184131252732272:13:0)
[11:19:16.953] Timer tick, asking Buildbucket for the build status
[11:19:17.023] Build 8862754069270562832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:19:17.023] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028184131252732272:14:0) after 1m0s
[11:20:17.064] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028184131252732272:14:0)
[11:20:17.064] Timer tick, asking Buildbucket for the build status
[11:20:17.152] Build 8862754069270562832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:20:17.152] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028184131252732272:15:0) after 1m0s
[11:21:17.212] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028184131252732272:15:0)
[11:21:17.212] Timer tick, asking Buildbucket for the build status
[11:21:17.497] Build 8862754069270562832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:21:17.497] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028184131252732272:16:0) after 1m0s
[11:22:17.515] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028184131252732272:16:0)
[11:22:17.515] Timer tick, asking Buildbucket for the build status
[11:22:17.569] Build 8862754069270562832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:22:17.569] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028184131252732272:17:0) after 1m0s
[11:23:17.673] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028184131252732272:17:0)
[11:23:17.673] Timer tick, asking Buildbucket for the build status
[11:23:17.792] Build 8862754069270562832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:23:17.792] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028184131252732272:18:0) after 1m0s
[11:24:17.851] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028184131252732272:18:0)
[11:24:17.851] Timer tick, asking Buildbucket for the build status
[11:24:17.901] Build 8862754069270562832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:24:17.901] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028184131252732272:19:0) after 1m0s
[11:25:17.961] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028184131252732272:19:0)
[11:25:17.961] Timer tick, asking Buildbucket for the build status
[11:25:18.024] Build 8862754069270562832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:25:18.024] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028184131252732272:20:0) after 1m0s
[11:26:18.129] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028184131252732272:20:0)
[11:26:18.129] Timer tick, asking Buildbucket for the build status
[11:26:18.206] Build 8862754069270562832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:26:18.206] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028184131252732272:21:0) after 1m0s
[11:27:18.253] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028184131252732272:21:0)
[11:27:18.253] Timer tick, asking Buildbucket for the build status
[11:27:18.384] Build 8862754069270562832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:27:18.384] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028184131252732272:22:0) after 1m0s
[11:28:18.459] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028184131252732272:22:0)
[11:28:18.459] Timer tick, asking Buildbucket for the build status
[11:28:18.559] Build 8862754069270562832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:28:18.559] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028184131252732272:23:0) after 1m0s
[11:29:18.602] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028184131252732272:23:0)
[11:29:18.602] Timer tick, asking Buildbucket for the build status
[11:29:18.663] Build 8862754069270562832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:29:18.663] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028184131252732272:24:0) after 1m0s
[11:30:18.735] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028184131252732272:24:0)
[11:30:18.735] Timer tick, asking Buildbucket for the build status
[11:30:18.804] Build 8862754069270562832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:30:18.804] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028184131252732272:25:0) after 1m0s
[11:31:18.831] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028184131252732272:25:0)
[11:31:18.831] Timer tick, asking Buildbucket for the build status
[11:31:18.906] Build 8862754069270562832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:31:18.906] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028184131252732272:26:0) after 1m0s
[11:32:18.991] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028184131252732272:26:0)
[11:32:18.991] Timer tick, asking Buildbucket for the build status
[11:32:19.111] Build 8862754069270562832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:32:19.111] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028184131252732272:27:0) after 1m0s
[11:33:19.230] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028184131252732272:27:0)
[11:33:19.230] Timer tick, asking Buildbucket for the build status
[11:33:19.582] Build 8862754069270562832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:33:19.582] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028184131252732272:28:0) after 1m0s
[11:34:19.643] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028184131252732272:28:0)
[11:34:19.643] Timer tick, asking Buildbucket for the build status
[11:34:19.708] Build 8862754069270562832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:34:19.708] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028184131252732272:29:0) after 1m0s
[11:35:20.876] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028184131252732272:29:0)
[11:35:20.876] Timer tick, asking Buildbucket for the build status
[11:35:20.938] Build 8862754069270562832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:35:20.938] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028184131252732272:30:0) after 1m0s
[11:36:21.772] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028184131252732272:30:0)
[11:36:21.772] Timer tick, asking Buildbucket for the build status
[11:36:22.043] Build 8862754069270562832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:36:22.043] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028184131252732272:31:0) after 1m0s
[11:37:22.269] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028184131252732272:31:0)
[11:37:22.270] Timer tick, asking Buildbucket for the build status
[11:37:22.348] Build 8862754069270562832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:37:22.348] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028184131252732272:32:0) after 1m0s
[11:38:22.395] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028184131252732272:32:0)
[11:38:22.395] Timer tick, asking Buildbucket for the build status
[11:38:22.498] Build 8862754069270562832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:38:22.498] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028184131252732272:33:0) after 1m0s
[11:39:22.563] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028184131252732272:33:0)
[11:39:22.563] Timer tick, asking Buildbucket for the build status
[11:39:22.704] Build 8862754069270562832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:39:22.704] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028184131252732272:34:0) after 1m0s
[11:40:22.880] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028184131252732272:34:0)
[11:40:22.880] Timer tick, asking Buildbucket for the build status
[11:40:22.955] Build 8862754069270562832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:40:22.955] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028184131252732272:35:0) after 1m0s
[11:41:23.123] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028184131252732272:35:0)
[11:41:23.123] Timer tick, asking Buildbucket for the build status
[11:41:23.326] Build 8862754069270562832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:41:23.326] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028184131252732272:36:0) after 1m0s
[11:42:23.304] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028184131252732272:36:0)
[11:42:23.304] Timer tick, asking Buildbucket for the build status
[11:42:23.371] Build 8862754069270562832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:42:23.371] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028184131252732272:37:0) after 1m0s
[11:43:23.523] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028184131252732272:37:0)
[11:43:23.523] Timer tick, asking Buildbucket for the build status
[11:43:23.773] Build 8862754069270562832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:43:23.773] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028184131252732272:38:0) after 1m0s
[11:44:23.868] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028184131252732272:38:0)
[11:44:23.868] Timer tick, asking Buildbucket for the build status
[11:44:23.969] Build 8862754069270562832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:44:23.969] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028184131252732272:39:0) after 1m0s
[11:45:24.113] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028184131252732272:39:0)
[11:45:24.113] Timer tick, asking Buildbucket for the build status
[11:45:24.446] Build 8862754069270562832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:45:24.446] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028184131252732272:40:0) after 1m0s
[11:46:24.474] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028184131252732272:40:0)
[11:46:24.474] Timer tick, asking Buildbucket for the build status
[11:46:24.546] Build 8862754069270562832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:46:24.546] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028184131252732272:41:0) after 1m0s
[11:47:24.638] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028184131252732272:41:0)
[11:47:24.638] Timer tick, asking Buildbucket for the build status
[11:47:24.737] Build 8862754069270562832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:47:24.737] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028184131252732272:42:0) after 1m0s
[11:48:24.775] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028184131252732272:42:0)
[11:48:24.775] Timer tick, asking Buildbucket for the build status
[11:48:24.922] Build 8862754069270562832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:48:24.922] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028184131252732272:43:0) after 1m0s
[11:49:25.014] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028184131252732272:43:0)
[11:49:25.014] Timer tick, asking Buildbucket for the build status
[11:49:25.132] Build 8862754069270562832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:49:25.132] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028184131252732272:44:0) after 1m0s
[11:50:25.262] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028184131252732272:44:0)
[11:50:25.262] Timer tick, asking Buildbucket for the build status
[11:50:25.335] Build 8862754069270562832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:50:25.335] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028184131252732272:45:0) after 1m0s
[11:51:25.416] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028184131252732272:45:0)
[11:51:25.416] Timer tick, asking Buildbucket for the build status
[11:51:25.514] Build 8862754069270562832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:51:25.514] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028184131252732272:46:0) after 1m0s
[11:52:25.630] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028184131252732272:46:0)
[11:52:25.630] Timer tick, asking Buildbucket for the build status
[11:52:25.893] Build 8862754069270562832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:52:25.893] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028184131252732272:47:0) after 1m0s
[11:53:25.931] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028184131252732272:47:0)
[11:53:25.931] Timer tick, asking Buildbucket for the build status
[11:53:26.381] Build 8862754069270562832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:53:26.381] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028184131252732272:48:0) after 1m0s
[11:54:26.454] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028184131252732272:48:0)
[11:54:26.454] Timer tick, asking Buildbucket for the build status
[11:54:26.623] Build 8862754069270562832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:54:26.623] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028184131252732272:49:0) after 1m0s
[11:55:26.719] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028184131252732272:49:0)
[11:55:26.719] Timer tick, asking Buildbucket for the build status
[11:55:26.832] Build 8862754069270562832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:55:26.832] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028184131252732272:50:0) after 1m0s
[11:56:26.856] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028184131252732272:50:0)
[11:56:26.856] Timer tick, asking Buildbucket for the build status
[11:56:26.981] Build 8862754069270562832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:56:26.981] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028184131252732272:51:0) after 1m0s
[11:57:27.103] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028184131252732272:51:0)
[11:57:27.103] Timer tick, asking Buildbucket for the build status
[11:57:27.246] Build 8862754069270562832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:57:27.246] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028184131252732272:52:0) after 1m0s
[11:58:27.381] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028184131252732272:52:0)
[11:58:27.381] Timer tick, asking Buildbucket for the build status
[11:58:27.506] Build 8862754069270562832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:58:27.506] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028184131252732272:53:0) after 1m0s
[11:59:27.679] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028184131252732272:53:0)
[11:59:27.679] Timer tick, asking Buildbucket for the build status
[11:59:27.784] Build 8862754069270562832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:59:27.784] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028184131252732272:54:0) after 1m0s
[12:00:27.891] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028184131252732272:54:0)
[12:00:27.891] Timer tick, asking Buildbucket for the build status
[12:00:28.099] Build 8862754069270562832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:00:28.099] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028184131252732272:55:0) after 1m0s
[12:01:28.154] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028184131252732272:55:0)
[12:01:28.154] Timer tick, asking Buildbucket for the build status
[12:01:28.749] Build 8862754069270562832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:01:28.749] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028184131252732272:56:0) after 1m0s
[12:02:28.748] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028184131252732272:56:0)
[12:02:28.748] Timer tick, asking Buildbucket for the build status
[12:02:29.166] Build 8862754069270562832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:02:29.166] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028184131252732272:57:0) after 1m0s
[12:03:29.203] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028184131252732272:57:0)
[12:03:29.203] Timer tick, asking Buildbucket for the build status
[12:03:29.401] Build 8862754069270562832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:03:29.401] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028184131252732272:58:0) after 1m0s
[12:04:29.434] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028184131252732272:58:0)
[12:04:29.434] Timer tick, asking Buildbucket for the build status
[12:04:29.685] Build 8862754069270562832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:04:29.685] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028184131252732272:59:0) after 1m0s
[12:05:29.752] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028184131252732272:59:0)
[12:05:29.752] Timer tick, asking Buildbucket for the build status
[12:05:29.910] Build 8862754069270562832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:05:29.910] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028184131252732272:60:0) after 1m0s
[12:06:29.913] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028184131252732272:60:0)
[12:06:29.913] Timer tick, asking Buildbucket for the build status
[12:06:30.153] Build 8862754069270562832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:06:30.154] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028184131252732272:61:0) after 1m0s
[12:07:30.200] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028184131252732272:61:0)
[12:07:30.200] Timer tick, asking Buildbucket for the build status
[12:07:30.331] Build 8862754069270562832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:07:30.331] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028184131252732272:62:0) after 1m0s
[12:07:47.783] Received PubSub notification, asking Buildbucket for the build status
[12:07:48.362] Build 8862754069270562832: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[12:07:48.362] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1606219666542921",
"created_by": "project:chromium",
"created_ts": "1606216093719979",
"id": "8862754069270562832",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"38bc3423773325414081578b544ea6711a26dec3\"}}",
"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\": \"38bc3423773325414081578b544ea6711a26dec3\", \"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-216-medw\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-rel\", \"buildnumber\": 43121, \"got_angle_revision\": \"35df41dad8851431846a2ba565dc7aad2e295002\", \"got_dawn_revision\": \"03badec5d73ab4138f798b94221546c496177a09\", \"got_nacl_revision\": \"3ac0b060c794b64531b47dfded8ff8d951a5c648\", \"got_revision\": \"38bc3423773325414081578b544ea6711a26dec3\", \"got_revision_cp\": \"refs/heads/master@{#830532}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"43bb60e1fa119d80b449c6550dd2b72328b101b9\", \"got_v8_revision\": \"4ff956a829fefd66af0deeb9bb091fda4389fa21\", \"got_v8_revision_cp\": \"refs/heads/8.9.39@{#1}\", \"got_webrtc_revision\": \"d840c8fb5dc4aca585e48dc92c7f6afb0408f258\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32680}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mirrored_builders\": [\"tryserver.chromium.chromiumos:linux-chromeos-rel\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"38bc3423773325414081578b544ea6711a26dec3\", \"swarm_hashes_refs/heads/master(at){#830532}_without_patch\": {\"absl_hardening_tests\": \"1275f5b9dc7d3b2cf668af4b1e5dd0b0802fd348\", \"accessibility_unittests\": \"154c8842b29e32e1999f258bc3edd0423cc3c27d\", \"angle_unittests\": \"d2030b860421120844185368c1987eff72f6fa73\", \"app_list_unittests\": \"f22ca61965c5b3fbc4ad5c8b4c08164472662bea\", \"app_shell_unittests\": \"da5486942fde942e020ce7cdfdf2939eb2f670a8\", \"ash_unittests\": \"34eb64c0dacf132f75774af965890b835f070cbb\", \"aura_unittests\": \"a78a1d8d8a2f6a60d91b96ef514d23cc306f7ed5\", \"base_unittests\": \"fa91ea0f809b0c59e1b6ed77e8add02b72ffc00b\", \"base_util_unittests\": \"0639181a0c5a7521769fed74dfecbcef59efc9dc\", \"blink_common_unittests\": \"3877cec066e13023c96746b1446449c4fc81440a\", \"blink_fuzzer_unittests\": \"1787141ab0b40b113bf077250f9ea3f91f25f40c\", \"blink_heap_unittests\": \"6ca55e4c23095cf40fb15f559898d39eac110bb3\", \"blink_platform_unittests\": \"b67578295b19927c5e4cce4d923a5d78be345bd3\", \"blink_unittests\": \"00ea5adaa5892fdbbffb4cc71b458bbc68e99f86\", \"boringssl_crypto_tests\": \"8fd5161edf0c75d2bbef10080e86312301e4a7a3\", \"boringssl_ssl_tests\": \"4f47fed6a41556e792b7cc407473d2c34df24c09\", \"browser_tests\": \"d73f509dddf7e1f628f70be308e7242d832ad2e6\", \"capture_unittests\": \"7765d46930058f97b2baf60060b8fdf9e4716782\", \"cast_unittests\": \"707395b8f425498e8c8ca0fcd638c6749246a0fd\", \"cc_unittests\": \"55c6cb5428cdc72f6f20ad0ecb9e2dd8c1799aea\", \"chrome_app_unittests\": \"76cbe600551b68de6ac99045709dc4f84f99cc5d\", \"chromedriver_unittests\": \"711021a63a564e2fd303722205fbf7c653d77c4c\", \"chromeos_components_unittests\": \"750fcb4f751b62ed1a54cf47e7fc6814a4b45c99\", \"chromeos_unittests\": \"7077df9048ba43ffab21ac50c913eb4da5d75053\", \"components_browsertests\": \"bf5e0a0ba5f6ce6589a5d0b289d7b47bb7734f20\", \"components_unittests\": \"0f1e740b450ab1c964c72e3891e32dd2d42069f2\", \"compositor_unittests\": \"2b45f185dcea752144447b690b492f0acc0f11e4\", \"content_browsertests\": \"e3c48b8a96257428818ad5e3083162428ebb5123\", \"content_unittests\": \"58944cfdf287a036dff994478b8a65354b00f415\", \"crashpad_tests\": \"a193d3c8fd977b59691811972fcfe8c75ab98331\", \"crypto_unittests\": \"ffbea861ff40ca63557bf9e0dbdece399f089f71\", \"dbus_unittests\": \"b30fda846fb981fced9fe364e0f5741785b30239\", \"device_unittests\": \"89c43c91e496ccc88e08b3cdc5f48ae486730f5b\", \"display_unittests\": \"fdafafd07e9e622e001b2c173ac3dc74b41a0c3c\", \"events_unittests\": \"249fb0d0171388ce4f0f9add8cb1f48e74759cb4\", \"exo_unittests\": \"0664499ce63cbe4a940b9ea6e576594ca7c99842\", \"extensions_browsertests\": \"e0f548e7850fdaf87610fc6c7feb45030d4ef724\", \"extensions_unittests\": \"fa495a15455e64aafe7659f23d18f61aa0544ce9\", \"filesystem_service_unittests\": \"6121c59361825cbe28c816d90c9cebe187c3d59b\", \"gcm_unit_tests\": \"da65d9034423600b099b64f0ab758971a5b7775e\", \"gfx_unittests\": \"ec4d755f42a36296fabd8b058e3c4d974c2ca738\", \"gin_unittests\": \"adad8c6760f0d936746e2570f38bf75faf7c4a03\", \"gl_unittests_ozone\": \"91855b2e1b541a1334bbec84b78732a7a8441db9\", \"google_apis_unittests\": \"e7c63f410cf14ec6907a9ec8b5a92e95995fce33\", \"gpu_unittests\": \"3f39707f889312cdb4aa5a7c49fa1f0ff5396ae0\", \"gwp_asan_unittests\": \"be923bc8cf093515b0567509a365caa8f769ea4e\", \"interactive_ui_tests\": \"e5e6b55327a1d6659e1fd9593a821764f5756a92\", \"ipc_tests\": \"158d9788d7fb9cc4e441be679cb92c2fd01d6cd5\", \"jingle_unittests\": \"9f65163a67481ca2ceb46463550186ea903f5f23\", \"keyboard_unittests\": \"f40617df48d4d5b05e01f3b7c7384ee4002cd4b5\", \"latency_unittests\": \"18a645bce850735779e8780ae9613f037fda2884\", \"libjingle_xmpp_unittests\": \"eea64dbc64bbe8bd79ffd11cac4ee610f4c32ec6\", \"liburlpattern_unittests\": \"c4f95f31208321e19a0917611331c145f1753f84\", \"media_blink_unittests\": \"b014e2926075bb4e1b6d1e41eb737aab67aaf34a\", \"media_unittests\": \"fa148793096a9e5da08530927e796690b311c5a0\", \"message_center_unittests\": \"310448bc601963a861bb34bcd56120c2dad04810\", \"midi_unittests\": \"75adc5694b22900af5ede6584d24a2f60ba48b43\", \"mojo_core_unittests\": \"59c51d3fb9e2a9b07b6688d200a653c113390667\", \"mojo_unittests\": \"a4186817d759eac9295a2ab20cc88f2cce65e339\", \"nacl_helper_nonsfi_unittests\": \"e6663dd4691d33c7daf9d1068956ced291a71d3c\", \"nacl_loader_unittests\": \"988b05df948736826d2990327e125af63ca1213d\", \"native_theme_unittests\": \"2f488db37f6cfe466fff49baf185a2ea020c46c8\", \"net_unittests\": \"aaf135f8393e42c8716bfe0d0b9da89a185dcc8e\", \"ozone_gl_unittests\": \"f75916465f74624b2a1f3e9985eb2febc98a348e\", \"ozone_unittests\": \"319a33ef8062b146426fd1b22de87ea326f36122\", \"ozone_x11_unittests\": \"d68caf4080f026395b3437352359b7ba0e75a609\", \"pdf_unittests\": \"af8db379e87fe6fc7623f14467525e4db1c3c563\", \"perfetto_unittests\": \"0e17c8f024261b8610ac61a4acb8aa0d6337da8f\", \"ppapi_unittests\": \"bffe5c95e0eb9ae92c379487aceeb2f2d9b738b7\", \"printing_unittests\": \"82b9d188f8150c78ecc72da18a72ab2ba2f77218\", \"remoting_unittests\": \"b99f3e8d192b7bba5b40070adfa19965483c94d3\", \"sandbox_linux_unittests\": \"e6f29da3918a2b546731c585ec11a6845e2751de\", \"service_manager_unittests\": \"afb0ce26b03e972bf1316e15a7cb9be147245bba\", \"services_unittests\": \"377a2c5ebc91679559c9961396dd37657264998d\", \"shell_dialogs_unittests\": \"d695366c6767f0d1d0053f93d9b92ff0d94348f7\", \"skia_unittests\": \"8391abd3fa18940527a6c16457a34d708389142a\", \"snapshot_unittests\": \"5abc86df099966015b6c980c95dc5f0ab2702225\", \"sql_unittests\": \"8dcd0679665edbd2df178372b0cb764bfc7b6ed0\", \"storage_unittests\": \"d17b99f6bccd687edf8dc629d7070863a98b1627\", \"sync_integration_tests\": \"aba8667067dc548a6b127417f3e12f9882353d8a\", \"traffic_annotation_auditor_unittests\": \"33bd47393a8772391fcf8b3779a07aa765786b91\", \"ui_base_unittests\": \"1a1e24cc76e78c212d91bb09e0fb719ee6085123\", \"ui_chromeos_unittests\": \"0ca9258b5b4e340071c7d0a93fbee41b48a46f8b\", \"ui_touch_selection_unittests\": \"c8138df2aee5f9d6a47b5d5d823b1d3be44527e9\", \"unit_tests\": \"322c808737b6f02b9a43f8050d22d937a5518aaa\", \"url_unittests\": \"1bd1649458de847250906e753b9092fe5fba6f12\", \"usage_time_limit_unittests\": \"5256ba534b1ac176a2c7037288c7a8bdc4c515f8\", \"views_unittests\": \"5a65a0bb0784419e1a8e8fc992680f6f621aab47\", \"viz_unittests\": \"685e0a41cfeba6fbd0136eac6caad315bc9bd7de\", \"wayland_client_perftests\": \"f0e2a402e7852d7305fa5560c9d87276f053dda9\", \"wm_unittests\": \"937bbf1821f33afd5dc740c6ce3fd0fbd7fdcd5d\", \"wtf_unittests\": \"67d0ba20c9abb8ee71eb2644b1ea1378b8ae70a5\", \"zlib_unittests\": \"012c3225ce92d10544c0d497e95c4b5ee6655e43\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_578c225c2c50d5653cce8ddfaab266952737b31d66b6113fbb64cc6f5229aebd_v2\", \"builder_8afdcfef4ae6fecf44bdc59bc36ab1bd30dfc75b00b262fd5e6b3421af127659_v2\", \"builder_94fa3119a28f4651cdd4fc5b981a64c69b26c151212072333439362290628993_v2\", \"builder_c6f1473df00e57847ed4ff0f74718f3dfb2a5d42012d3d3fe6b396b4c9a29f62_v2\", \"builder_e952ecb177c3aa407c442a905d84d05be4034274537a5522f4d6fcc7004f535c_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-216-medw\"], \"image\": [\"chrome-xenial-20111600-a937a23cefb\"], \"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\": [\"5637-4d8b832\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1606216094988091",
"status": "COMPLETED",
"status_changed_ts": "1606219666894505",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/43121",
"builder:linux-chromeos-rel",
"buildset:commit/git/38bc3423773325414081578b544ea6711a26dec3",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/38bc3423773325414081578b544ea6711a26dec3",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9028184131252732272",
"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/8862754069270562832/+/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:5012c21bc4fc3910",
"user_agent:luci-scheduler"
],
"updated_ts": "1606219666909368",
"url": "https://ci.chromium.org/b/8862754069270562832",
"utcnow_ts": "1606219668342233"
}
}
[12:07:48.362] Invocation finished in 59m35.917002932s with status SUCCEEDED