[11:36:00.500] New invocation is queued and will start shortly
[11:36:03.204] Starting the invocation (attempt 1)
[11:36:03.281] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[11:36:03.282] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[11:36:03.282] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9029450739723056304",
"parameters_json": "{\"builder_name\":\"linux-chromeos-rel\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"cf22557f3b446970d634e134fc03efb864e3aed6\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-rel",
"scheduler_invocation_id:9029450739723056304",
"scheduler_job_id:chromium/linux-chromeos-rel",
"user_agent:luci-scheduler",
"buildset:commit/git/cf22557f3b446970d634e134fc03efb864e3aed6",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/cf22557f3b446970d634e134fc03efb864e3aed6",
"gitiles_ref:refs/heads/master"
]
}
[11:36:06.595] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1605008163633647",
"id": "8864020675769022960",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"cf22557f3b446970d634e134fc03efb864e3aed6\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1605008165870233",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/42746",
"builder:linux-chromeos-rel",
"buildset:commit/git/cf22557f3b446970d634e134fc03efb864e3aed6",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/cf22557f3b446970d634e134fc03efb864e3aed6",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9029450739723056304",
"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/8864020675769022960/+/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": "1605008165870596",
"url": "https://ci.chromium.org/b/8864020675769022960",
"utcnow_ts": "1605008166549156"
}
}
[11:36:06.595] Task URL: https://ci.chromium.org/b/8864020675769022960
[11:36:06.595] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029450739723056304:2:0) after 1m0s
[11:36:11.690] Received PubSub notification, asking Buildbucket for the build status
[11:36:11.774] Build 8864020675769022960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:37:06.664] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029450739723056304:2:0)
[11:37:06.664] Timer tick, asking Buildbucket for the build status
[11:37:06.779] Build 8864020675769022960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:37:06.779] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029450739723056304:4:0) after 1m0s
[11:38:06.800] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029450739723056304:4:0)
[11:38:06.800] Timer tick, asking Buildbucket for the build status
[11:38:07.472] Build 8864020675769022960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:38:07.472] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029450739723056304:5:0) after 1m0s
[11:39:07.647] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029450739723056304:5:0)
[11:39:07.647] Timer tick, asking Buildbucket for the build status
[11:39:07.916] Build 8864020675769022960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:39:07.916] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029450739723056304:6:0) after 1m0s
[11:40:08.001] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029450739723056304:6:0)
[11:40:08.001] Timer tick, asking Buildbucket for the build status
[11:40:08.130] Build 8864020675769022960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:40:08.130] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029450739723056304:7:0) after 1m0s
[11:41:08.254] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029450739723056304:7:0)
[11:41:08.254] Timer tick, asking Buildbucket for the build status
[11:41:08.381] Build 8864020675769022960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:41:08.381] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029450739723056304:8:0) after 1m0s
[11:42:08.447] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029450739723056304:8:0)
[11:42:08.447] Timer tick, asking Buildbucket for the build status
[11:42:08.653] Build 8864020675769022960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:42:08.653] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029450739723056304:9:0) after 1m0s
[11:43:08.748] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029450739723056304:9:0)
[11:43:08.748] Timer tick, asking Buildbucket for the build status
[11:43:08.825] Build 8864020675769022960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:43:08.826] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029450739723056304:10:0) after 1m0s
[11:44:08.893] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029450739723056304:10:0)
[11:44:08.893] Timer tick, asking Buildbucket for the build status
[11:44:08.973] Build 8864020675769022960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:44:08.973] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029450739723056304:11:0) after 1m0s
[11:45:08.978] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029450739723056304:11:0)
[11:45:08.978] Timer tick, asking Buildbucket for the build status
[11:45:09.101] Build 8864020675769022960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:45:09.101] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029450739723056304:12:0) after 1m0s
[11:46:09.092] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029450739723056304:12:0)
[11:46:09.092] Timer tick, asking Buildbucket for the build status
[11:46:09.244] Build 8864020675769022960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:46:09.244] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029450739723056304:13:0) after 1m0s
[11:47:09.275] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029450739723056304:13:0)
[11:47:09.275] Timer tick, asking Buildbucket for the build status
[11:47:09.337] Build 8864020675769022960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:47:09.337] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029450739723056304:14:0) after 1m0s
[11:48:09.384] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029450739723056304:14:0)
[11:48:09.384] Timer tick, asking Buildbucket for the build status
[11:48:09.609] Build 8864020675769022960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:48:09.609] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029450739723056304:15:0) after 1m0s
[11:49:09.619] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029450739723056304:15:0)
[11:49:09.619] Timer tick, asking Buildbucket for the build status
[11:49:10.048] Build 8864020675769022960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:49:10.048] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029450739723056304:16:0) after 1m0s
[11:50:10.075] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029450739723056304:16:0)
[11:50:10.075] Timer tick, asking Buildbucket for the build status
[11:50:10.255] Build 8864020675769022960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:50:10.255] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029450739723056304:17:0) after 1m0s
[11:51:10.275] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029450739723056304:17:0)
[11:51:10.275] Timer tick, asking Buildbucket for the build status
[11:51:10.382] Build 8864020675769022960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:51:10.382] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029450739723056304:18:0) after 1m0s
[11:52:10.414] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029450739723056304:18:0)
[11:52:10.414] Timer tick, asking Buildbucket for the build status
[11:52:10.585] Build 8864020675769022960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:52:10.586] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029450739723056304:19:0) after 1m0s
[11:53:10.646] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029450739723056304:19:0)
[11:53:10.646] Timer tick, asking Buildbucket for the build status
[11:53:10.713] Build 8864020675769022960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:53:10.713] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029450739723056304:20:0) after 1m0s
[11:54:10.793] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029450739723056304:20:0)
[11:54:10.793] Timer tick, asking Buildbucket for the build status
[11:54:10.876] Build 8864020675769022960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:54:10.876] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029450739723056304:21:0) after 1m0s
[11:55:10.897] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029450739723056304:21:0)
[11:55:10.897] Timer tick, asking Buildbucket for the build status
[11:55:10.960] Build 8864020675769022960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:55:10.960] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029450739723056304:22:0) after 1m0s
[11:56:10.942] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029450739723056304:22:0)
[11:56:10.942] Timer tick, asking Buildbucket for the build status
[11:56:12.302] Build 8864020675769022960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:56:12.302] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029450739723056304:23:0) after 1m0s
[11:57:12.509] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029450739723056304:23:0)
[11:57:12.509] Timer tick, asking Buildbucket for the build status
[11:57:12.589] Build 8864020675769022960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:57:12.589] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029450739723056304:24:0) after 1m0s
[11:58:12.719] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029450739723056304:24:0)
[11:58:12.719] Timer tick, asking Buildbucket for the build status
[11:58:12.794] Build 8864020675769022960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:58:12.794] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029450739723056304:25:0) after 1m0s
[11:59:12.851] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029450739723056304:25:0)
[11:59:12.851] Timer tick, asking Buildbucket for the build status
[11:59:13.661] Build 8864020675769022960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:59:13.661] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029450739723056304:26:0) after 1m0s
[12:00:13.735] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029450739723056304:26:0)
[12:00:13.735] Timer tick, asking Buildbucket for the build status
[12:00:13.815] Build 8864020675769022960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:00:13.815] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029450739723056304:27:0) after 1m0s
[12:01:13.960] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029450739723056304:27:0)
[12:01:13.960] Timer tick, asking Buildbucket for the build status
[12:01:14.338] Build 8864020675769022960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:01:14.338] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029450739723056304:28:0) after 1m0s
[12:02:14.374] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029450739723056304:28:0)
[12:02:14.374] Timer tick, asking Buildbucket for the build status
[12:02:14.583] Build 8864020675769022960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:02:14.583] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029450739723056304:29:0) after 1m0s
[12:03:14.660] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029450739723056304:29:0)
[12:03:14.660] Timer tick, asking Buildbucket for the build status
[12:03:14.761] Build 8864020675769022960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:03:14.761] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029450739723056304:30:0) after 1m0s
[12:04:14.786] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029450739723056304:30:0)
[12:04:14.786] Timer tick, asking Buildbucket for the build status
[12:04:14.849] Build 8864020675769022960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:04:14.849] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029450739723056304:31:0) after 1m0s
[12:05:14.940] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029450739723056304:31:0)
[12:05:14.940] Timer tick, asking Buildbucket for the build status
[12:05:15.170] Build 8864020675769022960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:05:15.170] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029450739723056304:32:0) after 1m0s
[12:06:15.224] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029450739723056304:32:0)
[12:06:15.224] Timer tick, asking Buildbucket for the build status
[12:06:15.346] Build 8864020675769022960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:06:15.346] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029450739723056304:33:0) after 1m0s
[12:07:15.537] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029450739723056304:33:0)
[12:07:15.537] Timer tick, asking Buildbucket for the build status
[12:07:15.656] Build 8864020675769022960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:07:15.656] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029450739723056304:34:0) after 1m0s
[12:08:15.801] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029450739723056304:34:0)
[12:08:15.801] Timer tick, asking Buildbucket for the build status
[12:08:16.163] Build 8864020675769022960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:08:16.163] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029450739723056304:35:0) after 1m0s
[12:09:16.346] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029450739723056304:35:0)
[12:09:16.346] Timer tick, asking Buildbucket for the build status
[12:09:16.423] Build 8864020675769022960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:09:16.423] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029450739723056304:36:0) after 1m0s
[12:10:16.469] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029450739723056304:36:0)
[12:10:16.469] Timer tick, asking Buildbucket for the build status
[12:10:16.624] Build 8864020675769022960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:10:16.624] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029450739723056304:37:0) after 1m0s
[12:11:16.679] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029450739723056304:37:0)
[12:11:16.679] Timer tick, asking Buildbucket for the build status
[12:11:17.150] Build 8864020675769022960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:11:17.150] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029450739723056304:38:0) after 1m0s
[12:12:17.173] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029450739723056304:38:0)
[12:12:17.173] Timer tick, asking Buildbucket for the build status
[12:12:17.248] Build 8864020675769022960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:12:17.248] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029450739723056304:39:0) after 1m0s
[12:13:17.363] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029450739723056304:39:0)
[12:13:17.363] Timer tick, asking Buildbucket for the build status
[12:13:17.661] Build 8864020675769022960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:13:17.661] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029450739723056304:40:0) after 1m0s
[12:14:17.846] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029450739723056304:40:0)
[12:14:17.846] Timer tick, asking Buildbucket for the build status
[12:14:17.925] Build 8864020675769022960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:14:17.925] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029450739723056304:41:0) after 1m0s
[12:15:18.068] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029450739723056304:41:0)
[12:15:18.068] Timer tick, asking Buildbucket for the build status
[12:15:18.142] Build 8864020675769022960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:15:18.142] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029450739723056304:42:0) after 1m0s
[12:16:18.275] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029450739723056304:42:0)
[12:16:18.275] Timer tick, asking Buildbucket for the build status
[12:16:18.577] Build 8864020675769022960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:16:18.577] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029450739723056304:43:0) after 1m0s
[12:17:18.783] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029450739723056304:43:0)
[12:17:18.783] Timer tick, asking Buildbucket for the build status
[12:17:18.877] Build 8864020675769022960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:17:18.878] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029450739723056304:44:0) after 1m0s
[12:18:18.941] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029450739723056304:44:0)
[12:18:18.941] Timer tick, asking Buildbucket for the build status
[12:18:19.094] Build 8864020675769022960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:18:19.094] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029450739723056304:45:0) after 1m0s
[12:19:19.197] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029450739723056304:45:0)
[12:19:19.197] Timer tick, asking Buildbucket for the build status
[12:19:19.364] Build 8864020675769022960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:19:19.364] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029450739723056304:46:0) after 1m0s
[12:20:19.615] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029450739723056304:46:0)
[12:20:19.615] Timer tick, asking Buildbucket for the build status
[12:20:20.056] Build 8864020675769022960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:20:20.056] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029450739723056304:47:0) after 1m0s
[12:21:20.187] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029450739723056304:47:0)
[12:21:20.187] Timer tick, asking Buildbucket for the build status
[12:21:20.316] Build 8864020675769022960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:21:20.316] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029450739723056304:48:0) after 1m0s
[12:22:20.382] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029450739723056304:48:0)
[12:22:20.382] Timer tick, asking Buildbucket for the build status
[12:22:20.506] Build 8864020675769022960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:22:20.506] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029450739723056304:49:0) after 1m0s
[12:23:20.586] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029450739723056304:49:0)
[12:23:20.586] Timer tick, asking Buildbucket for the build status
[12:23:20.919] Build 8864020675769022960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:23:20.919] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029450739723056304:50:0) after 1m0s
[12:24:21.088] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029450739723056304:50:0)
[12:24:21.088] Timer tick, asking Buildbucket for the build status
[12:24:21.363] Build 8864020675769022960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:24:21.363] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029450739723056304:51:0) after 1m0s
[12:25:21.460] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029450739723056304:51:0)
[12:25:21.460] Timer tick, asking Buildbucket for the build status
[12:25:22.577] Build 8864020675769022960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:25:22.577] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029450739723056304:52:0) after 1m0s
[12:26:22.850] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029450739723056304:52:0)
[12:26:22.850] Timer tick, asking Buildbucket for the build status
[12:26:22.983] Build 8864020675769022960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:26:22.983] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029450739723056304:53:0) after 1m0s
[12:27:23.072] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029450739723056304:53:0)
[12:27:23.072] Timer tick, asking Buildbucket for the build status
[12:27:23.232] Build 8864020675769022960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:27:23.232] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029450739723056304:54:0) after 1m0s
[12:28:23.276] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029450739723056304:54:0)
[12:28:23.276] Timer tick, asking Buildbucket for the build status
[12:28:23.390] Build 8864020675769022960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:28:23.390] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029450739723056304:55:0) after 1m0s
[12:29:23.526] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029450739723056304:55:0)
[12:29:23.526] Timer tick, asking Buildbucket for the build status
[12:29:24.214] Build 8864020675769022960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:29:24.214] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029450739723056304:56:0) after 1m0s
[12:30:24.310] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029450739723056304:56:0)
[12:30:24.310] Timer tick, asking Buildbucket for the build status
[12:30:24.708] Build 8864020675769022960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:30:24.708] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029450739723056304:57:0) after 1m0s
[12:31:24.799] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029450739723056304:57:0)
[12:31:24.799] Timer tick, asking Buildbucket for the build status
[12:31:26.156] Build 8864020675769022960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:31:26.156] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029450739723056304:58:0) after 1m0s
[12:32:26.163] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029450739723056304:58:0)
[12:32:26.163] Timer tick, asking Buildbucket for the build status
[12:32:26.312] Build 8864020675769022960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:32:26.313] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029450739723056304:59:0) after 1m0s
[12:33:26.338] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029450739723056304:59:0)
[12:33:26.338] Timer tick, asking Buildbucket for the build status
[12:33:27.415] Build 8864020675769022960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:33:27.415] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029450739723056304:60:0) after 1m0s
[12:34:27.509] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029450739723056304:60:0)
[12:34:27.509] Timer tick, asking Buildbucket for the build status
[12:34:27.910] Build 8864020675769022960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:34:27.910] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029450739723056304:61:0) after 1m0s
[12:35:27.935] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029450739723056304:61:0)
[12:35:27.935] Timer tick, asking Buildbucket for the build status
[12:35:28.901] Build 8864020675769022960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:35:28.901] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029450739723056304:62:0) after 1m0s
[12:36:29.065] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029450739723056304:62:0)
[12:36:29.065] Timer tick, asking Buildbucket for the build status
[12:36:29.225] Build 8864020675769022960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:36:29.225] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029450739723056304:63:0) after 1m0s
[12:37:29.291] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029450739723056304:63:0)
[12:37:29.291] Timer tick, asking Buildbucket for the build status
[12:37:29.551] Build 8864020675769022960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:37:29.551] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029450739723056304:64:0) after 1m0s
[12:37:32.790] Received PubSub notification, asking Buildbucket for the build status
[12:37:32.948] Build 8864020675769022960: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[12:37:32.948] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1605011851579378",
"created_by": "project:chromium",
"created_ts": "1605008163633647",
"id": "8864020675769022960",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"cf22557f3b446970d634e134fc03efb864e3aed6\"}}",
"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\": \"cf22557f3b446970d634e134fc03efb864e3aed6\", \"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-east1-d-8-114-jw8n\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-rel\", \"buildnumber\": 42746, \"got_angle_revision\": \"36787dcb438203b3bade1d99aa5b996c84d04cbe\", \"got_dawn_revision\": \"c04a0132be6c384c90bd33b06929a9b7fc5d4726\", \"got_nacl_revision\": \"f9e6f257d1a0934dbeed3c5c8f83b651dc4f55e1\", \"got_revision\": \"cf22557f3b446970d634e134fc03efb864e3aed6\", \"got_revision_cp\": \"refs/heads/master@{#825762}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"4ed9d3498dcffa987acba1a8007ff8dec336f263\", \"got_v8_revision\": \"bc5c47815cc7cbd63f78a779ed5470f91422f1f9\", \"got_v8_revision_cp\": \"refs/heads/8.8.272@{#1}\", \"got_webrtc_revision\": \"9c99b7964fabb16a022293ffbd2804f9b46551f6\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32565}\", \"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\": \"cf22557f3b446970d634e134fc03efb864e3aed6\", \"swarm_hashes_refs/heads/master(at){#825762}_without_patch\": {\"absl_hardening_tests\": \"2d832d4939bab3d926c1b06ddfe35b527c598a25\", \"accessibility_unittests\": \"d7f2a23f0f2e858e5a662598a7e4fed62b1cfcaa\", \"angle_unittests\": \"7682a2d388fa96c49a46c8ebbbad775697c72a38\", \"app_list_unittests\": \"a274c7c3733c72ce0ccda0902a126d1b10c8430c\", \"app_shell_unittests\": \"2a627d4ff7bdd686b782183ece18820e4b8deec6\", \"ash_unittests\": \"e94ff3a9b500843555573c6eb8aebcf803588440\", \"aura_unittests\": \"74f2b1caaa0ff69ef8da5678454fae8fe7c294e7\", \"base_unittests\": \"5178dc1decccb6d705b4f5a5f77619d87e13dd85\", \"base_util_unittests\": \"2b50450779aa929cfc906bb9cda78122a9a97f25\", \"blink_common_unittests\": \"27f8e7a7090c4506cd5165010a364231caf4ca4f\", \"blink_fuzzer_unittests\": \"de77e7c0ce25d579ccd1e4ff7c4036a2ebf6e49a\", \"blink_heap_unittests\": \"b03b73f8a4a6ece4b72b585c377d3a6b66458924\", \"blink_platform_unittests\": \"c2dff3e7c289d5d84a8f3af8bca428cfcd4480ac\", \"blink_unittests\": \"b10e7587743a8076c7d04efc944071a1a75b8abf\", \"boringssl_crypto_tests\": \"7349745cc67c9785753caa631d4bda75fbc42f00\", \"boringssl_ssl_tests\": \"07cdee842c5c4b0b3013c512b3216b685ae479a3\", \"browser_tests\": \"0670ff4570bd65872add33e1d755b1358caaae1b\", \"capture_unittests\": \"b53abe201bdcb8ab730829ac29d9c7d75dcbd682\", \"cast_unittests\": \"98d65f63eb2d2a1d1220823f034d13db7ca4ec58\", \"cc_unittests\": \"535b8b9f7f12bcffb45b266a81ddef2726f4ac5f\", \"chrome_app_unittests\": \"d49d865dab939159f0b8c1bd7cd36046e218c803\", \"chromedriver_unittests\": \"5baa4c1d64747471db110347ccf1a49450eb5413\", \"chromeos_components_unittests\": \"9d5cf355404e2b30c87791e1b8dd6e323d9f88d4\", \"chromeos_unittests\": \"f643b6e09c4091385e0a650f6a832e119be03560\", \"components_browsertests\": \"96fb65d7c8b9ea48759eb3d585fdb9a1ebf881fd\", \"components_unittests\": \"8f789e3c5a9fd764f21a965e07cf4da967b558b4\", \"compositor_unittests\": \"6b44e521c22a021510d0a3109e7b36862235e1b4\", \"content_browsertests\": \"2ad69d6838472b6db68bd071cb73f0c2d69e0a32\", \"content_unittests\": \"9d3ef5c3a1943506c9012f7e798276d248541569\", \"crypto_unittests\": \"d01ad0eb3fbd7de6225b1737b1d4cf95dccb92c1\", \"dbus_unittests\": \"40e9d9ebe956028e706e2b3f5a3d5b3e37d02b60\", \"device_unittests\": \"251261c0945d5a97db5e90e883f90e8612633c9e\", \"display_unittests\": \"99e1f4f3f0d230dc567d0ab068dabd449eb11ffc\", \"events_unittests\": \"476cf06ab59af2dd018f0f63b17741dc7504146d\", \"exo_unittests\": \"4bae92b1bc0f612841b2e933e262b8c83d3f6a29\", \"extensions_browsertests\": \"118294b9c2dc0163434106420243e2e4fcc1a17e\", \"extensions_unittests\": \"79f5234629eaff29825b6fbd9df77e774c8a6fa2\", \"filesystem_service_unittests\": \"511a8398e460b1b39beb62abf645a7589c61aec2\", \"gcm_unit_tests\": \"f135ae9dc3437fbe8fdf8ec57316cc1b41f91f91\", \"gfx_unittests\": \"42365d5e5d563317613c4c236944b13b0b2a0d10\", \"gin_unittests\": \"4abef0aaf2f6bba74db5b82fa1419aa631f4e4fd\", \"gl_unittests_ozone\": \"2a7c54e2670a2a75e199781c64d7d176e2d7e615\", \"google_apis_unittests\": \"873ed87058cbd01f8f2b8302b0e894311997d4da\", \"gpu_unittests\": \"b13e1b562d2c9f07cdea69d5b734c1b2f3a746f0\", \"gwp_asan_unittests\": \"50a9bc821f47fe5df5cf8b3f4c7c5ee464e12086\", \"interactive_ui_tests\": \"afbcc9ce3df27f0c8cd402b57fa192d3eee38f30\", \"ipc_tests\": \"364e6e1b4c3a9fd0c0e62c2cf6392d78f07fb003\", \"jingle_unittests\": \"3c1966dc4063ebd553cea757fa3f2a2bc47dbdd4\", \"keyboard_unittests\": \"ab3ceb625c9febf0cd352836b289bba2d27a026b\", \"latency_unittests\": \"520906e46e3d0b80426309f2e8dbbc7b84164c6c\", \"libjingle_xmpp_unittests\": \"dafdb6896a0728b5b33eddfeab102568e22f2f06\", \"liburlpattern_unittests\": \"ac048f8c80c42ca9d6fbad38723fdb2360175c18\", \"media_blink_unittests\": \"91ce5af8a7f80ee45e84a26aa5feaafcdcf29bdb\", \"media_unittests\": \"4336dba77139f29e13bf2792eb7777ca21e1cc75\", \"message_center_unittests\": \"5cbfe0cbeef527c322fc15b4eb4c03a174d2866e\", \"midi_unittests\": \"db8ca3b34be45cd2c367a4f2a9bb7bdab0c2b261\", \"mojo_core_unittests\": \"b19a3698ac69456e6102ef16daca7a50e6e0488f\", \"mojo_unittests\": \"832c72203fea66dd58c3aa8b9543a65a0be833cf\", \"nacl_helper_nonsfi_unittests\": \"7daa73ea145733ba9f882c07ea353fd0a5274394\", \"nacl_loader_unittests\": \"746ed7b6bec776cec05412900f2b7ee632fdd5ef\", \"native_theme_unittests\": \"ead1f25e7352b3425b1f198f7a87456b9c886921\", \"net_unittests\": \"2939a749fb90f0652fc594aa118653881de1e77b\", \"ozone_gl_unittests\": \"04bb2942c2db4b93118c6dfa660868b044289a6c\", \"ozone_unittests\": \"764d84f4f5d38634b5e029fb948a32c291fca0ee\", \"ozone_x11_unittests\": \"547d9635044da9b82b4b84cf60533b6b0b1d3663\", \"pdf_unittests\": \"c6b9d8e6b9f60e795d2638afeaa4ad8fa00ec3fa\", \"perfetto_unittests\": \"16cdb277c626d64e600a78cbe013966882c02ed9\", \"ppapi_unittests\": \"95ece44edebc6a1e6870adf57fe138ac51c6acc1\", \"printing_unittests\": \"9cf36afe5e3542fd4139b9219b020d5c7862bf22\", \"remoting_unittests\": \"55176b89b5485a06a6d825ed3dd1e038a0fd7ffa\", \"sandbox_linux_unittests\": \"1ca57f90aa0b85636fd4c658785ed8b595c76a2a\", \"service_manager_unittests\": \"bd04743fe2619ebb94cec892f33def46c957c10e\", \"services_unittests\": \"fe579b39720cd1a5df0372c4c5be4c95a712fe38\", \"shell_dialogs_unittests\": \"5a32f19652e9867edd4c35c3f3462e454bbb7c2d\", \"skia_unittests\": \"dd86e3c64cda47d0c41e5fe022b75a04dbd981a7\", \"snapshot_unittests\": \"5138fd983b0f6cc413768d7e1347c9245c916a1f\", \"sql_unittests\": \"3bcc959d565701ced1d7a0b3d7e2be7b1f7bde9c\", \"storage_unittests\": \"5fab3e2e6c7a5816a098ff825f2535fb87417bca\", \"sync_integration_tests\": \"671cc63b7a159e19b0323884fcfe28a30f35984f\", \"traffic_annotation_auditor_unittests\": \"4f104f4d5945040e196d60851368601b464a66dc\", \"ui_base_unittests\": \"671902a9a734a708cc06572e4d8b0c37bc7470da\", \"ui_chromeos_unittests\": \"bd8c666560f3d2ab894d0dba1b8ce7734a83ac64\", \"ui_touch_selection_unittests\": \"de08b7fb4a5466248d509c488c3a2d307629d766\", \"unit_tests\": \"d7a20f64d36432b46940b09cfa5565417551a0f3\", \"url_unittests\": \"d7cbf1002a73045ad7b6d43c63c643b33dacf4a8\", \"usage_time_limit_unittests\": \"5a8ed09db10b31f9b0fad167a71d93b85b3c971d\", \"views_unittests\": \"abe9c9cf72a1741c2e257e4490ecff140bf75510\", \"viz_unittests\": \"553e14b308a4e1306f8c7548171150d472b670c2\", \"wayland_client_perftests\": \"25e67549ec1e2e306daab4714bc2afc4ae37b2a2\", \"wm_unittests\": \"7c66321cee2a63110d400102349a87e429e74556\", \"wtf_unittests\": \"9cb682f4a256621c342e25759669938957305677\", \"zlib_unittests\": \"922bc4e1f5453573cf5ec0b6514275086de6e42e\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_510bb217f1fbd7cfc6f82b4f5538258f70582616cab4052bd10c7cd8994fe33a_v2\", \"builder_756d7885c4e2fdf98a0ecf3a0240e3f6188de5be53188a09baa9eae15dedd66f_v2\", \"builder_94fa3119a28f4651cdd4fc5b981a64c69b26c151212072333439362290628993_v2\", \"builder_d6e5378aee5938d080eb0dcdbd0211f3098981aa309245e37202498e30b4f23b_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-east1-d-8-114-jw8n\"], \"image\": [\"chrome-xenial-20110100-7e97731f9b6\"], \"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\": [\"5626-39642e9\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-d\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1605008170617061",
"status": "COMPLETED",
"status_changed_ts": "1605011852315719",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/42746",
"builder:linux-chromeos-rel",
"buildset:commit/git/cf22557f3b446970d634e134fc03efb864e3aed6",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/cf22557f3b446970d634e134fc03efb864e3aed6",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9029450739723056304",
"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/8864020675769022960/+/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:4fcac29640292e10",
"user_agent:luci-scheduler"
],
"updated_ts": "1605011852325804",
"url": "https://ci.chromium.org/b/8864020675769022960",
"utcnow_ts": "1605011852934345"
}
}
[12:37:32.948] Invocation finished in 1h1m32.469414234s with status SUCCEEDED