[22:56:57.457] New invocation is queued and will start shortly
[22:56:58.530] Starting the invocation (attempt 1)
[22:56:58.580] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[22:56:58.580] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[22:56:58.581] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9017992680326612544",
"parameters_json": "{\"builder_name\":\"linux-chromeos-rel\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"41f0d44e8e7f1cb6726ea8ba3c5c1e76918ae959\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-rel",
"scheduler_invocation_id:9017992680326612544",
"scheduler_job_id:chromium/linux-chromeos-rel",
"user_agent:luci-scheduler",
"buildset:commit/git/41f0d44e8e7f1cb6726ea8ba3c5c1e76918ae959",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/41f0d44e8e7f1cb6726ea8ba3c5c1e76918ae959",
"gitiles_ref:refs/heads/master"
]
}
[22:56:59.827] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1615935418723086",
"id": "8852562618335031728",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"41f0d44e8e7f1cb6726ea8ba3c5c1e76918ae959\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1615935419545992",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/46196",
"builder:linux-chromeos-rel",
"buildset:commit/git/41f0d44e8e7f1cb6726ea8ba3c5c1e76918ae959",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/41f0d44e8e7f1cb6726ea8ba3c5c1e76918ae959",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9017992680326612544",
"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/8852562618335031728/+/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": "1615935419546113",
"url": "https://ci.chromium.org/b/8852562618335031728",
"utcnow_ts": "1615935419809654"
}
}
[22:56:59.827] Task URL: https://ci.chromium.org/b/8852562618335031728
[22:56:59.827] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017992680326612544:2:0) after 1m0s
[22:57:03.165] Received PubSub notification, asking Buildbucket for the build status
[22:57:03.561] Build 8852562618335031728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:57:59.875] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017992680326612544:2:0)
[22:57:59.875] Timer tick, asking Buildbucket for the build status
[22:57:59.944] Build 8852562618335031728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:57:59.945] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017992680326612544:4:0) after 1m0s
[22:59:00.003] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017992680326612544:4:0)
[22:59:00.003] Timer tick, asking Buildbucket for the build status
[22:59:00.492] Build 8852562618335031728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:59:00.492] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017992680326612544:5:0) after 1m0s
[23:00:00.565] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017992680326612544:5:0)
[23:00:00.565] Timer tick, asking Buildbucket for the build status
[23:00:00.626] Build 8852562618335031728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:00:00.626] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017992680326612544:6:0) after 1m0s
[23:01:00.718] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017992680326612544:6:0)
[23:01:00.718] Timer tick, asking Buildbucket for the build status
[23:01:00.798] Build 8852562618335031728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:01:00.798] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017992680326612544:7:0) after 1m0s
[23:02:00.834] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017992680326612544:7:0)
[23:02:00.834] Timer tick, asking Buildbucket for the build status
[23:02:06.663] Build 8852562618335031728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:02:06.663] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017992680326612544:8:0) after 1m0s
[23:03:06.747] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017992680326612544:8:0)
[23:03:06.747] Timer tick, asking Buildbucket for the build status
[23:03:06.819] Build 8852562618335031728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:03:06.819] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017992680326612544:9:0) after 1m0s
[23:04:06.884] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017992680326612544:9:0)
[23:04:06.884] Timer tick, asking Buildbucket for the build status
[23:04:07.399] Build 8852562618335031728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:04:07.399] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017992680326612544:10:0) after 1m0s
[23:05:07.688] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017992680326612544:10:0)
[23:05:07.688] Timer tick, asking Buildbucket for the build status
[23:05:07.796] Build 8852562618335031728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:05:07.796] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017992680326612544:11:0) after 1m0s
[23:06:07.946] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017992680326612544:11:0)
[23:06:07.946] Timer tick, asking Buildbucket for the build status
[23:06:08.115] Build 8852562618335031728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:06:08.115] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017992680326612544:12:0) after 1m0s
[23:07:08.552] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017992680326612544:12:0)
[23:07:08.552] Timer tick, asking Buildbucket for the build status
[23:07:09.511] Build 8852562618335031728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:07:09.511] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017992680326612544:13:0) after 1m0s
[23:08:09.656] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017992680326612544:13:0)
[23:08:09.656] Timer tick, asking Buildbucket for the build status
[23:08:09.870] Build 8852562618335031728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:08:09.870] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017992680326612544:14:0) after 1m0s
[23:09:09.907] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017992680326612544:14:0)
[23:09:09.907] Timer tick, asking Buildbucket for the build status
[23:09:09.980] Build 8852562618335031728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:09:09.980] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017992680326612544:15:0) after 1m0s
[23:10:10.075] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017992680326612544:15:0)
[23:10:10.075] Timer tick, asking Buildbucket for the build status
[23:10:10.148] Build 8852562618335031728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:10:10.148] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017992680326612544:16:0) after 1m0s
[23:11:10.247] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017992680326612544:16:0)
[23:11:10.247] Timer tick, asking Buildbucket for the build status
[23:11:10.344] Build 8852562618335031728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:11:10.344] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017992680326612544:17:0) after 1m0s
[23:12:10.444] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017992680326612544:17:0)
[23:12:10.444] Timer tick, asking Buildbucket for the build status
[23:12:10.703] Build 8852562618335031728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:12:10.704] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017992680326612544:18:0) after 1m0s
[23:13:10.722] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017992680326612544:18:0)
[23:13:10.722] Timer tick, asking Buildbucket for the build status
[23:13:10.781] Build 8852562618335031728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:13:10.781] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017992680326612544:19:0) after 1m0s
[23:14:10.825] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017992680326612544:19:0)
[23:14:10.825] Timer tick, asking Buildbucket for the build status
[23:14:10.898] Build 8852562618335031728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:14:10.898] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017992680326612544:20:0) after 1m0s
[23:15:10.976] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017992680326612544:20:0)
[23:15:10.976] Timer tick, asking Buildbucket for the build status
[23:15:11.043] Build 8852562618335031728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:15:11.043] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017992680326612544:21:0) after 1m0s
[23:16:11.119] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017992680326612544:21:0)
[23:16:11.119] Timer tick, asking Buildbucket for the build status
[23:16:11.303] Build 8852562618335031728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:16:11.303] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017992680326612544:22:0) after 1m0s
[23:17:11.320] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017992680326612544:22:0)
[23:17:11.320] Timer tick, asking Buildbucket for the build status
[23:17:11.412] Build 8852562618335031728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:17:11.412] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017992680326612544:23:0) after 1m0s
[23:18:11.420] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017992680326612544:23:0)
[23:18:11.420] Timer tick, asking Buildbucket for the build status
[23:18:11.489] Build 8852562618335031728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:18:11.489] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017992680326612544:24:0) after 1m0s
[23:19:11.534] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017992680326612544:24:0)
[23:19:11.534] Timer tick, asking Buildbucket for the build status
[23:19:11.603] Build 8852562618335031728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:19:11.604] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017992680326612544:25:0) after 1m0s
[23:20:11.679] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017992680326612544:25:0)
[23:20:11.679] Timer tick, asking Buildbucket for the build status
[23:20:11.749] Build 8852562618335031728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:20:11.749] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017992680326612544:26:0) after 1m0s
[23:21:11.827] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017992680326612544:26:0)
[23:21:11.827] Timer tick, asking Buildbucket for the build status
[23:21:12.212] Build 8852562618335031728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:21:12.212] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017992680326612544:27:0) after 1m0s
[23:22:12.306] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017992680326612544:27:0)
[23:22:12.306] Timer tick, asking Buildbucket for the build status
[23:22:12.389] Build 8852562618335031728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:22:12.389] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017992680326612544:28:0) after 1m0s
[23:23:12.411] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017992680326612544:28:0)
[23:23:12.411] Timer tick, asking Buildbucket for the build status
[23:23:12.545] Build 8852562618335031728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:23:12.545] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017992680326612544:29:0) after 1m0s
[23:24:12.602] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017992680326612544:29:0)
[23:24:12.602] Timer tick, asking Buildbucket for the build status
[23:24:12.697] Build 8852562618335031728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:24:12.697] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017992680326612544:30:0) after 1m0s
[23:25:12.755] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017992680326612544:30:0)
[23:25:12.755] Timer tick, asking Buildbucket for the build status
[23:25:12.841] Build 8852562618335031728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:25:12.841] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017992680326612544:31:0) after 1m0s
[23:26:12.882] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017992680326612544:31:0)
[23:26:12.882] Timer tick, asking Buildbucket for the build status
[23:26:12.974] Build 8852562618335031728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:26:12.974] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017992680326612544:32:0) after 1m0s
[23:27:12.991] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017992680326612544:32:0)
[23:27:12.991] Timer tick, asking Buildbucket for the build status
[23:27:13.358] Build 8852562618335031728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:27:13.358] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017992680326612544:33:0) after 1m0s
[23:28:13.375] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017992680326612544:33:0)
[23:28:13.375] Timer tick, asking Buildbucket for the build status
[23:28:13.440] Build 8852562618335031728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:28:13.440] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017992680326612544:34:0) after 1m0s
[23:29:13.457] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017992680326612544:34:0)
[23:29:13.457] Timer tick, asking Buildbucket for the build status
[23:29:13.561] Build 8852562618335031728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:29:13.561] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017992680326612544:35:0) after 1m0s
[23:30:13.547] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017992680326612544:35:0)
[23:30:13.547] Timer tick, asking Buildbucket for the build status
[23:30:13.795] Build 8852562618335031728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:30:13.795] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017992680326612544:36:0) after 1m0s
[23:31:13.860] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017992680326612544:36:0)
[23:31:13.860] Timer tick, asking Buildbucket for the build status
[23:31:13.962] Build 8852562618335031728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:31:13.962] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017992680326612544:37:0) after 1m0s
[23:32:13.981] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017992680326612544:37:0)
[23:32:13.981] Timer tick, asking Buildbucket for the build status
[23:32:14.139] Build 8852562618335031728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:32:14.139] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017992680326612544:38:0) after 1m0s
[23:33:14.124] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017992680326612544:38:0)
[23:33:14.124] Timer tick, asking Buildbucket for the build status
[23:33:14.232] Build 8852562618335031728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:33:14.232] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017992680326612544:39:0) after 1m0s
[23:34:14.274] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017992680326612544:39:0)
[23:34:14.274] Timer tick, asking Buildbucket for the build status
[23:34:14.354] Build 8852562618335031728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:34:14.354] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017992680326612544:40:0) after 1m0s
[23:35:14.462] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017992680326612544:40:0)
[23:35:14.462] Timer tick, asking Buildbucket for the build status
[23:35:14.538] Build 8852562618335031728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:35:14.538] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017992680326612544:41:0) after 1m0s
[23:36:14.553] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017992680326612544:41:0)
[23:36:14.553] Timer tick, asking Buildbucket for the build status
[23:36:14.617] Build 8852562618335031728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:36:14.617] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017992680326612544:42:0) after 1m0s
[23:37:14.649] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017992680326612544:42:0)
[23:37:14.649] Timer tick, asking Buildbucket for the build status
[23:37:14.744] Build 8852562618335031728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:37:14.744] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017992680326612544:43:0) after 1m0s
[23:38:14.809] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017992680326612544:43:0)
[23:38:14.809] Timer tick, asking Buildbucket for the build status
[23:38:14.904] Build 8852562618335031728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:38:14.904] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017992680326612544:44:0) after 1m0s
[23:39:15.159] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017992680326612544:44:0)
[23:39:15.159] Timer tick, asking Buildbucket for the build status
[23:39:15.316] Build 8852562618335031728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:39:15.316] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017992680326612544:45:0) after 1m0s
[23:40:15.342] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017992680326612544:45:0)
[23:40:15.342] Timer tick, asking Buildbucket for the build status
[23:40:15.413] Build 8852562618335031728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:40:15.413] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017992680326612544:46:0) after 1m0s
[23:41:15.558] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017992680326612544:46:0)
[23:41:15.558] Timer tick, asking Buildbucket for the build status
[23:41:15.775] Build 8852562618335031728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:41:15.775] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017992680326612544:47:0) after 1m0s
[23:42:15.793] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017992680326612544:47:0)
[23:42:15.793] Timer tick, asking Buildbucket for the build status
[23:42:15.989] Build 8852562618335031728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:42:15.989] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017992680326612544:48:0) after 1m0s
[23:43:16.079] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017992680326612544:48:0)
[23:43:16.079] Timer tick, asking Buildbucket for the build status
[23:43:16.216] Build 8852562618335031728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:43:16.216] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017992680326612544:49:0) after 1m0s
[23:44:16.260] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017992680326612544:49:0)
[23:44:16.260] Timer tick, asking Buildbucket for the build status
[23:44:16.351] Build 8852562618335031728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:44:16.351] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017992680326612544:50:0) after 1m0s
[23:45:16.393] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017992680326612544:50:0)
[23:45:16.393] Timer tick, asking Buildbucket for the build status
[23:45:16.558] Build 8852562618335031728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:45:16.558] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017992680326612544:51:0) after 1m0s
[23:46:16.553] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017992680326612544:51:0)
[23:46:16.554] Timer tick, asking Buildbucket for the build status
[23:46:16.906] Build 8852562618335031728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:46:16.906] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017992680326612544:52:0) after 1m0s
[23:47:16.953] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017992680326612544:52:0)
[23:47:16.953] Timer tick, asking Buildbucket for the build status
[23:47:17.250] Build 8852562618335031728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:47:17.250] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017992680326612544:53:0) after 1m0s
[23:48:17.272] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017992680326612544:53:0)
[23:48:17.272] Timer tick, asking Buildbucket for the build status
[23:48:17.358] Build 8852562618335031728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:48:17.358] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017992680326612544:54:0) after 1m0s
[23:49:17.378] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017992680326612544:54:0)
[23:49:17.378] Timer tick, asking Buildbucket for the build status
[23:49:17.616] Build 8852562618335031728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:49:17.616] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017992680326612544:55:0) after 1m0s
[23:50:17.622] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017992680326612544:55:0)
[23:50:17.622] Timer tick, asking Buildbucket for the build status
[23:50:17.702] Build 8852562618335031728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:50:17.702] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9017992680326612544:56:0) after 1m0s
[23:51:02.785] Received PubSub notification, asking Buildbucket for the build status
[23:51:02.880] Build 8852562618335031728: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[23:51:02.880] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1615938661922902",
"created_by": "project:chromium",
"created_ts": "1615935418723086",
"id": "8852562618335031728",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"41f0d44e8e7f1cb6726ea8ba3c5c1e76918ae959\"}}",
"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\": \"41f0d44e8e7f1cb6726ea8ba3c5c1e76918ae959\", \"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-166-3f40\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-rel\", \"buildnumber\": 46196, \"got_angle_revision\": \"c99e405cc2b3f1c358bf2e594ba8c9d4658bc82c\", \"got_dawn_revision\": \"cc84ee24fc89229d956a6cd1a8ed0f7fd7528c1b\", \"got_nacl_revision\": \"abf3277f295c6c87f0b103f291ed5e3e73933b76\", \"got_revision\": \"41f0d44e8e7f1cb6726ea8ba3c5c1e76918ae959\", \"got_revision_cp\": \"refs/heads/master@{#863516}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"a0aeb64e01d75b257495eda180bea9758d072005\", \"got_v8_revision\": \"c47dfc8ab1b8c17a6959f42e069091a38e72c040\", \"got_v8_revision_cp\": \"refs/heads/9.1.140@{#1}\", \"got_webrtc_revision\": \"ab6335041130fdc0365a604f49668eac9194f9d6\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33479}\", \"is_cached\": true, \"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\": \"41f0d44e8e7f1cb6726ea8ba3c5c1e76918ae959\", \"swarm_hashes_refs/heads/master(at){#863516}_without_patch\": {\"absl_hardening_tests\": \"09a75f857b8735ea1ead1e094b98d0fe441d7c96\", \"accessibility_unittests\": \"42814b2a60f14c6e9fea68ef48c6259bd250b07b\", \"angle_unittests\": \"45807eafdcb3aa435fc7ed2081c3a1c64817888b\", \"app_list_unittests\": \"100aef591b30cb3968a2cebd470ec990b1922e2d\", \"app_shell_unittests\": \"7dbb442c6c89e8925012271b447e359905535e5a\", \"ash_unittests\": \"21ec0a5d3caa3e6e7f9065fca6d913b3fd11fbba\", \"aura_unittests\": \"bf4639304fee6347b609bfacd25186c10bcdb200\", \"base_unittests\": \"47c07d54efbb99a7110ecc58a4473edca8347240\", \"base_util_unittests\": \"a6d8a2f75a9dbc361df44b882d3e1c256e7614c1\", \"blink_common_unittests\": \"4417cf8f6f57cd209aab39f5fae8604e08954245\", \"blink_fuzzer_unittests\": \"51774eff0be104159554490273c0ee05482f7ac0\", \"blink_heap_unittests\": \"68f032499568b21b2a2bd2c6be976280d873313f\", \"blink_platform_unittests\": \"4c233cf2de34687a716d40bfa1a6d00b3b1481b2\", \"blink_unittests\": \"580066727ee94bfa27276ae7683572eadea47ad4\", \"boringssl_crypto_tests\": \"8e6103e98e37508c7e25331a557b96b71fe2f970\", \"boringssl_ssl_tests\": \"6633fcfaea23a70d368f4688429153a4b152d6e0\", \"browser_tests\": \"08dc9ac85ae4b5ace6fbdfc82cb0308c3d7b040a\", \"capture_unittests\": \"4b82640f9bda0ceb46975dea9de48092b7a77b2b\", \"cast_unittests\": \"502f551cb06c6319508be107870ecb62b6b47442\", \"cc_unittests\": \"456bf329080bbfe94aee825a583d61430df0a19c\", \"chrome_app_unittests\": \"4c365df4899c901d6f08c5f8601e19075f0dadcb\", \"chromedriver_unittests\": \"25de89efe0fdb0db0cb03e22b3f54500c66bf698\", \"chromeos_components_unittests\": \"43141d8f68359f404d5423c6ec001344c46a0f06\", \"chromeos_unittests\": \"6fcf2b34e111b9d7d74749fcd634407a8b2ebe74\", \"color_unittests\": \"532f9aef060d330e6a7d2f908a2193df2dd4d76a\", \"components_browsertests\": \"14753bb98f9b95c85dc617e9a329146687da89c9\", \"components_unittests\": \"a0982f1e95dd07a53751a350150917b0042826bd\", \"compositor_unittests\": \"8508468b6dfff20f5ba708173ed90f22ecbefd28\", \"content_browsertests\": \"667f1aec32bc6e0d424bb865ac452e28f371f13c\", \"content_unittests\": \"cbcafef729c5b087e36dc3074902c29ec1dda2ca\", \"crashpad_tests\": \"7d0d3f35558f4e3d6a450f17a604b73f7969e781\", \"crypto_unittests\": \"9a6585219333c000eeeac2f00980d2d38383c1d6\", \"dbus_unittests\": \"617457838f0c26d698c0f3c6be80678df6d92d24\", \"device_unittests\": \"51899c00ca4d76bbad8f07db3cd7459a24ced898\", \"display_unittests\": \"8c62a97436f67822ddd3de01d3674643191017f0\", \"events_unittests\": \"951609186c4cb329ab91d32890c4cc1ded03b9b0\", \"exo_unittests\": \"4e742a1083ff4f9d7cceec1226bd6bc9a8155740\", \"extensions_browsertests\": \"aa20f1f01196e72c4e4348d6ce4dd6b7a1ad03a7\", \"extensions_unittests\": \"a7c1e78c047577322ae8291f1a3e3280200d0cf8\", \"filesystem_service_unittests\": \"7fe4df8dbc8a5e9751da74b8fb82059d62e01a56\", \"gcm_unit_tests\": \"1bb078cec163047db6062d2bdcbc248c92149941\", \"gfx_unittests\": \"830b3993669221a71c6c549981129c2cd3190c37\", \"gin_unittests\": \"3e8181952d195dbf89d7e2ea1bc4197d8c8670bb\", \"gl_unittests_ozone\": \"6b3f99851333e2da50a289cf02eb5769fc03e938\", \"google_apis_unittests\": \"afba2a9f2beb5f73c09a1e32aed5b387ba4dd841\", \"gpu_unittests\": \"3d7bb68d17cd5c2349f9310a77cb8455bab82f77\", \"gwp_asan_unittests\": \"80c6107d4a8356b36ed0a3643d2bec6567711e68\", \"interactive_ui_tests\": \"b312fc51ec87cfd0bf262ee034447419ff71f9bd\", \"ipc_tests\": \"af2e917b98bc02ddd9efbb190d0feb20d1369d77\", \"jingle_unittests\": \"81f0f87cd6da1e4d8c98a05c28ca2b19f4bc156c\", \"keyboard_unittests\": \"02ea1eeb0b23f5c9e12404a890fc2cce406c8593\", \"latency_unittests\": \"6d5700b0c57455b80658b938d5fa15ee4be0185b\", \"libjingle_xmpp_unittests\": \"1bf44bd3c0f02b249f74df30cc4749bd0a465461\", \"liburlpattern_unittests\": \"e03cedeb0556caf571bc126cde5ddae7401926a6\", \"media_blink_unittests\": \"c534e30a8e755a3f64874a2e128117e5275b10dd\", \"media_unittests\": \"770d3082ce0708c8c0a18c740c30f234a2b87ae9\", \"message_center_unittests\": \"eeba201bcac4a31e31932509d57d3664fde87d38\", \"midi_unittests\": \"d53c09b55eb748a765a743159c8f3a96ce04a06b\", \"mojo_core_unittests\": \"0e1c2ebb2d7362855ab98e825e288c09a0684310\", \"mojo_unittests\": \"cad8689460e6412ed8a9c623a77f3cfa6492f33e\", \"nacl_helper_nonsfi_unittests\": \"36364424047c4c83da458f5e29b89504d2c35209\", \"nacl_loader_unittests\": \"1d0b91bdc03d1bfe2e945603f0e51b7295e82fa7\", \"native_theme_unittests\": \"be4f2f3776d8cdbf225689dff792adb5f9c162b7\", \"net_unittests\": \"bf46f07c3676a67443794b16e12c6dafa49d204a\", \"ozone_gl_unittests\": \"eadca2124628d19ad3424012d07b7fc9a7662c68\", \"ozone_unittests\": \"b26dbe0207b22d39afe8d6e6aff4f484a3c934f8\", \"ozone_x11_unittests\": \"a3ab6ef99578a6aea539e759d33d7f630d581021\", \"pdf_unittests\": \"6ba0e35d375fcccc93ac2d518a79b33ad0af1b68\", \"perfetto_unittests\": \"e5bdf92ac8335a9942238310e6e48e769f16770a\", \"ppapi_unittests\": \"2e5da9dc7815ce10d16253e346212e35b3d7d4ae\", \"printing_unittests\": \"fcb6087a61fb1fd466ae08dc7a0c1c2bbe40239b\", \"remoting_unittests\": \"a964a7702e777cce57dfce66c854c7f4493c41f3\", \"sandbox_linux_unittests\": \"7cdabaf719dd09883c19c13f0e047fa5f8cda040\", \"service_manager_unittests\": \"085f30fdb3760b7b820f57f714ca37aef9afcf02\", \"services_unittests\": \"f58d4f5644032f49dd5113abad381895dc0fe592\", \"shell_dialogs_unittests\": \"044c400e0be13bc32daebcf69f6e2e1a88a95fa8\", \"shell_encryption_unittests\": \"bd949448cce9b2539bf06e52e8cfac58facf480c\", \"skia_unittests\": \"69d2941de25017ba0d8ce74e9cb8f325254d4d54\", \"snapshot_unittests\": \"ca7f7002413ff196349c30d1e840c75c507a4c8e\", \"sql_unittests\": \"d984433c79e2b4540254e1193c19928d5dcb6f9c\", \"storage_unittests\": \"abd6550d3d0a48ce7e5b0dc2750b04906bc953f9\", \"sync_integration_tests\": \"530b132674f17394890d8978f502c263c94cb34a\", \"traffic_annotation_auditor_unittests\": \"3b0c5ebd6cd4b03c4a8911bcd27a98b9b79b88d4\", \"ui_base_unittests\": \"3b785c1ee09ff6db18843b22b880710e8d6ed466\", \"ui_chromeos_unittests\": \"3e960c535bf4d8451194d996ef6876761aac01ba\", \"ui_touch_selection_unittests\": \"5aacb073cf665c6aa33d885b1f2da92b0bf14706\", \"unit_tests\": \"9457f98c9f1af94947f0c64bdb564bbed5cf7f2b\", \"url_unittests\": \"628ae595fda6611ec88f42ceb69423f0f8fa7a84\", \"usage_time_limit_unittests\": \"d63daf9bb0765db039a347fabf2bf6e98ee5bf3b\", \"views_unittests\": \"ac51d1f41fa8945a8c5e7cba8e1b6fecd69f3176\", \"viz_unittests\": \"2d892873c4be43e6750f94f3c2819f70fde21865\", \"wayland_client_perftests\": \"7ff09496bccb038096ef0316e3eff4bc1c494af4\", \"wayland_client_tests\": \"26f2ec1a2c0509921a8a1e1f7607c445f0727678\", \"wm_unittests\": \"3aa8412c6012cf2fccb8e58135de587e819f5d69\", \"wtf_unittests\": \"15d635df60aa55238e4ac75d0a5a692bfe7e749b\", \"zlib_unittests\": \"d4b8749b60f3e27002bc5806271d940afde0bf5f\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_304607b978b8f4bcd63bac0f543c3a69f6b4c7cf202dcd2d3c5b8d4a70be201e_v2\", \"builder_61cf7edf32cfa6f409296179bf0eb529cec28454dd3732e2fae2e14176388844_v2\", \"builder_6440ef9dedd0dc3b34dcb22ab34f3415d4cc49eb5cc8c1aeab1a9757364e9da5_v2\", \"builder_94fa3119a28f4651cdd4fc5b981a64c69b26c151212072333439362290628993_v2\", \"builder_97b15bccca8d35086c1bc50eecf0b53d98289cdc65be75a287efcc384d1f34ed_v2\", \"builder_9b311965bcdd39b39aa4e18428ed38a374d2d5d9f2ab24593845b266390676f3_v2\", \"builder_c6f1473df00e57847ed4ff0f74718f3dfb2a5d42012d3d3fe6b396b4c9a29f62_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-166-3f40\"], \"image\": [\"chrome-xenial-21011100-9488e34e558\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"e2-standard-8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16\", \"Ubuntu-16.04\", \"Ubuntu-16.04.6\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.12\"], \"server_version\": [\"5754-8c4f760\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1615935421423542",
"status": "COMPLETED",
"status_changed_ts": "1615938662179941",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/46196",
"builder:linux-chromeos-rel",
"buildset:commit/git/41f0d44e8e7f1cb6726ea8ba3c5c1e76918ae959",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/41f0d44e8e7f1cb6726ea8ba3c5c1e76918ae959",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9017992680326612544",
"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/8852562618335031728/+/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:5256133dfc77be10",
"user_agent:luci-scheduler"
],
"updated_ts": "1615938662185608",
"url": "https://ci.chromium.org/b/8852562618335031728",
"utcnow_ts": "1615938662868812"
}
}
[23:51:02.880] Invocation finished in 54m5.440509422s with status SUCCEEDED