[21:01:34.822] New invocation is queued and will start shortly
[21:01:35.970] Starting the invocation (attempt 1)
[21:01:36.009] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[21:01:36.010] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[21:01:36.010] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9019902475530548256",
"parameters_json": "{\"builder_name\":\"linux-chromeos-rel\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"67f9550f50c37b5b5edbc17d4ba112ba2920dfec\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-rel",
"scheduler_invocation_id:9019902475530548256",
"scheduler_job_id:chromium/linux-chromeos-rel",
"user_agent:luci-scheduler",
"buildset:commit/git/67f9550f50c37b5b5edbc17d4ba112ba2920dfec",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/67f9550f50c37b5b5edbc17d4ba112ba2920dfec",
"gitiles_ref:refs/heads/master"
]
}
[21:01:37.540] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1614114096129557",
"id": "8854472413495705520",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"67f9550f50c37b5b5edbc17d4ba112ba2920dfec\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1614114097036645",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/45673",
"builder:linux-chromeos-rel",
"buildset:commit/git/67f9550f50c37b5b5edbc17d4ba112ba2920dfec",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/67f9550f50c37b5b5edbc17d4ba112ba2920dfec",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019902475530548256",
"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/8854472413495705520/+/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": "1614114097036785",
"url": "https://ci.chromium.org/b/8854472413495705520",
"utcnow_ts": "1614114097512579"
}
}
[21:01:37.540] Task URL: https://ci.chromium.org/b/8854472413495705520
[21:01:37.540] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019902475530548256:2:0) after 1m0s
[21:01:46.785] Received PubSub notification, asking Buildbucket for the build status
[21:01:46.856] Build 8854472413495705520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:02:37.534] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019902475530548256:2:0)
[21:02:37.534] Timer tick, asking Buildbucket for the build status
[21:02:37.608] Build 8854472413495705520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:02:37.608] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019902475530548256:4:0) after 1m0s
[21:03:37.649] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019902475530548256:4:0)
[21:03:37.649] Timer tick, asking Buildbucket for the build status
[21:03:37.782] Build 8854472413495705520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:03:37.782] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019902475530548256:5:0) after 1m0s
[21:04:37.822] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019902475530548256:5:0)
[21:04:37.822] Timer tick, asking Buildbucket for the build status
[21:04:39.934] Build 8854472413495705520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:04:39.934] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019902475530548256:6:0) after 1m0s
[21:05:40.002] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019902475530548256:6:0)
[21:05:40.002] Timer tick, asking Buildbucket for the build status
[21:05:40.087] Build 8854472413495705520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:05:40.087] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019902475530548256:7:0) after 1m0s
[21:06:40.107] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019902475530548256:7:0)
[21:06:40.107] Timer tick, asking Buildbucket for the build status
[21:06:40.209] Build 8854472413495705520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:06:40.209] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019902475530548256:8:0) after 1m0s
[21:07:40.248] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019902475530548256:8:0)
[21:07:40.248] Timer tick, asking Buildbucket for the build status
[21:07:40.328] Build 8854472413495705520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:07:40.328] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019902475530548256:9:0) after 1m0s
[21:08:40.394] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019902475530548256:9:0)
[21:08:40.394] Timer tick, asking Buildbucket for the build status
[21:08:40.641] Build 8854472413495705520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:08:40.641] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019902475530548256:10:0) after 1m0s
[21:09:40.719] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019902475530548256:10:0)
[21:09:40.719] Timer tick, asking Buildbucket for the build status
[21:09:41.113] Build 8854472413495705520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:09:41.113] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019902475530548256:11:0) after 1m0s
[21:10:41.186] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019902475530548256:11:0)
[21:10:41.186] Timer tick, asking Buildbucket for the build status
[21:10:41.258] Build 8854472413495705520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:10:41.258] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019902475530548256:12:0) after 1m0s
[21:11:41.329] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019902475530548256:12:0)
[21:11:41.329] Timer tick, asking Buildbucket for the build status
[21:11:41.454] Build 8854472413495705520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:11:41.454] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019902475530548256:13:0) after 1m0s
[21:12:41.499] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019902475530548256:13:0)
[21:12:41.499] Timer tick, asking Buildbucket for the build status
[21:12:41.569] Build 8854472413495705520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:12:41.569] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019902475530548256:14:0) after 1m0s
[21:13:41.599] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019902475530548256:14:0)
[21:13:41.599] Timer tick, asking Buildbucket for the build status
[21:13:41.682] Build 8854472413495705520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:13:41.682] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019902475530548256:15:0) after 1m0s
[21:14:41.724] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019902475530548256:15:0)
[21:14:41.724] Timer tick, asking Buildbucket for the build status
[21:14:41.836] Build 8854472413495705520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:14:41.836] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019902475530548256:16:0) after 1m0s
[21:15:41.860] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019902475530548256:16:0)
[21:15:41.860] Timer tick, asking Buildbucket for the build status
[21:15:42.049] Build 8854472413495705520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:15:42.049] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019902475530548256:17:0) after 1m0s
[21:16:42.042] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019902475530548256:17:0)
[21:16:42.042] Timer tick, asking Buildbucket for the build status
[21:16:42.162] Build 8854472413495705520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:16:42.162] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019902475530548256:18:0) after 1m0s
[21:17:42.235] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019902475530548256:18:0)
[21:17:42.235] Timer tick, asking Buildbucket for the build status
[21:17:42.319] Build 8854472413495705520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:17:42.319] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019902475530548256:19:0) after 1m0s
[21:18:42.512] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019902475530548256:19:0)
[21:18:42.512] Timer tick, asking Buildbucket for the build status
[21:18:42.580] Build 8854472413495705520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:18:42.580] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019902475530548256:20:0) after 1m0s
[21:19:42.597] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019902475530548256:20:0)
[21:19:42.597] Timer tick, asking Buildbucket for the build status
[21:19:42.686] Build 8854472413495705520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:19:42.686] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019902475530548256:21:0) after 1m0s
[21:20:42.733] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019902475530548256:21:0)
[21:20:42.733] Timer tick, asking Buildbucket for the build status
[21:20:43.062] Build 8854472413495705520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:20:43.062] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019902475530548256:22:0) after 1m0s
[21:21:43.111] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019902475530548256:22:0)
[21:21:43.111] Timer tick, asking Buildbucket for the build status
[21:21:43.271] Build 8854472413495705520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:21:43.271] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019902475530548256:23:0) after 1m0s
[21:22:43.319] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019902475530548256:23:0)
[21:22:43.319] Timer tick, asking Buildbucket for the build status
[21:22:43.707] Build 8854472413495705520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:22:43.707] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019902475530548256:24:0) after 1m0s
[21:23:43.782] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019902475530548256:24:0)
[21:23:43.782] Timer tick, asking Buildbucket for the build status
[21:23:43.863] Build 8854472413495705520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:23:43.863] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019902475530548256:25:0) after 1m0s
[21:24:43.902] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019902475530548256:25:0)
[21:24:43.902] Timer tick, asking Buildbucket for the build status
[21:24:44.206] Build 8854472413495705520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:24:44.206] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019902475530548256:26:0) after 1m0s
[21:25:44.225] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019902475530548256:26:0)
[21:25:44.225] Timer tick, asking Buildbucket for the build status
[21:25:44.298] Build 8854472413495705520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:25:44.298] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019902475530548256:27:0) after 1m0s
[21:26:44.441] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019902475530548256:27:0)
[21:26:44.441] Timer tick, asking Buildbucket for the build status
[21:26:44.539] Build 8854472413495705520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:26:44.539] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019902475530548256:28:0) after 1m0s
[21:27:44.585] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019902475530548256:28:0)
[21:27:44.585] Timer tick, asking Buildbucket for the build status
[21:27:44.701] Build 8854472413495705520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:27:44.701] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019902475530548256:29:0) after 1m0s
[21:28:44.748] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019902475530548256:29:0)
[21:28:44.748] Timer tick, asking Buildbucket for the build status
[21:28:45.036] Build 8854472413495705520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:28:45.036] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019902475530548256:30:0) after 1m0s
[21:29:45.100] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019902475530548256:30:0)
[21:29:45.100] Timer tick, asking Buildbucket for the build status
[21:29:45.185] Build 8854472413495705520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:29:45.185] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019902475530548256:31:0) after 1m0s
[21:30:45.203] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019902475530548256:31:0)
[21:30:45.203] Timer tick, asking Buildbucket for the build status
[21:30:45.572] Build 8854472413495705520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:30:45.572] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019902475530548256:32:0) after 1m0s
[21:31:45.600] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019902475530548256:32:0)
[21:31:45.600] Timer tick, asking Buildbucket for the build status
[21:31:45.986] Build 8854472413495705520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:31:45.986] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019902475530548256:33:0) after 1m0s
[21:32:46.002] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019902475530548256:33:0)
[21:32:46.002] Timer tick, asking Buildbucket for the build status
[21:32:46.080] Build 8854472413495705520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:32:46.080] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019902475530548256:34:0) after 1m0s
[21:33:46.085] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019902475530548256:34:0)
[21:33:46.085] Timer tick, asking Buildbucket for the build status
[21:33:46.304] Build 8854472413495705520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:33:46.304] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019902475530548256:35:0) after 1m0s
[21:34:46.342] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019902475530548256:35:0)
[21:34:46.342] Timer tick, asking Buildbucket for the build status
[21:34:46.417] Build 8854472413495705520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:34:46.417] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019902475530548256:36:0) after 1m0s
[21:35:46.416] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019902475530548256:36:0)
[21:35:46.416] Timer tick, asking Buildbucket for the build status
[21:35:46.564] Build 8854472413495705520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:35:46.564] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019902475530548256:37:0) after 1m0s
[21:36:46.572] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019902475530548256:37:0)
[21:36:46.572] Timer tick, asking Buildbucket for the build status
[21:36:46.869] Build 8854472413495705520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:36:46.869] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019902475530548256:38:0) after 1m0s
[21:37:46.959] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019902475530548256:38:0)
[21:37:46.959] Timer tick, asking Buildbucket for the build status
[21:37:47.045] Build 8854472413495705520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:37:47.045] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019902475530548256:39:0) after 1m0s
[21:38:47.058] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019902475530548256:39:0)
[21:38:47.058] Timer tick, asking Buildbucket for the build status
[21:38:47.156] Build 8854472413495705520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:38:47.156] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019902475530548256:40:0) after 1m0s
[21:39:47.143] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019902475530548256:40:0)
[21:39:47.143] Timer tick, asking Buildbucket for the build status
[21:39:47.342] Build 8854472413495705520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:39:47.342] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019902475530548256:41:0) after 1m0s
[21:40:47.381] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019902475530548256:41:0)
[21:40:47.381] Timer tick, asking Buildbucket for the build status
[21:40:47.952] Build 8854472413495705520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:40:47.952] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019902475530548256:42:0) after 1m0s
[21:41:48.038] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019902475530548256:42:0)
[21:41:48.038] Timer tick, asking Buildbucket for the build status
[21:41:48.218] Build 8854472413495705520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:41:48.218] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019902475530548256:43:0) after 1m0s
[21:42:48.399] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019902475530548256:43:0)
[21:42:48.399] Timer tick, asking Buildbucket for the build status
[21:42:48.487] Build 8854472413495705520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:42:48.487] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019902475530548256:44:0) after 1m0s
[21:43:48.662] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019902475530548256:44:0)
[21:43:48.662] Timer tick, asking Buildbucket for the build status
[21:43:48.923] Build 8854472413495705520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:43:48.923] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019902475530548256:45:0) after 1m0s
[21:44:48.980] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019902475530548256:45:0)
[21:44:48.980] Timer tick, asking Buildbucket for the build status
[21:44:49.121] Build 8854472413495705520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:44:49.121] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019902475530548256:46:0) after 1m0s
[21:45:49.232] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019902475530548256:46:0)
[21:45:49.232] Timer tick, asking Buildbucket for the build status
[21:45:49.329] Build 8854472413495705520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:45:49.329] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019902475530548256:47:0) after 1m0s
[21:46:49.361] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019902475530548256:47:0)
[21:46:49.361] Timer tick, asking Buildbucket for the build status
[21:46:49.483] Build 8854472413495705520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:46:49.483] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019902475530548256:48:0) after 1m0s
[21:47:49.516] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019902475530548256:48:0)
[21:47:49.516] Timer tick, asking Buildbucket for the build status
[21:47:49.596] Build 8854472413495705520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:47:49.596] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019902475530548256:49:0) after 1m0s
[21:48:49.663] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019902475530548256:49:0)
[21:48:49.663] Timer tick, asking Buildbucket for the build status
[21:48:49.753] Build 8854472413495705520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:48:49.753] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019902475530548256:50:0) after 1m0s
[21:49:49.831] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019902475530548256:50:0)
[21:49:49.831] Timer tick, asking Buildbucket for the build status
[21:49:49.986] Build 8854472413495705520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:49:49.986] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019902475530548256:51:0) after 1m0s
[21:50:50.110] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019902475530548256:51:0)
[21:50:50.110] Timer tick, asking Buildbucket for the build status
[21:50:50.429] Build 8854472413495705520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:50:50.429] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019902475530548256:52:0) after 1m0s
[21:51:50.546] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019902475530548256:52:0)
[21:51:50.546] Timer tick, asking Buildbucket for the build status
[21:51:50.991] Build 8854472413495705520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:51:50.991] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019902475530548256:53:0) after 1m0s
[21:52:51.109] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019902475530548256:53:0)
[21:52:51.109] Timer tick, asking Buildbucket for the build status
[21:52:51.193] Build 8854472413495705520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:52:51.193] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9019902475530548256:54:0) after 1m0s
[21:53:06.979] Received PubSub notification, asking Buildbucket for the build status
[21:53:07.584] Build 8854472413495705520: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[21:53:07.584] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1614117184600822",
"created_by": "project:chromium",
"created_ts": "1614114096129557",
"id": "8854472413495705520",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"67f9550f50c37b5b5edbc17d4ba112ba2920dfec\"}}",
"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\": \"67f9550f50c37b5b5edbc17d4ba112ba2920dfec\", \"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-27-2b5k\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-rel\", \"buildnumber\": 45673, \"got_angle_revision\": \"f19f319a59c83d9ccdce08a3b41024cfc997fd8d\", \"got_dawn_revision\": \"232f4d183ce8ab93909b30af814e320c0f0db57c\", \"got_nacl_revision\": \"abf3277f295c6c87f0b103f291ed5e3e73933b76\", \"got_revision\": \"67f9550f50c37b5b5edbc17d4ba112ba2920dfec\", \"got_revision_cp\": \"refs/heads/master@{#856840}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"22186f076bf3bbe1a818792bde93b297e3773f7e\", \"got_v8_revision\": \"8bc6184274d97b9b57d5389e423f6f3f7c092918\", \"got_v8_revision_cp\": \"refs/heads/9.0.239@{#1}\", \"got_webrtc_revision\": \"d0844a80deaab28d7df8e5dff9f71e6ab6919025\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33323}\", \"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\": \"67f9550f50c37b5b5edbc17d4ba112ba2920dfec\", \"swarm_hashes_refs/heads/master(at){#856840}_without_patch\": {\"absl_hardening_tests\": \"14fdd353a0f5aac3256d188a681018794fd1820f\", \"accessibility_unittests\": \"d560cde138dcc37676f48196f4807a414d1a6d70\", \"angle_unittests\": \"13306ffdb69b6bf70c1ae87aaea10b86a7ff68b5\", \"app_list_unittests\": \"8ae718bab414907206e3ef5e970cb92e8abbb434\", \"app_shell_unittests\": \"2809e7c0ea14d33381a613c0fe3a9fd0952a872b\", \"ash_unittests\": \"3b2e37e640c6d9bce051b529ac3f17f938cc4c29\", \"aura_unittests\": \"875045f3f6678716b1b981c22502264257402940\", \"base_unittests\": \"0b2863a79c06516ae3afb0ca8691441f7b472fc8\", \"base_util_unittests\": \"6f8b61b0f4ee43884295995d1fde719273278c69\", \"blink_common_unittests\": \"d0e66866fb83e0b123284112603b96640efd21f3\", \"blink_fuzzer_unittests\": \"993737f96f2596e806a07442778ad72a50236d42\", \"blink_heap_unittests\": \"201dfcef071f474c6a2ea82ab7af6cd18ea1f47c\", \"blink_platform_unittests\": \"e3f520ba88f7e93290805c6ed1cadc38a6c44e0b\", \"blink_unittests\": \"17f20f6e53b2b6391f29e368dbf72a0d6f9ca85e\", \"boringssl_crypto_tests\": \"a041e2d1841edd944b8010f9609e45748165c868\", \"boringssl_ssl_tests\": \"8d7a9f58ed7a0b4021a7c65a7ce59086ec07d3e2\", \"browser_tests\": \"b15d50ce6727687fef57c82761053511ef3081b3\", \"capture_unittests\": \"0a62a8926207d685a9d33aeb0490eb05d64ce89c\", \"cast_unittests\": \"8b41093077fba108d950d35eb95414f58ab061c5\", \"cc_unittests\": \"2aeef1b2dbc9cd3eeb49dc34c53561e3e6bb4c9a\", \"chrome_app_unittests\": \"978547abfbcdd57b4c123c6290b4cdbcee8a86ea\", \"chromedriver_unittests\": \"8cea6ca9dcc7770ecb7154f406e81f87d56c72b3\", \"chromeos_components_unittests\": \"74bb227dcef32f899893123f6cec4e8aa2267025\", \"chromeos_unittests\": \"13d9829ce2ed95770e160ca5523fc3b53a4cc67d\", \"components_browsertests\": \"32b3aa39683ad2107790fe4bbbe85ace5a1572d2\", \"components_unittests\": \"c2a6427534f854e69842d5b7b810c58a8d435f48\", \"compositor_unittests\": \"e500b5210f8744744826b285a18a79e3bcff8cc0\", \"content_browsertests\": \"ac0d36592447f02a25bfe51c34a5d21c5bc39df9\", \"content_unittests\": \"461923ea1146085f10744e34798259b03964509c\", \"crashpad_tests\": \"57880b9e05e9f4210356c2fe8425c77b6a7af301\", \"crypto_unittests\": \"1578e0c5c575a729c523655a3278151ed743ba2c\", \"dbus_unittests\": \"593cbb0c358b849061f71d5d3f802a341bc25feb\", \"device_unittests\": \"a4c2f948f73762039ae27622584c0c2882de6822\", \"display_unittests\": \"c5915a933117826f5156f2e967a68cdae3d6a8fd\", \"events_unittests\": \"66aa5117a9b177461e47eb2dd2bfb20ae7fa1daf\", \"exo_unittests\": \"b66c943b42b207a86b61a4666f5c14df3fae54cb\", \"extensions_browsertests\": \"ebae37986fe5f31136006d1835ec3b8d3b026490\", \"extensions_unittests\": \"10319666be70c055fa849ad42c90b9cbf038bf6a\", \"filesystem_service_unittests\": \"15c1a1791528e07487b4a8522a9892235f2206a1\", \"gcm_unit_tests\": \"69d9c5330c6511377cda41f77de1f14a1439f015\", \"gfx_unittests\": \"aeaaf937622d1d95d59b1f1419f3e2a35980e8f9\", \"gin_unittests\": \"36d888eef052cb065847e45137a5b6e767251a6b\", \"gl_unittests_ozone\": \"3f1ae4e3051f9925e725e2e618e29865cb84c96c\", \"google_apis_unittests\": \"a5692475e3ab69db95c1a39f3c2dff5132231d5e\", \"gpu_unittests\": \"5676e42b87c917667e7da0d768432c3e34fed257\", \"gwp_asan_unittests\": \"47fe900097d98628d7773ddcb4aa34c116bac20b\", \"interactive_ui_tests\": \"660571cd83480c45dcf9494908e5211043e15bbd\", \"ipc_tests\": \"b843648d15f1b22bb44b91b3f971bef35728516c\", \"jingle_unittests\": \"91a1f5ec17e2ff18e2ff2b61d6d1a3834d9481b0\", \"keyboard_unittests\": \"e8993957d62c0be86ed3e57880bdc4c79927912a\", \"latency_unittests\": \"ba012e87d44dbf3be598a50e4ba205255bdc0140\", \"libjingle_xmpp_unittests\": \"498900204bf9d065d1033a0966b51f39eccd3360\", \"liburlpattern_unittests\": \"b192e27a9d85f57afd94121ab6cc9e2dbd2d1c92\", \"media_blink_unittests\": \"a257c01d2ed177f23b95a16ade14e53ee220c4fc\", \"media_unittests\": \"d99663a2929329dfd7f693fbb0d93f2c0059b5fe\", \"message_center_unittests\": \"fde31194e8eaf11b8e4fbfd08b936c5f96219115\", \"midi_unittests\": \"82a5e74837207a1f8fb6f7e4d5e2f34a7ee56293\", \"mojo_core_unittests\": \"153379a248ff5a4d212d3e841ebb893a3cdeed3b\", \"mojo_unittests\": \"87d24b1d1d2ac40d7828491c77a26b1754345d89\", \"nacl_helper_nonsfi_unittests\": \"322f42ac61e8c7bec0e6e64702359ab17782e73c\", \"nacl_loader_unittests\": \"7d691031f2eb07a46f4c3a0fbe60eaaaf895f88a\", \"native_theme_unittests\": \"eac63abcf9164820439ee2825f3b7b767cf4289e\", \"net_unittests\": \"67f9715036f239500a7b73945e8202c52e78829f\", \"ozone_gl_unittests\": \"08b29a9287d233ad5609f8b6b698a186054bc3e0\", \"ozone_unittests\": \"80dcf75ceb6de46fc15071ce8c46787286a1e30c\", \"ozone_x11_unittests\": \"8939e90722dd3c33f48805f1c7197d748200fb38\", \"pdf_unittests\": \"c5f9475f213371435f93bc78bec6817a113d4b77\", \"perfetto_unittests\": \"9afd000696efef036a6a2f50afe21fda03af1a56\", \"ppapi_unittests\": \"9e524669e5bc2468514ac3a5a5983f69d326e288\", \"printing_unittests\": \"fdbde9fcbe63e792026c9e26b9901d48ad5dc557\", \"remoting_unittests\": \"9135641094ec01f69242fb36780d2dc66d584f3c\", \"sandbox_linux_unittests\": \"fce57e6c1eb508095e156325f19df05ff1755724\", \"service_manager_unittests\": \"3ab9ff00f188a5da06f2bf714702bf05f3294616\", \"services_unittests\": \"324e6dbb2d3021f97b39a19c65f33657c208ad74\", \"shell_dialogs_unittests\": \"0b79dd9d4967015b15c935be36134e05a86f1e72\", \"shell_encryption_unittests\": \"e9aca797077ffb202a9875d4878fd2bc963cc257\", \"skia_unittests\": \"22a8cdfd27825ad1cc438f5e76a843b11191db32\", \"snapshot_unittests\": \"d3fbb68a032120c0a9b1b628338201f74956b508\", \"sql_unittests\": \"a94f388c28e5a3b78683c9cbe5ca9ff522c70ed7\", \"storage_unittests\": \"b6bbc8e75e0b574ca0348192a59c7a3c55dc1f18\", \"sync_integration_tests\": \"75d194a00f54a51b03db8698c2761be05a317537\", \"traffic_annotation_auditor_unittests\": \"a05179b9017337e736a5537e91406a2363b1273e\", \"ui_base_unittests\": \"c33b052306ca7ae68679f5ae164290ef58f155cd\", \"ui_chromeos_unittests\": \"8fcc159e11f8cda3630557b8774f08b6a708d346\", \"ui_touch_selection_unittests\": \"4887208c6e1f346fedf82260cd6539171b7f9d98\", \"unit_tests\": \"bbd899ae1e926381df7fb648948d043ad52d2151\", \"url_unittests\": \"f1c9ccb33c822995257e10b00ac2d58a9e010368\", \"usage_time_limit_unittests\": \"2ef085db5bce05a51df80b1db867f50f8cbce4a8\", \"views_unittests\": \"f90be70f1462a87675cbaabd17d979d60485bc4e\", \"viz_unittests\": \"35893f5da3cd5a9fa505a947094861119a31f0ee\", \"wayland_client_perftests\": \"2e6c3276059a8bfb8b0285d4a0fa9a3a1f0bd5f5\", \"wm_unittests\": \"0f45660c3e6f0fd4498eace5eb0c305b0cb96b95\", \"wtf_unittests\": \"6d68898712ac771b7ab6b56aab2d080e93093538\", \"zlib_unittests\": \"a42ac3e3d5d0d19c295beee3fd89c4e0d49ad59f\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_1143bf8a0c86d96d189f99a4f0f749b4f3d1e46c849c82cdd2c6ebe168938b3a_v2\", \"builder_554b324272771ff5af13275cc9c865b3499df108d1c4d159c356d5c678bf725c_v2\", \"builder_573c606f18492d6cde6d25a02b12203725732038b1c2909b0b80126d2afb25c2_v2\", \"builder_609b54f795c46f6da98bce3e3c3edeb2c2e2d72c7759cc5040a86edccd734c7c_v2\", \"builder_7e0fd5fb59bce9eac4ec1dec14c7ab059c08dcb0c6471580f0ba0fe000c8167b_v2\", \"builder_94fa3119a28f4651cdd4fc5b981a64c69b26c151212072333439362290628993_v2\", \"builder_a805855f4ac8de7768c87aef010c4dbf266d6f852a36787503910d065c8041d1_v2\", \"builder_bcc6b3e5c0858529730294da730af8ea17f2c5c79b8b0dc7af4e722a8412ac73_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-27-2b5k\"], \"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\": [\"5706-397711c\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1614114106024725",
"status": "COMPLETED",
"status_changed_ts": "1614117185008861",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/45673",
"builder:linux-chromeos-rel",
"buildset:commit/git/67f9550f50c37b5b5edbc17d4ba112ba2920dfec",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/67f9550f50c37b5b5edbc17d4ba112ba2920dfec",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019902475530548256",
"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/8854472413495705520/+/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:51e98412f424ff10",
"user_agent:luci-scheduler"
],
"updated_ts": "1614117185023806",
"url": "https://ci.chromium.org/b/8854472413495705520",
"utcnow_ts": "1614117187564899"
}
}
[21:53:07.584] Invocation finished in 51m32.77956552s with status SUCCEEDED