[15:41:12.088] New invocation is queued and will start shortly
[15:41:13.221] Starting the invocation (attempt 1)
[15:41:13.263] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[15:41:13.263] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[15:41:13.263] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9029797701365536928",
"parameters_json": "{\"builder_name\":\"linux-chromeos-rel\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"e5c176e5b92b4bcc4cc0beba978ae9332d583c65\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-rel",
"scheduler_invocation_id:9029797701365536928",
"scheduler_job_id:chromium/linux-chromeos-rel",
"user_agent:luci-scheduler",
"buildset:commit/git/e5c176e5b92b4bcc4cc0beba978ae9332d583c65",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/e5c176e5b92b4bcc4cc0beba978ae9332d583c65",
"gitiles_ref:refs/heads/master"
]
}
[15:41:14.066] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1604677273352386",
"id": "8864367639376214000",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"e5c176e5b92b4bcc4cc0beba978ae9332d583c65\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1604677273941931",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/42642",
"builder:linux-chromeos-rel",
"buildset:commit/git/e5c176e5b92b4bcc4cc0beba978ae9332d583c65",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/e5c176e5b92b4bcc4cc0beba978ae9332d583c65",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9029797701365536928",
"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/8864367639376214000/+/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": "1604677273942066",
"url": "https://ci.chromium.org/b/8864367639376214000",
"utcnow_ts": "1604677274055179"
}
}
[15:41:14.066] Task URL: https://ci.chromium.org/b/8864367639376214000
[15:41:14.066] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029797701365536928:2:0) after 1m0s
[15:41:34.636] Received PubSub notification, asking Buildbucket for the build status
[15:41:34.897] Build 8864367639376214000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:42:14.178] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029797701365536928:2:0)
[15:42:14.178] Timer tick, asking Buildbucket for the build status
[15:42:14.283] Build 8864367639376214000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:42:14.283] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029797701365536928:4:0) after 1m0s
[15:43:14.335] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029797701365536928:4:0)
[15:43:14.335] Timer tick, asking Buildbucket for the build status
[15:43:14.487] Build 8864367639376214000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:43:14.487] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029797701365536928:5:0) after 1m0s
[15:44:14.562] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029797701365536928:5:0)
[15:44:14.562] Timer tick, asking Buildbucket for the build status
[15:44:14.660] Build 8864367639376214000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:44:14.660] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029797701365536928:6:0) after 1m0s
[15:45:14.758] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029797701365536928:6:0)
[15:45:14.758] Timer tick, asking Buildbucket for the build status
[15:45:14.951] Build 8864367639376214000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:45:14.951] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029797701365536928:7:0) after 1m0s
[15:46:14.967] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029797701365536928:7:0)
[15:46:14.967] Timer tick, asking Buildbucket for the build status
[15:46:15.117] Build 8864367639376214000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:46:15.117] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029797701365536928:8:0) after 1m0s
[15:47:15.154] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029797701365536928:8:0)
[15:47:15.154] Timer tick, asking Buildbucket for the build status
[15:47:15.331] Build 8864367639376214000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:47:15.331] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029797701365536928:9:0) after 1m0s
[15:48:15.401] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029797701365536928:9:0)
[15:48:15.401] Timer tick, asking Buildbucket for the build status
[15:48:15.832] Build 8864367639376214000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:48:15.832] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029797701365536928:10:0) after 1m0s
[15:49:15.889] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029797701365536928:10:0)
[15:49:15.889] Timer tick, asking Buildbucket for the build status
[15:49:16.090] Build 8864367639376214000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:49:16.090] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029797701365536928:11:0) after 1m0s
[15:50:16.135] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029797701365536928:11:0)
[15:50:16.135] Timer tick, asking Buildbucket for the build status
[15:50:16.201] Build 8864367639376214000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:50:16.201] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029797701365536928:12:0) after 1m0s
[15:51:16.355] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029797701365536928:12:0)
[15:51:16.355] Timer tick, asking Buildbucket for the build status
[15:51:16.545] Build 8864367639376214000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:51:16.545] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029797701365536928:13:0) after 1m0s
[15:52:16.621] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029797701365536928:13:0)
[15:52:16.621] Timer tick, asking Buildbucket for the build status
[15:52:16.772] Build 8864367639376214000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:52:16.772] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029797701365536928:14:0) after 1m0s
[15:53:16.888] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029797701365536928:14:0)
[15:53:16.888] Timer tick, asking Buildbucket for the build status
[15:53:17.244] Build 8864367639376214000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:53:17.244] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029797701365536928:15:0) after 1m0s
[15:54:17.308] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029797701365536928:15:0)
[15:54:17.308] Timer tick, asking Buildbucket for the build status
[15:54:17.408] Build 8864367639376214000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:54:17.409] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029797701365536928:16:0) after 1m0s
[15:55:17.443] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029797701365536928:16:0)
[15:55:17.443] Timer tick, asking Buildbucket for the build status
[15:55:17.532] Build 8864367639376214000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:55:17.532] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029797701365536928:17:0) after 1m0s
[15:56:17.655] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029797701365536928:17:0)
[15:56:17.655] Timer tick, asking Buildbucket for the build status
[15:56:17.784] Build 8864367639376214000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:56:17.784] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029797701365536928:18:0) after 1m0s
[15:57:17.829] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029797701365536928:18:0)
[15:57:17.829] Timer tick, asking Buildbucket for the build status
[15:57:18.202] Build 8864367639376214000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:57:18.202] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029797701365536928:19:0) after 1m0s
[15:58:18.237] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029797701365536928:19:0)
[15:58:18.237] Timer tick, asking Buildbucket for the build status
[15:58:18.377] Build 8864367639376214000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:58:18.377] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029797701365536928:20:0) after 1m0s
[15:59:20.269] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029797701365536928:20:0)
[15:59:20.269] Timer tick, asking Buildbucket for the build status
[15:59:21.152] Build 8864367639376214000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:59:21.152] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029797701365536928:21:0) after 1m0s
[16:00:21.454] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029797701365536928:21:0)
[16:00:21.454] Timer tick, asking Buildbucket for the build status
[16:00:21.532] Build 8864367639376214000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:00:21.532] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029797701365536928:22:0) after 1m0s
[16:01:21.740] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029797701365536928:22:0)
[16:01:21.740] Timer tick, asking Buildbucket for the build status
[16:01:21.830] Build 8864367639376214000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:01:21.830] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029797701365536928:23:0) after 1m0s
[16:02:22.175] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029797701365536928:23:0)
[16:02:22.175] Timer tick, asking Buildbucket for the build status
[16:02:22.538] Build 8864367639376214000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:02:22.538] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029797701365536928:24:0) after 1m0s
[16:03:22.726] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029797701365536928:24:0)
[16:03:22.726] Timer tick, asking Buildbucket for the build status
[16:03:22.804] Build 8864367639376214000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:03:22.804] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029797701365536928:25:0) after 1m0s
[16:04:22.823] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029797701365536928:25:0)
[16:04:22.823] Timer tick, asking Buildbucket for the build status
[16:04:22.974] Build 8864367639376214000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:04:22.974] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029797701365536928:26:0) after 1m0s
[16:05:23.064] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029797701365536928:26:0)
[16:05:23.064] Timer tick, asking Buildbucket for the build status
[16:05:23.260] Build 8864367639376214000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:05:23.260] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029797701365536928:27:0) after 1m0s
[16:06:23.312] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029797701365536928:27:0)
[16:06:23.312] Timer tick, asking Buildbucket for the build status
[16:06:23.372] Build 8864367639376214000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:06:23.372] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029797701365536928:28:0) after 1m0s
[16:07:23.459] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029797701365536928:28:0)
[16:07:23.460] Timer tick, asking Buildbucket for the build status
[16:07:23.623] Build 8864367639376214000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:07:23.623] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029797701365536928:29:0) after 1m0s
[16:08:23.643] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029797701365536928:29:0)
[16:08:23.643] Timer tick, asking Buildbucket for the build status
[16:08:23.709] Build 8864367639376214000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:08:23.709] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029797701365536928:30:0) after 1m0s
[16:09:23.686] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029797701365536928:30:0)
[16:09:23.686] Timer tick, asking Buildbucket for the build status
[16:09:23.749] Build 8864367639376214000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:09:23.749] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029797701365536928:31:0) after 1m0s
[16:10:23.899] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029797701365536928:31:0)
[16:10:23.899] Timer tick, asking Buildbucket for the build status
[16:10:23.970] Build 8864367639376214000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:10:23.970] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029797701365536928:32:0) after 1m0s
[16:11:24.067] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029797701365536928:32:0)
[16:11:24.067] Timer tick, asking Buildbucket for the build status
[16:11:24.238] Build 8864367639376214000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:11:24.238] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029797701365536928:33:0) after 1m0s
[16:12:24.318] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029797701365536928:33:0)
[16:12:24.318] Timer tick, asking Buildbucket for the build status
[16:12:24.457] Build 8864367639376214000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:12:24.457] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029797701365536928:34:0) after 1m0s
[16:13:24.521] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029797701365536928:34:0)
[16:13:24.521] Timer tick, asking Buildbucket for the build status
[16:13:24.663] Build 8864367639376214000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:13:24.663] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029797701365536928:35:0) after 1m0s
[16:14:24.684] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029797701365536928:35:0)
[16:14:24.684] Timer tick, asking Buildbucket for the build status
[16:14:25.358] Build 8864367639376214000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:14:25.358] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029797701365536928:36:0) after 1m0s
[16:15:25.432] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029797701365536928:36:0)
[16:15:25.432] Timer tick, asking Buildbucket for the build status
[16:15:25.588] Build 8864367639376214000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:15:25.588] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029797701365536928:37:0) after 1m0s
[16:16:25.665] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029797701365536928:37:0)
[16:16:25.665] Timer tick, asking Buildbucket for the build status
[16:16:25.802] Build 8864367639376214000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:16:25.802] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029797701365536928:38:0) after 1m0s
[16:17:25.824] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029797701365536928:38:0)
[16:17:25.824] Timer tick, asking Buildbucket for the build status
[16:17:25.980] Build 8864367639376214000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:17:25.980] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029797701365536928:39:0) after 1m0s
[16:18:26.007] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029797701365536928:39:0)
[16:18:26.007] Timer tick, asking Buildbucket for the build status
[16:18:26.125] Build 8864367639376214000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:18:26.125] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029797701365536928:40:0) after 1m0s
[16:19:26.160] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029797701365536928:40:0)
[16:19:26.160] Timer tick, asking Buildbucket for the build status
[16:19:26.288] Build 8864367639376214000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:19:26.288] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029797701365536928:41:0) after 1m0s
[16:20:26.332] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029797701365536928:41:0)
[16:20:26.332] Timer tick, asking Buildbucket for the build status
[16:20:26.553] Build 8864367639376214000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:20:26.553] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029797701365536928:42:0) after 1m0s
[16:21:26.731] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029797701365536928:42:0)
[16:21:26.731] Timer tick, asking Buildbucket for the build status
[16:21:27.206] Build 8864367639376214000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:21:27.206] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029797701365536928:43:0) after 1m0s
[16:22:27.380] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029797701365536928:43:0)
[16:22:27.380] Timer tick, asking Buildbucket for the build status
[16:22:27.832] Build 8864367639376214000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:22:27.832] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029797701365536928:44:0) after 1m0s
[16:23:27.905] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029797701365536928:44:0)
[16:23:27.905] Timer tick, asking Buildbucket for the build status
[16:23:28.914] Build 8864367639376214000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:23:28.914] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029797701365536928:45:0) after 1m0s
[16:24:29.851] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029797701365536928:45:0)
[16:24:29.851] Timer tick, asking Buildbucket for the build status
[16:24:30.488] Build 8864367639376214000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:24:30.488] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029797701365536928:46:0) after 1m0s
[16:25:30.721] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029797701365536928:46:0)
[16:25:30.721] Timer tick, asking Buildbucket for the build status
[16:25:31.412] Build 8864367639376214000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:25:31.412] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029797701365536928:47:0) after 1m0s
[16:26:31.626] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029797701365536928:47:0)
[16:26:31.626] Timer tick, asking Buildbucket for the build status
[16:26:31.786] Build 8864367639376214000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:26:31.786] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029797701365536928:48:0) after 1m0s
[16:27:31.911] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029797701365536928:48:0)
[16:27:31.911] Timer tick, asking Buildbucket for the build status
[16:27:32.302] Build 8864367639376214000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:27:32.302] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029797701365536928:49:0) after 1m0s
[16:28:32.386] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029797701365536928:49:0)
[16:28:32.386] Timer tick, asking Buildbucket for the build status
[16:28:32.470] Build 8864367639376214000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:28:32.470] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029797701365536928:50:0) after 1m0s
[16:28:48.886] Received PubSub notification, asking Buildbucket for the build status
[16:28:49.638] Build 8864367639376214000: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[16:28:49.638] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1604680126793948",
"created_by": "project:chromium",
"created_ts": "1604677273352386",
"id": "8864367639376214000",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"e5c176e5b92b4bcc4cc0beba978ae9332d583c65\"}}",
"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\": \"e5c176e5b92b4bcc4cc0beba978ae9332d583c65\", \"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-41-sjjl\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-rel\", \"buildnumber\": 42642, \"got_angle_revision\": \"0781b693fa2d29680c5620a4c73de158eab05f7b\", \"got_dawn_revision\": \"973d145df8656f81ee3c4f99a3e9a0222ec2c826\", \"got_nacl_revision\": \"f9e6f257d1a0934dbeed3c5c8f83b651dc4f55e1\", \"got_revision\": \"e5c176e5b92b4bcc4cc0beba978ae9332d583c65\", \"got_revision_cp\": \"refs/heads/master@{#824874}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"612ded0654a027d64922448f136d125a9dc17956\", \"got_v8_revision\": \"d38e00646bf1572fac3a259cc4a031f470d1097b\", \"got_v8_revision_cp\": \"refs/heads/8.8.244@{#1}\", \"got_webrtc_revision\": \"42cafa5696f516712bbe6709fedeedffac61544c\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32540}\", \"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\": \"e5c176e5b92b4bcc4cc0beba978ae9332d583c65\", \"swarm_hashes_refs/heads/master(at){#824874}_without_patch\": {\"absl_hardening_tests\": \"81c346e525eafd45c6c5a3ac9da13c728b2b753e\", \"accessibility_unittests\": \"4f912fd20842ddfa04e0f98e3436a5c4ba806e80\", \"angle_unittests\": \"9f4413535edd30ab7992906f0255966a926951ab\", \"app_list_unittests\": \"62854ed2e2f21895fdaaa699d1ee3360c47c247c\", \"app_shell_unittests\": \"5f7746331655b49715292fc1d2e6c7405c7e84da\", \"ash_unittests\": \"62fc18289f5228d8455bf1a6e6ef053758a5d607\", \"aura_unittests\": \"c4642325f7542407ac077a8059766477df725da8\", \"base_unittests\": \"26c8128ac1b58691f710ef84c2160d8dcd0c7967\", \"base_util_unittests\": \"513986a7595d8d4eaaeea0efc139cfd6288eb327\", \"blink_common_unittests\": \"18c75b6ef1b67ac164bd4fb91249839712776887\", \"blink_fuzzer_unittests\": \"d2783d78544df176e39acb8df415156bb1e57aad\", \"blink_heap_unittests\": \"0c4a02cb8106dff865052089286c70b9aac8beed\", \"blink_platform_unittests\": \"8bab33b5a417d8c3f49c7520f818a115c63c63bd\", \"blink_unittests\": \"77b956698cee431ad3757fc2931737a54c82c832\", \"boringssl_crypto_tests\": \"6b4606b63ab07ff5b489792421d9324a0a707d17\", \"boringssl_ssl_tests\": \"e19d70869d8d209b7c59c15155e1a491921abef5\", \"browser_tests\": \"fa5ab4b18caad6ea8cfd67d6c9f4ef73304ae8dd\", \"capture_unittests\": \"f1890d811bffa5c0727d1037d0cf2be58db0994c\", \"cast_unittests\": \"33ae1fed010a251743c41199a33a3d848d5e8a23\", \"cc_unittests\": \"975a851bb100d818824f6f34b1c5aa362077a50d\", \"chrome_app_unittests\": \"61b105456f5df0216022e3120df86dad5eb97fba\", \"chromedriver_unittests\": \"9ef45a1a8d99460dd3f58f380f1d80820d138ce1\", \"chromeos_components_unittests\": \"1f9bc3bd4016381561730e9c5cdc7e0fbdb2b4a9\", \"chromeos_unittests\": \"af3826d87762c8816d032f78229e2007662fac8a\", \"components_browsertests\": \"57162dcea3ca6fc5c2e4e9e1612355c71aeff292\", \"components_unittests\": \"6e68f464d6e9ea94d9ee358024ffa84581bed3dc\", \"compositor_unittests\": \"e6a8f5357b4f087184612fda6a1947bc186ef5e8\", \"content_browsertests\": \"bfff8fb307918f739c9d4e11dde07a2d21db0f95\", \"content_unittests\": \"e38ae2d5383f5a36048f283a4f17e569880aee28\", \"crypto_unittests\": \"1c3d9cd75801804d4f7724bcfe6ff86c121688ba\", \"dbus_unittests\": \"07327001f631ee6a36b5f75e52fe4e585ff3dc82\", \"device_unittests\": \"eb39f757ab58a519c141fe50632d1f80d3a8db2e\", \"display_unittests\": \"17119756a941eef776933cb3078c4224fd12fdd7\", \"events_unittests\": \"38a8f1a13099998c609244f6fdffee77fa496c4b\", \"exo_unittests\": \"74cc0b91dee7ad7a766939e6686a3c97b14cb927\", \"extensions_browsertests\": \"ae0a9c3bae6fc47a3ab3630b0d4aec51a60758dc\", \"extensions_unittests\": \"40b791b9bc880cca9c115c693347e07edf696838\", \"filesystem_service_unittests\": \"5be4f07b78c1b83838e1fe1878dac1366d37c892\", \"gcm_unit_tests\": \"1d762777ee7cda924f6d95e085b4500914372084\", \"gfx_unittests\": \"171111ae962df80462b9ce6e4b281e490089fb17\", \"gin_unittests\": \"6a9e2ee1b2c3cd365bd753b72bebc3eb899d3968\", \"gl_unittests_ozone\": \"d24b2f7ea81a35a56a4d3b66520abe2eeccc52ef\", \"google_apis_unittests\": \"2aaeeb51ad62279f0a4c12af68d6babc9193477a\", \"gpu_unittests\": \"c72d91945bada296aecc01d5a229fb60e6998c20\", \"gwp_asan_unittests\": \"79f58433db1c10cb088744f409c6b5ea1ca4ec18\", \"interactive_ui_tests\": \"c66a2dd728176e12f5c233ea3ba999a966e7791c\", \"ipc_tests\": \"76375780a377ef697eff9523697fb4bd64d184c8\", \"jingle_unittests\": \"a55b974e8050f7fc149466f8c6a93378a24e4fd8\", \"keyboard_unittests\": \"e2c1526cf38e2b67eadf91b7a746948d78717b28\", \"latency_unittests\": \"a27c509ea8645aee69ef971579eae51ead94af1a\", \"libjingle_xmpp_unittests\": \"813a3218b1786f9323da73b6f3bc8d7ca5058ab6\", \"liburlpattern_unittests\": \"66e2748468b91d22a2943075c484dce377b7cb28\", \"media_blink_unittests\": \"a818eae74233285c47932d16c4d2fb8892f63715\", \"media_unittests\": \"846d070223f562ba67691c9b49ff53b0b4204dd1\", \"message_center_unittests\": \"b5aca562315e83d079b157bc867970b7bd22a5f0\", \"midi_unittests\": \"bb7f4bdc889063d13dba17f034f31f825541e94e\", \"mojo_core_unittests\": \"40edcf912ef97eb0a0bc91f2395c2d243cfc2d34\", \"mojo_unittests\": \"bc7973d00411a36d9cfabd015b32888c237d22bb\", \"nacl_helper_nonsfi_unittests\": \"7776cb3c41d26ede9a21b62b7f3295b1e38f0846\", \"nacl_loader_unittests\": \"814923a2e3b6d64f68f1899bfe0a7e36b1e5deb0\", \"native_theme_unittests\": \"7b903b7843dcac43aa65a79ce15a363434e91441\", \"net_unittests\": \"fb73217ffcb37d41d0e63d270b3b3c5501bd9d17\", \"ozone_gl_unittests\": \"38f417ea530fdf4857b85fe22f232e0574988527\", \"ozone_unittests\": \"490f788ae25bb8f9e196069552da30c0ca5573e5\", \"ozone_x11_unittests\": \"959f33812de5ee9f49d569b5c30784d1be095cec\", \"pdf_unittests\": \"7105fc41e11f63d27ac0cf65040dd90a8699ec84\", \"perfetto_unittests\": \"d481bc3809d63120c807b18bff1c0e2f9c604220\", \"ppapi_unittests\": \"8bb8eccabc1c6b9166c93b365d6c514c63c02125\", \"printing_unittests\": \"b49ddac8256bbb1d53317ef0a6f970213202dd40\", \"remoting_unittests\": \"c0da308eef7586763c56db35a746942048a067ab\", \"sandbox_linux_unittests\": \"e72754b73b099fb1b9922f75617606359f1ff1ad\", \"service_manager_unittests\": \"c25b3aa58ea7209d9b1a250581af1c467a013001\", \"services_unittests\": \"8724924f07bae008180eb1e4411629868af23d69\", \"shell_dialogs_unittests\": \"d4dc4e30ac68cf95b2c78a70dfd441e341d4c006\", \"skia_unittests\": \"ebd926c8acb8664b4cdfadaef36bfed534a2bf45\", \"snapshot_unittests\": \"d86dc85b39131b79f026191462361d0cfbbd62af\", \"sql_unittests\": \"742cb650f385ecf3660f5ea8f1659fb18a45dd2c\", \"storage_unittests\": \"3f65185b114497e527bfc83c69d7acfa5f3980ae\", \"sync_integration_tests\": \"0684bf44ee0cd85347ea8afe67f51bb23422fe3c\", \"traffic_annotation_auditor_unittests\": \"40c0cfb76c0df0ff980d4c80842aa0b1855acfe7\", \"ui_base_unittests\": \"9ba3a69a26394c911b2898b3a0851af9b795e2dd\", \"ui_chromeos_unittests\": \"9dc6d73c1c44442b457581361c89b18cf38f4fbc\", \"ui_touch_selection_unittests\": \"f11cfa07f60d2261d4efb3e8e3b1fd23d1c51169\", \"unit_tests\": \"2202a60b950ce37a0f78c525baad061ccc9e7e2b\", \"url_unittests\": \"7d56d94095f8ed06561a41fd7ee1c730b6592914\", \"usage_time_limit_unittests\": \"342cbff4aa22a120d87d2645634048d73a0dd145\", \"views_unittests\": \"4b0127d90bf5889b3e6586d16f1fadd7b49befb7\", \"viz_unittests\": \"d442e89043d0399d87965c4533ba5794489b8460\", \"wayland_client_perftests\": \"61410e35a070511d87ab6fd68c4ec79e694ee882\", \"wm_unittests\": \"8ca22e086d92032cf11a739cac3b41e3d9171601\", \"wtf_unittests\": \"2b366d7ca28a459aa6125927a56db70c536ed057\", \"zlib_unittests\": \"498a49e98d045a2547134bdfc239f511fae18913\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"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-41-sjjl\"], \"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\": [\"5614-d862eaa\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-d\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1604677293745093",
"status": "COMPLETED",
"status_changed_ts": "1604680127281969",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/42642",
"builder:linux-chromeos-rel",
"buildset:commit/git/e5c176e5b92b4bcc4cc0beba978ae9332d583c65",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/e5c176e5b92b4bcc4cc0beba978ae9332d583c65",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9029797701365536928",
"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/8864367639376214000/+/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:4fb709d7e5d05010",
"user_agent:luci-scheduler"
],
"updated_ts": "1604680127292480",
"url": "https://ci.chromium.org/b/8864367639376214000",
"utcnow_ts": "1604680129572254"
}
}
[16:28:49.638] Invocation finished in 47m37.567329684s with status SUCCEEDED