[18:15:36.845] New invocation is queued and will start shortly
[18:15:37.992] Starting the invocation (attempt 1)
[18:15:38.061] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[18:15:38.061] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[18:15:38.061] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9023808586800665440",
"parameters_json": "{\"builder_name\":\"linux-chromeos-rel\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"37b032d626cf5cd9c5a56ea0f037cf3eca2fb319\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-rel",
"scheduler_invocation_id:9023808586800665440",
"scheduler_job_id:chromium/linux-chromeos-rel",
"user_agent:luci-scheduler",
"buildset:commit/git/37b032d626cf5cd9c5a56ea0f037cf3eca2fb319",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/37b032d626cf5cd9c5a56ea0f037cf3eca2fb319",
"gitiles_ref:refs/heads/master"
]
}
[18:15:38.728] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1610388938166337",
"id": "8858378524732370224",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"37b032d626cf5cd9c5a56ea0f037cf3eca2fb319\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1610388938547045",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/44512",
"builder:linux-chromeos-rel",
"buildset:commit/git/37b032d626cf5cd9c5a56ea0f037cf3eca2fb319",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/37b032d626cf5cd9c5a56ea0f037cf3eca2fb319",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023808586800665440",
"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/8858378524732370224/+/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": "1610388938547190",
"url": "https://ci.chromium.org/b/8858378524732370224",
"utcnow_ts": "1610388938716291"
}
}
[18:15:38.728] Task URL: https://ci.chromium.org/b/8858378524732370224
[18:15:38.729] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023808586800665440:2:0) after 1m0s
[18:15:42.989] Received PubSub notification, asking Buildbucket for the build status
[18:15:43.110] Build 8858378524732370224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:16:38.752] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023808586800665440:2:0)
[18:16:38.752] Timer tick, asking Buildbucket for the build status
[18:16:38.978] Build 8858378524732370224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:16:38.978] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023808586800665440:4:0) after 1m0s
[18:17:39.028] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023808586800665440:4:0)
[18:17:39.028] Timer tick, asking Buildbucket for the build status
[18:17:39.106] Build 8858378524732370224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:17:39.106] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023808586800665440:5:0) after 1m0s
[18:18:39.152] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023808586800665440:5:0)
[18:18:39.152] Timer tick, asking Buildbucket for the build status
[18:18:39.344] Build 8858378524732370224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:18:39.344] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023808586800665440:6:0) after 1m0s
[18:19:39.385] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023808586800665440:6:0)
[18:19:39.385] Timer tick, asking Buildbucket for the build status
[18:19:39.625] Build 8858378524732370224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:19:39.625] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023808586800665440:7:0) after 1m0s
[18:20:39.650] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023808586800665440:7:0)
[18:20:39.650] Timer tick, asking Buildbucket for the build status
[18:20:39.923] Build 8858378524732370224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:20:39.923] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023808586800665440:8:0) after 1m0s
[18:21:39.935] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023808586800665440:8:0)
[18:21:39.935] Timer tick, asking Buildbucket for the build status
[18:21:41.859] Build 8858378524732370224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:21:41.859] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023808586800665440:9:0) after 1m0s
[18:22:41.971] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023808586800665440:9:0)
[18:22:41.971] Timer tick, asking Buildbucket for the build status
[18:22:42.353] Build 8858378524732370224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:22:42.353] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023808586800665440:10:0) after 1m0s
[18:23:42.387] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023808586800665440:10:0)
[18:23:42.387] Timer tick, asking Buildbucket for the build status
[18:23:43.207] Build 8858378524732370224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:23:43.207] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023808586800665440:11:0) after 1m0s
[18:24:43.288] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023808586800665440:11:0)
[18:24:43.288] Timer tick, asking Buildbucket for the build status
[18:24:43.373] Build 8858378524732370224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:24:43.373] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023808586800665440:12:0) after 1m0s
[18:25:43.491] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023808586800665440:12:0)
[18:25:43.491] Timer tick, asking Buildbucket for the build status
[18:25:43.671] Build 8858378524732370224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:25:43.671] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023808586800665440:13:0) after 1m0s
[18:26:43.737] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023808586800665440:13:0)
[18:26:43.737] Timer tick, asking Buildbucket for the build status
[18:26:43.808] Build 8858378524732370224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:26:43.808] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023808586800665440:14:0) after 1m0s
[18:27:43.884] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023808586800665440:14:0)
[18:27:43.884] Timer tick, asking Buildbucket for the build status
[18:27:44.043] Build 8858378524732370224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:27:44.043] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023808586800665440:15:0) after 1m0s
[18:28:44.106] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023808586800665440:15:0)
[18:28:44.106] Timer tick, asking Buildbucket for the build status
[18:28:44.192] Build 8858378524732370224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:28:44.192] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023808586800665440:16:0) after 1m0s
[18:29:44.223] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023808586800665440:16:0)
[18:29:44.223] Timer tick, asking Buildbucket for the build status
[18:29:44.322] Build 8858378524732370224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:29:44.322] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023808586800665440:17:0) after 1m0s
[18:30:44.573] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023808586800665440:17:0)
[18:30:44.573] Timer tick, asking Buildbucket for the build status
[18:30:44.708] Build 8858378524732370224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:30:44.708] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023808586800665440:18:0) after 1m0s
[18:31:44.783] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023808586800665440:18:0)
[18:31:44.783] Timer tick, asking Buildbucket for the build status
[18:31:44.866] Build 8858378524732370224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:31:44.866] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023808586800665440:19:0) after 1m0s
[18:32:44.910] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023808586800665440:19:0)
[18:32:44.910] Timer tick, asking Buildbucket for the build status
[18:32:45.028] Build 8858378524732370224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:32:45.028] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023808586800665440:20:0) after 1m0s
[18:33:45.075] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023808586800665440:20:0)
[18:33:45.075] Timer tick, asking Buildbucket for the build status
[18:33:45.188] Build 8858378524732370224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:33:45.188] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023808586800665440:21:0) after 1m0s
[18:34:45.240] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023808586800665440:21:0)
[18:34:45.240] Timer tick, asking Buildbucket for the build status
[18:34:45.303] Build 8858378524732370224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:34:45.303] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023808586800665440:22:0) after 1m0s
[18:35:45.499] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023808586800665440:22:0)
[18:35:45.499] Timer tick, asking Buildbucket for the build status
[18:35:46.140] Build 8858378524732370224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:35:46.140] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023808586800665440:23:0) after 1m0s
[18:36:46.204] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023808586800665440:23:0)
[18:36:46.204] Timer tick, asking Buildbucket for the build status
[18:36:46.361] Build 8858378524732370224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:36:46.361] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023808586800665440:24:0) after 1m0s
[18:37:46.639] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023808586800665440:24:0)
[18:37:46.639] Timer tick, asking Buildbucket for the build status
[18:37:47.836] Build 8858378524732370224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:37:47.836] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023808586800665440:25:0) after 1m0s
[18:38:48.034] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023808586800665440:25:0)
[18:38:48.034] Timer tick, asking Buildbucket for the build status
[18:38:48.731] Build 8858378524732370224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:38:48.732] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023808586800665440:26:0) after 1m0s
[18:39:48.858] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023808586800665440:26:0)
[18:39:48.858] Timer tick, asking Buildbucket for the build status
[18:39:49.362] Build 8858378524732370224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:39:49.362] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023808586800665440:27:0) after 1m0s
[18:40:49.483] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023808586800665440:27:0)
[18:40:49.483] Timer tick, asking Buildbucket for the build status
[18:40:49.636] Build 8858378524732370224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:40:49.636] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023808586800665440:28:0) after 1m0s
[18:41:49.695] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023808586800665440:28:0)
[18:41:49.695] Timer tick, asking Buildbucket for the build status
[18:41:49.758] Build 8858378524732370224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:41:49.758] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023808586800665440:29:0) after 1m0s
[18:42:49.859] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023808586800665440:29:0)
[18:42:49.859] Timer tick, asking Buildbucket for the build status
[18:42:49.934] Build 8858378524732370224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:42:49.934] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023808586800665440:30:0) after 1m0s
[18:43:50.005] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023808586800665440:30:0)
[18:43:50.005] Timer tick, asking Buildbucket for the build status
[18:43:50.173] Build 8858378524732370224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:43:50.173] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023808586800665440:31:0) after 1m0s
[18:44:50.181] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023808586800665440:31:0)
[18:44:50.181] Timer tick, asking Buildbucket for the build status
[18:44:50.252] Build 8858378524732370224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:44:50.252] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023808586800665440:32:0) after 1m0s
[18:45:50.331] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023808586800665440:32:0)
[18:45:50.331] Timer tick, asking Buildbucket for the build status
[18:45:50.525] Build 8858378524732370224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:45:50.525] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023808586800665440:33:0) after 1m0s
[18:46:50.562] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023808586800665440:33:0)
[18:46:50.562] Timer tick, asking Buildbucket for the build status
[18:46:50.662] Build 8858378524732370224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:46:50.662] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023808586800665440:34:0) after 1m0s
[18:47:50.757] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023808586800665440:34:0)
[18:47:50.757] Timer tick, asking Buildbucket for the build status
[18:47:50.870] Build 8858378524732370224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:47:50.870] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023808586800665440:35:0) after 1m0s
[18:48:50.883] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023808586800665440:35:0)
[18:48:50.883] Timer tick, asking Buildbucket for the build status
[18:48:50.951] Build 8858378524732370224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:48:50.951] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023808586800665440:36:0) after 1m0s
[18:49:50.956] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023808586800665440:36:0)
[18:49:50.956] Timer tick, asking Buildbucket for the build status
[18:49:51.429] Build 8858378524732370224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:49:51.429] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023808586800665440:37:0) after 1m0s
[18:50:51.483] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023808586800665440:37:0)
[18:50:51.483] Timer tick, asking Buildbucket for the build status
[18:50:51.757] Build 8858378524732370224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:50:51.757] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023808586800665440:38:0) after 1m0s
[18:51:51.765] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023808586800665440:38:0)
[18:51:51.765] Timer tick, asking Buildbucket for the build status
[18:51:52.062] Build 8858378524732370224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:51:52.062] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023808586800665440:39:0) after 1m0s
[18:52:52.105] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023808586800665440:39:0)
[18:52:52.105] Timer tick, asking Buildbucket for the build status
[18:52:52.189] Build 8858378524732370224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:52:52.189] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023808586800665440:40:0) after 1m0s
[18:53:52.306] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023808586800665440:40:0)
[18:53:52.306] Timer tick, asking Buildbucket for the build status
[18:53:52.415] Build 8858378524732370224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:53:52.415] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023808586800665440:41:0) after 1m0s
[18:54:52.481] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023808586800665440:41:0)
[18:54:52.481] Timer tick, asking Buildbucket for the build status
[18:54:52.578] Build 8858378524732370224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:54:52.578] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023808586800665440:42:0) after 1m0s
[18:55:52.625] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023808586800665440:42:0)
[18:55:52.625] Timer tick, asking Buildbucket for the build status
[18:55:52.717] Build 8858378524732370224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:55:52.717] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023808586800665440:43:0) after 1m0s
[18:56:52.777] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023808586800665440:43:0)
[18:56:52.777] Timer tick, asking Buildbucket for the build status
[18:56:53.165] Build 8858378524732370224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:56:53.165] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023808586800665440:44:0) after 1m0s
[18:57:53.228] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023808586800665440:44:0)
[18:57:53.228] Timer tick, asking Buildbucket for the build status
[18:57:53.384] Build 8858378524732370224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:57:53.384] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023808586800665440:45:0) after 1m0s
[18:58:53.429] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023808586800665440:45:0)
[18:58:53.429] Timer tick, asking Buildbucket for the build status
[18:58:53.820] Build 8858378524732370224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:58:53.820] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023808586800665440:46:0) after 1m0s
[18:59:53.880] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023808586800665440:46:0)
[18:59:53.880] Timer tick, asking Buildbucket for the build status
[18:59:54.193] Build 8858378524732370224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:59:54.193] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023808586800665440:47:0) after 1m0s
[19:00:54.225] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023808586800665440:47:0)
[19:00:54.225] Timer tick, asking Buildbucket for the build status
[19:00:54.674] Build 8858378524732370224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:00:54.674] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023808586800665440:48:0) after 1m0s
[19:01:54.721] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023808586800665440:48:0)
[19:01:54.721] Timer tick, asking Buildbucket for the build status
[19:01:54.857] Build 8858378524732370224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:01:54.857] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023808586800665440:49:0) after 1m0s
[19:02:54.916] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023808586800665440:49:0)
[19:02:54.916] Timer tick, asking Buildbucket for the build status
[19:02:55.083] Build 8858378524732370224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:02:55.083] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023808586800665440:50:0) after 1m0s
[19:03:55.147] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023808586800665440:50:0)
[19:03:55.147] Timer tick, asking Buildbucket for the build status
[19:03:55.346] Build 8858378524732370224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:03:55.346] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023808586800665440:51:0) after 1m0s
[19:04:55.332] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023808586800665440:51:0)
[19:04:55.332] Timer tick, asking Buildbucket for the build status
[19:04:55.631] Build 8858378524732370224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:04:55.631] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023808586800665440:52:0) after 1m0s
[19:05:55.747] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023808586800665440:52:0)
[19:05:55.747] Timer tick, asking Buildbucket for the build status
[19:05:56.680] Build 8858378524732370224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:05:56.680] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023808586800665440:53:0) after 1m0s
[19:06:56.726] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023808586800665440:53:0)
[19:06:56.726] Timer tick, asking Buildbucket for the build status
[19:06:56.926] Build 8858378524732370224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:06:56.926] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023808586800665440:54:0) after 1m0s
[19:07:57.025] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023808586800665440:54:0)
[19:07:57.025] Timer tick, asking Buildbucket for the build status
[19:07:57.226] Build 8858378524732370224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:07:57.226] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023808586800665440:55:0) after 1m0s
[19:08:57.263] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023808586800665440:55:0)
[19:08:57.263] Timer tick, asking Buildbucket for the build status
[19:08:57.438] Build 8858378524732370224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:08:57.438] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023808586800665440:56:0) after 1m0s
[19:09:57.461] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023808586800665440:56:0)
[19:09:57.461] Timer tick, asking Buildbucket for the build status
[19:09:57.584] Build 8858378524732370224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:09:57.584] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023808586800665440:57:0) after 1m0s
[19:10:57.764] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023808586800665440:57:0)
[19:10:57.764] Timer tick, asking Buildbucket for the build status
[19:10:57.957] Build 8858378524732370224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:10:57.957] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023808586800665440:58:0) after 1m0s
[19:11:58.285] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023808586800665440:58:0)
[19:11:58.285] Timer tick, asking Buildbucket for the build status
[19:11:58.422] Build 8858378524732370224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:11:58.422] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023808586800665440:59:0) after 1m0s
[19:12:31.288] Received PubSub notification, asking Buildbucket for the build status
[19:12:31.392] Build 8858378524732370224: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[19:12:31.392] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1610392349757198",
"created_by": "project:chromium",
"created_ts": "1610388938166337",
"id": "8858378524732370224",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"37b032d626cf5cd9c5a56ea0f037cf3eca2fb319\"}}",
"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\": \"37b032d626cf5cd9c5a56ea0f037cf3eca2fb319\", \"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-9-met4\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-rel\", \"buildnumber\": 44512, \"got_angle_revision\": \"c600e47812c88d45087582122dbb004851ae966b\", \"got_dawn_revision\": \"99c3a691c23dbd75075eb852d2f6a1c569148826\", \"got_nacl_revision\": \"d28e6c52e05ea2f1b3231de473b6dbfce59bce40\", \"got_revision\": \"37b032d626cf5cd9c5a56ea0f037cf3eca2fb319\", \"got_revision_cp\": \"refs/heads/master@{#842093}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"009667d5f97c46652d9a804b3579e28fd692a43e\", \"got_v8_revision\": \"2779617a24693e22ad550b0b15467568c5a4a1ee\", \"got_v8_revision_cp\": \"refs/heads/8.9.234@{#1}\", \"got_webrtc_revision\": \"da06e8f6bdb7bda271d9e68300f42e769d896542\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32935}\", \"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\": \"37b032d626cf5cd9c5a56ea0f037cf3eca2fb319\", \"swarm_hashes_refs/heads/master(at){#842093}_without_patch\": {\"absl_hardening_tests\": \"06e51c00254e4b8e3d17e991f17fc48bc23fa1c5\", \"accessibility_unittests\": \"8a2f3c59231d04112350f5752e4d93bdca921c87\", \"angle_unittests\": \"e2b03b36aa22954c23bbb684948d0d3499ec5aa9\", \"app_list_unittests\": \"491d6a62dbe29df1f937d8e532f74cb4e8624cd8\", \"app_shell_unittests\": \"6a3f830c74f5e0a9c356d307aeb101ba7722203e\", \"ash_unittests\": \"69432dc27aa16e774887f877fcfe9c0eb8e2a997\", \"aura_unittests\": \"ecfac813209d4d7c4cb2390f47892454558ef077\", \"base_unittests\": \"563da68f22902333176e50eacd602a4bbd38a415\", \"base_util_unittests\": \"e50f8d5084e4c411a652812348b1c26b5432e9e1\", \"blink_common_unittests\": \"5138e5b0a6c1b0219264582209970a9560e0a9e8\", \"blink_fuzzer_unittests\": \"6716598e0b6b7324af27297db08e060484e1a431\", \"blink_heap_unittests\": \"01a41476994eef8b403524b7aa11e5b02f6e91b9\", \"blink_platform_unittests\": \"b5d18b0c29fb62f98557c6c77271ad038936ac34\", \"blink_unittests\": \"fc206434415ada2f65bb371ff77796c2d980c5dc\", \"boringssl_crypto_tests\": \"6f31d9a154b835362b5f8923765d7253d0dce5d7\", \"boringssl_ssl_tests\": \"506335f0735132343badf57dc049d0be5790a793\", \"browser_tests\": \"1f642b3a7d5b5b0f816f7bc36277f336f076befe\", \"capture_unittests\": \"342099c0c6ad903409ff47c9ef22d00178406558\", \"cast_unittests\": \"f909ee1c2d5b4a671e77e2ae000fb8eb9deb0856\", \"cc_unittests\": \"14bc9a173d880e75bc09ae192aebd5242222d487\", \"chrome_app_unittests\": \"4842dc84207e41d560221a7340cf7c5a86055595\", \"chromedriver_unittests\": \"624467a38868f5ea7a87d6dce1e1ca5ae1b46e01\", \"chromeos_components_unittests\": \"59e8d2896a5e9cc9d5987c5b9955a219c9658d11\", \"chromeos_unittests\": \"4086b772675a84d6a53fcf1d81448b5e7ac22ce8\", \"components_browsertests\": \"afd9cd90e41e79562b0f6e4dcaa3fbd149cd6f74\", \"components_unittests\": \"cadf5343378027586f542ff54e3cd9ec2cd941cf\", \"compositor_unittests\": \"8707c1c49ad1205862283c52493374f5bb39b22a\", \"content_browsertests\": \"1af263d947a2eaf73613215988ae69dbd4949e51\", \"content_unittests\": \"92c587e2f8ef37c81b47e0ac1a72aa3047efd0fb\", \"crashpad_tests\": \"03a6d06e2cd5a7c8f7bba85ba6f691a274623c59\", \"crypto_unittests\": \"c50d1f1051ed8f97c6cfa9027b20bd070b0ae1c6\", \"dbus_unittests\": \"0fca7cb3d25b362eb81ee7c8fb6b611379200c0d\", \"device_unittests\": \"ffb9c87d5a6fa3f7d6eef492f4ac3ffe56336ab5\", \"display_unittests\": \"e1ec54d96e5e21ae25092e352a05296cb3f03e5a\", \"events_unittests\": \"33b1e0e534467edfb6e3458c92b399a5ec20807f\", \"exo_unittests\": \"88dd1acde68bd63fa25fbcc76097625d39541da6\", \"extensions_browsertests\": \"39883696516a5e4bd5da65ba02fa0c7e86225e6b\", \"extensions_unittests\": \"5ed391d346939cbfadbc3f7000f60b32263cf288\", \"filesystem_service_unittests\": \"d543da764663dd546b237996bffbb953eb24aa35\", \"gcm_unit_tests\": \"3ddedc998d090cbc5e063c5af21645b1c56bb819\", \"gfx_unittests\": \"f419dfde41dd1c705af53be39467d8c462602177\", \"gin_unittests\": \"a228e1d1ad90dd039ac8cba0e92b2174847d56b2\", \"gl_unittests_ozone\": \"09f6ad0833e642bddc6934c35007c792395d8655\", \"google_apis_unittests\": \"efb1e67ad7f8bd35275baa2d750805ad877bb724\", \"gpu_unittests\": \"17e834842529adde4eea9361a019e16933e55114\", \"gwp_asan_unittests\": \"93e09dabef170b9c95520e465e8d94e2ada1f48a\", \"interactive_ui_tests\": \"bf20fd5ebd80b797bd0ca8ea12faaad4bdb1268c\", \"ipc_tests\": \"eb5489ef5aee3ec0c1e6792ebc0f27ad3ad47e24\", \"jingle_unittests\": \"b830d614a1bbd178d4e0c4e0089d7fd11c7f93b5\", \"keyboard_unittests\": \"676dbe80c0d4742fc4c1549f2d37e69a15eec0c6\", \"latency_unittests\": \"d2f4608abf4cd763504b2abb0676ca48be4a8574\", \"libjingle_xmpp_unittests\": \"76aa0abb37426d7b4ca605a0e744ebc46863cfd6\", \"liburlpattern_unittests\": \"394626af618c988a0496099baa457395a47b8798\", \"media_blink_unittests\": \"b7004390a5d1905449b2548e6ff531aab0363714\", \"media_unittests\": \"c9c592432263738d38fa855bbc4c04c1ece8fe3d\", \"message_center_unittests\": \"f13d564940e273077a503342717ea6d33b3ccd99\", \"midi_unittests\": \"0aae98317c3082cec1e4e8038d53a21685a9bb3f\", \"mojo_core_unittests\": \"1dbe8734762ecc0f3404101708caface3d272203\", \"mojo_unittests\": \"5a9d4c9ced9d2a068b6c26df3b9f98ac535c50ea\", \"nacl_helper_nonsfi_unittests\": \"a9cabe85e214b0dc92ba3066bf42c55f238704f9\", \"nacl_loader_unittests\": \"50999ca61a12704b012eb7ba60910e7f10f281d8\", \"native_theme_unittests\": \"8a31296d18bf7b1b1265ddf31609a7d3e1675faf\", \"net_unittests\": \"23003ed9066e7af3673b8eeebcbae2f7429a56f5\", \"ozone_gl_unittests\": \"aecccf306d20aec3828345ef5840d41ac3c4345d\", \"ozone_unittests\": \"4f25e717cbcfce991674461994b565a9e5dbd34f\", \"ozone_x11_unittests\": \"bae64576338cfb5411e28d9fff7f0b2015a5e653\", \"pdf_unittests\": \"b150a9957f10eae2242ba4889bac2e553e035241\", \"perfetto_unittests\": \"138ccea84b5ab09c57e1bdefef7d517be3cc3fff\", \"ppapi_unittests\": \"535395e539322baa06a37547f509291c5e18a66e\", \"printing_unittests\": \"097d18d2fda96f776dc8556307223b4e14fb510f\", \"remoting_unittests\": \"d54e9c61ee0859e350ececb1a89b0acd034076e1\", \"sandbox_linux_unittests\": \"f7d4b10ab9c8ad8c142b85ab0cbca96afdb96295\", \"service_manager_unittests\": \"d10887dc823d8b671c6d0172c3acfdaaa0eca9f9\", \"services_unittests\": \"f20b957ea626cd34136bf5f0eca88886b13996e7\", \"shell_dialogs_unittests\": \"ce861d900d019ddcc8a5165e6d3b96f0cd6190a2\", \"shell_encryption_unittests\": \"8b164ffaf1a5edd1ac28076ecac27ca442525fa1\", \"skia_unittests\": \"37afca3594371774666e5b7ba9434864ada429f9\", \"snapshot_unittests\": \"46ec2c2e21fe64dfb97d90a507b0fd7baa363ead\", \"sql_unittests\": \"599034234a3b494fc1d738c3d046fc9df9ac74bd\", \"storage_unittests\": \"474677391f5ba240c7ed76bf2afb93f800704043\", \"sync_integration_tests\": \"5a8d2d6cbcb66d0c82bb7f3a5464920576aa365f\", \"traffic_annotation_auditor_unittests\": \"675d09cb10ef78fa59eaed09bdbdc373fedfac7a\", \"ui_base_unittests\": \"7ae1a0231909b9c7f48ac6fa52c0a372f6a3f84c\", \"ui_chromeos_unittests\": \"20dc857c7604195e0af2d32a2c0b48470c5c5282\", \"ui_touch_selection_unittests\": \"11416df4d3b2e40ce0f730fab371c378ff100858\", \"unit_tests\": \"94389a07cf2af52c27890c63ac8a8d11dbdf8f95\", \"url_unittests\": \"d6a35802e87f92e67ee994e6f93879ee5b9050cf\", \"usage_time_limit_unittests\": \"f09575b953220ce93391dd571a237ee14e045eae\", \"views_unittests\": \"1209f9903af3ee51da0260cba805aba66d5390bb\", \"viz_unittests\": \"aef8e08ba45bcc1b2686b1f2dea75c389313dbe7\", \"wayland_client_perftests\": \"bbfa818eef794929798d1446daab4c7f96f08031\", \"wm_unittests\": \"405bda88434b881f442008a0dd824a74654c9344\", \"wtf_unittests\": \"de46e2f410d261f67c794a7c9f03c338ef666ce6\", \"zlib_unittests\": \"56d60e8231c31a09801bb3123c99b4073df1a64f\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_33498e86c0181efb6d75c3f73059d9522f6d5d2b3b1c7a2dc2a1c6b75cd205cd_v2\", \"builder_8c23a72064650b4b1d9380637a287eed4ae2e2a2092116afea022d28ee09b5dd_v2\", \"builder_94fa3119a28f4651cdd4fc5b981a64c69b26c151212072333439362290628993_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-9-met4\"], \"image\": [\"chrome-xenial-21010300-f0aeabbfea7\"], \"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\": [\"5657-0417a3f\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-d\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1610388942088299",
"status": "COMPLETED",
"status_changed_ts": "1610392350094305",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/44512",
"builder:linux-chromeos-rel",
"buildset:commit/git/37b032d626cf5cd9c5a56ea0f037cf3eca2fb319",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/37b032d626cf5cd9c5a56ea0f037cf3eca2fb319",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023808586800665440",
"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/8858378524732370224/+/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:510b7aa8a12eb610",
"user_agent:luci-scheduler"
],
"updated_ts": "1610392350100964",
"url": "https://ci.chromium.org/b/8858378524732370224",
"utcnow_ts": "1610392351375795"
}
}
[19:12:31.394] Invocation finished in 56m54.584058194s with status SUCCEEDED