[13:55:30.812] New invocation is queued and will start shortly
[13:55:31.989] Starting the invocation (attempt 1)
[13:55:32.030] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[13:55:32.030] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[13:55:32.031] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9023100175161931808",
"parameters_json": "{\"builder_name\":\"linux-chromeos-rel\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"bc9b7fe2b7507b1959f098cb6b5ca984b79d6121\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-rel",
"scheduler_invocation_id:9023100175161931808",
"scheduler_job_id:chromium/linux-chromeos-rel",
"user_agent:luci-scheduler",
"buildset:commit/git/bc9b7fe2b7507b1959f098cb6b5ca984b79d6121",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/bc9b7fe2b7507b1959f098cb6b5ca984b79d6121",
"gitiles_ref:refs/heads/master"
]
}
[13:55:33.602] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1611064532176787",
"id": "8857670113067302400",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"bc9b7fe2b7507b1959f098cb6b5ca984b79d6121\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1611064533333099",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/44737",
"builder:linux-chromeos-rel",
"buildset:commit/git/bc9b7fe2b7507b1959f098cb6b5ca984b79d6121",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/bc9b7fe2b7507b1959f098cb6b5ca984b79d6121",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023100175161931808",
"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/8857670113067302400/+/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": "1611064533333208",
"url": "https://ci.chromium.org/b/8857670113067302400",
"utcnow_ts": "1611064533587998"
}
}
[13:55:33.602] Task URL: https://ci.chromium.org/b/8857670113067302400
[13:55:33.602] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023100175161931808:2:0) after 1m0s
[13:55:39.065] Received PubSub notification, asking Buildbucket for the build status
[13:55:39.139] Build 8857670113067302400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:56:33.812] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023100175161931808:2:0)
[13:56:33.812] Timer tick, asking Buildbucket for the build status
[13:56:33.953] Build 8857670113067302400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:56:33.953] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023100175161931808:4:0) after 1m0s
[13:57:34.071] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023100175161931808:4:0)
[13:57:34.071] Timer tick, asking Buildbucket for the build status
[13:57:34.601] Build 8857670113067302400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:57:34.601] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023100175161931808:5:0) after 1m0s
[13:58:34.689] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023100175161931808:5:0)
[13:58:34.689] Timer tick, asking Buildbucket for the build status
[13:58:34.905] Build 8857670113067302400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:58:34.905] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023100175161931808:6:0) after 1m0s
[13:59:34.959] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023100175161931808:6:0)
[13:59:34.959] Timer tick, asking Buildbucket for the build status
[13:59:35.095] Build 8857670113067302400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:59:35.095] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023100175161931808:7:0) after 1m0s
[14:00:35.188] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023100175161931808:7:0)
[14:00:35.188] Timer tick, asking Buildbucket for the build status
[14:00:35.393] Build 8857670113067302400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:00:35.393] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023100175161931808:8:0) after 1m0s
[14:01:35.391] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023100175161931808:8:0)
[14:01:35.391] Timer tick, asking Buildbucket for the build status
[14:01:35.603] Build 8857670113067302400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:01:35.603] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023100175161931808:9:0) after 1m0s
[14:02:35.716] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023100175161931808:9:0)
[14:02:35.716] Timer tick, asking Buildbucket for the build status
[14:02:35.787] Build 8857670113067302400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:02:35.788] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023100175161931808:10:0) after 1m0s
[14:03:35.848] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023100175161931808:10:0)
[14:03:35.848] Timer tick, asking Buildbucket for the build status
[14:03:35.910] Build 8857670113067302400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:03:35.910] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023100175161931808:11:0) after 1m0s
[14:04:35.982] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023100175161931808:11:0)
[14:04:35.982] Timer tick, asking Buildbucket for the build status
[14:04:36.053] Build 8857670113067302400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:04:36.053] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023100175161931808:12:0) after 1m0s
[14:05:36.144] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023100175161931808:12:0)
[14:05:36.144] Timer tick, asking Buildbucket for the build status
[14:05:36.213] Build 8857670113067302400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:05:36.213] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023100175161931808:13:0) after 1m0s
[14:06:36.284] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023100175161931808:13:0)
[14:06:36.284] Timer tick, asking Buildbucket for the build status
[14:06:36.388] Build 8857670113067302400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:06:36.388] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023100175161931808:14:0) after 1m0s
[14:07:36.384] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023100175161931808:14:0)
[14:07:36.384] Timer tick, asking Buildbucket for the build status
[14:07:36.456] Build 8857670113067302400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:07:36.456] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023100175161931808:15:0) after 1m0s
[14:08:36.470] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023100175161931808:15:0)
[14:08:36.470] Timer tick, asking Buildbucket for the build status
[14:08:36.576] Build 8857670113067302400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:08:36.576] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023100175161931808:16:0) after 1m0s
[14:09:36.672] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023100175161931808:16:0)
[14:09:36.672] Timer tick, asking Buildbucket for the build status
[14:09:36.742] Build 8857670113067302400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:09:36.742] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023100175161931808:17:0) after 1m0s
[14:10:36.803] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023100175161931808:17:0)
[14:10:36.803] Timer tick, asking Buildbucket for the build status
[14:10:36.860] Build 8857670113067302400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:10:36.860] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023100175161931808:18:0) after 1m0s
[14:11:36.892] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023100175161931808:18:0)
[14:11:36.892] Timer tick, asking Buildbucket for the build status
[14:11:36.987] Build 8857670113067302400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:11:36.987] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023100175161931808:19:0) after 1m0s
[14:12:37.125] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023100175161931808:19:0)
[14:12:37.125] Timer tick, asking Buildbucket for the build status
[14:12:37.182] Build 8857670113067302400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:12:37.182] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023100175161931808:20:0) after 1m0s
[14:13:37.212] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023100175161931808:20:0)
[14:13:37.212] Timer tick, asking Buildbucket for the build status
[14:13:37.285] Build 8857670113067302400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:13:37.285] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023100175161931808:21:0) after 1m0s
[14:14:37.316] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023100175161931808:21:0)
[14:14:37.316] Timer tick, asking Buildbucket for the build status
[14:14:37.376] Build 8857670113067302400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:14:37.376] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023100175161931808:22:0) after 1m0s
[14:15:37.453] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023100175161931808:22:0)
[14:15:37.453] Timer tick, asking Buildbucket for the build status
[14:15:37.599] Build 8857670113067302400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:15:37.599] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023100175161931808:23:0) after 1m0s
[14:16:37.595] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023100175161931808:23:0)
[14:16:37.595] Timer tick, asking Buildbucket for the build status
[14:16:37.898] Build 8857670113067302400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:16:37.898] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023100175161931808:24:0) after 1m0s
[14:17:37.921] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023100175161931808:24:0)
[14:17:37.921] Timer tick, asking Buildbucket for the build status
[14:17:38.015] Build 8857670113067302400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:17:38.015] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023100175161931808:25:0) after 1m0s
[14:18:38.003] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023100175161931808:25:0)
[14:18:38.003] Timer tick, asking Buildbucket for the build status
[14:18:38.062] Build 8857670113067302400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:18:38.062] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023100175161931808:26:0) after 1m0s
[14:19:38.243] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023100175161931808:26:0)
[14:19:38.243] Timer tick, asking Buildbucket for the build status
[14:19:38.335] Build 8857670113067302400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:19:38.335] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023100175161931808:27:0) after 1m0s
[14:20:38.395] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023100175161931808:27:0)
[14:20:38.395] Timer tick, asking Buildbucket for the build status
[14:20:38.790] Build 8857670113067302400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:20:38.790] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023100175161931808:28:0) after 1m0s
[14:21:38.877] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023100175161931808:28:0)
[14:21:38.877] Timer tick, asking Buildbucket for the build status
[14:21:38.989] Build 8857670113067302400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:21:38.989] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023100175161931808:29:0) after 1m0s
[14:22:39.131] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023100175161931808:29:0)
[14:22:39.131] Timer tick, asking Buildbucket for the build status
[14:22:39.210] Build 8857670113067302400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:22:39.210] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023100175161931808:30:0) after 1m0s
[14:23:39.261] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023100175161931808:30:0)
[14:23:39.261] Timer tick, asking Buildbucket for the build status
[14:23:39.329] Build 8857670113067302400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:23:39.329] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023100175161931808:31:0) after 1m0s
[14:24:39.416] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023100175161931808:31:0)
[14:24:39.416] Timer tick, asking Buildbucket for the build status
[14:24:39.485] Build 8857670113067302400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:24:39.485] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023100175161931808:32:0) after 1m0s
[14:25:39.530] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023100175161931808:32:0)
[14:25:39.530] Timer tick, asking Buildbucket for the build status
[14:25:40.041] Build 8857670113067302400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:25:40.042] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023100175161931808:33:0) after 1m0s
[14:26:40.062] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023100175161931808:33:0)
[14:26:40.063] Timer tick, asking Buildbucket for the build status
[14:26:40.137] Build 8857670113067302400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:26:40.137] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023100175161931808:34:0) after 1m0s
[14:27:40.171] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023100175161931808:34:0)
[14:27:40.171] Timer tick, asking Buildbucket for the build status
[14:27:40.235] Build 8857670113067302400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:27:40.235] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023100175161931808:35:0) after 1m0s
[14:28:40.344] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023100175161931808:35:0)
[14:28:40.344] Timer tick, asking Buildbucket for the build status
[14:28:40.430] Build 8857670113067302400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:28:40.430] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023100175161931808:36:0) after 1m0s
[14:29:40.511] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023100175161931808:36:0)
[14:29:40.511] Timer tick, asking Buildbucket for the build status
[14:29:40.663] Build 8857670113067302400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:29:40.663] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023100175161931808:37:0) after 1m0s
[14:30:40.708] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023100175161931808:37:0)
[14:30:40.708] Timer tick, asking Buildbucket for the build status
[14:30:40.933] Build 8857670113067302400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:30:40.933] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023100175161931808:38:0) after 1m0s
[14:31:41.049] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023100175161931808:38:0)
[14:31:41.049] Timer tick, asking Buildbucket for the build status
[14:31:41.122] Build 8857670113067302400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:31:41.122] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023100175161931808:39:0) after 1m0s
[14:32:41.154] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023100175161931808:39:0)
[14:32:41.154] Timer tick, asking Buildbucket for the build status
[14:32:41.523] Build 8857670113067302400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:32:41.523] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023100175161931808:40:0) after 1m0s
[14:33:41.566] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023100175161931808:40:0)
[14:33:41.566] Timer tick, asking Buildbucket for the build status
[14:33:41.625] Build 8857670113067302400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:33:41.625] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023100175161931808:41:0) after 1m0s
[14:34:41.720] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023100175161931808:41:0)
[14:34:41.720] Timer tick, asking Buildbucket for the build status
[14:34:41.785] Build 8857670113067302400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:34:41.786] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023100175161931808:42:0) after 1m0s
[14:35:41.806] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023100175161931808:42:0)
[14:35:41.806] Timer tick, asking Buildbucket for the build status
[14:35:42.169] Build 8857670113067302400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:35:42.169] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023100175161931808:43:0) after 1m0s
[14:36:42.190] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023100175161931808:43:0)
[14:36:42.190] Timer tick, asking Buildbucket for the build status
[14:36:42.267] Build 8857670113067302400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:36:42.267] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023100175161931808:44:0) after 1m0s
[14:37:42.287] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023100175161931808:44:0)
[14:37:42.287] Timer tick, asking Buildbucket for the build status
[14:37:42.363] Build 8857670113067302400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:37:42.363] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023100175161931808:45:0) after 1m0s
[14:38:42.402] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023100175161931808:45:0)
[14:38:42.402] Timer tick, asking Buildbucket for the build status
[14:38:42.461] Build 8857670113067302400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:38:42.461] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023100175161931808:46:0) after 1m0s
[14:39:42.575] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023100175161931808:46:0)
[14:39:42.575] Timer tick, asking Buildbucket for the build status
[14:39:42.685] Build 8857670113067302400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:39:42.685] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023100175161931808:47:0) after 1m0s
[14:40:42.781] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023100175161931808:47:0)
[14:40:42.781] Timer tick, asking Buildbucket for the build status
[14:40:43.183] Build 8857670113067302400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:40:43.183] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023100175161931808:48:0) after 1m0s
[14:41:43.481] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023100175161931808:48:0)
[14:41:43.481] Timer tick, asking Buildbucket for the build status
[14:41:43.590] Build 8857670113067302400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:41:43.590] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023100175161931808:49:0) after 1m0s
[14:42:43.854] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023100175161931808:49:0)
[14:42:43.854] Timer tick, asking Buildbucket for the build status
[14:42:43.916] Build 8857670113067302400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:42:43.916] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023100175161931808:50:0) after 1m0s
[14:43:44.148] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023100175161931808:50:0)
[14:43:44.148] Timer tick, asking Buildbucket for the build status
[14:43:44.531] Build 8857670113067302400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:43:44.531] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023100175161931808:51:0) after 1m0s
[14:44:44.733] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023100175161931808:51:0)
[14:44:44.733] Timer tick, asking Buildbucket for the build status
[14:44:44.815] Build 8857670113067302400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:44:44.815] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023100175161931808:52:0) after 1m0s
[14:45:44.996] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023100175161931808:52:0)
[14:45:44.996] Timer tick, asking Buildbucket for the build status
[14:45:45.113] Build 8857670113067302400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:45:45.113] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023100175161931808:53:0) after 1m0s
[14:46:45.139] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023100175161931808:53:0)
[14:46:45.139] Timer tick, asking Buildbucket for the build status
[14:46:45.327] Build 8857670113067302400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:46:45.327] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023100175161931808:54:0) after 1m0s
[14:47:45.363] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023100175161931808:54:0)
[14:47:45.363] Timer tick, asking Buildbucket for the build status
[14:47:45.557] Build 8857670113067302400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:47:45.557] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023100175161931808:55:0) after 1m0s
[14:48:45.647] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023100175161931808:55:0)
[14:48:45.647] Timer tick, asking Buildbucket for the build status
[14:48:45.977] Build 8857670113067302400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:48:45.977] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023100175161931808:56:0) after 1m0s
[14:49:46.069] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023100175161931808:56:0)
[14:49:46.069] Timer tick, asking Buildbucket for the build status
[14:49:46.268] Build 8857670113067302400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:49:46.268] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023100175161931808:57:0) after 1m0s
[14:50:46.332] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023100175161931808:57:0)
[14:50:46.332] Timer tick, asking Buildbucket for the build status
[14:50:46.465] Build 8857670113067302400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:50:46.465] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023100175161931808:58:0) after 1m0s
[14:51:46.482] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023100175161931808:58:0)
[14:51:46.482] Timer tick, asking Buildbucket for the build status
[14:51:46.660] Build 8857670113067302400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:51:46.660] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023100175161931808:59:0) after 1m0s
[14:52:46.633] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023100175161931808:59:0)
[14:52:46.633] Timer tick, asking Buildbucket for the build status
[14:52:46.903] Build 8857670113067302400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:52:46.903] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023100175161931808:60:0) after 1m0s
[14:53:46.975] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023100175161931808:60:0)
[14:53:46.975] Timer tick, asking Buildbucket for the build status
[14:53:47.269] Build 8857670113067302400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:53:47.269] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023100175161931808:61:0) after 1m0s
[14:54:47.401] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023100175161931808:61:0)
[14:54:47.401] Timer tick, asking Buildbucket for the build status
[14:54:47.591] Build 8857670113067302400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:54:47.591] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023100175161931808:62:0) after 1m0s
[14:55:47.639] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023100175161931808:62:0)
[14:55:47.639] Timer tick, asking Buildbucket for the build status
[14:55:47.735] Build 8857670113067302400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:55:47.735] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023100175161931808:63:0) after 1m0s
[14:56:40.357] Received PubSub notification, asking Buildbucket for the build status
[14:56:40.547] Build 8857670113067302400: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[14:56:40.548] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1611068198876107",
"created_by": "project:chromium",
"created_ts": "1611064532176787",
"id": "8857670113067302400",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"bc9b7fe2b7507b1959f098cb6b5ca984b79d6121\"}}",
"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\": \"bc9b7fe2b7507b1959f098cb6b5ca984b79d6121\", \"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-2-c7p5\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-rel\", \"buildnumber\": 44737, \"got_angle_revision\": \"b69f54069cbf700a4646817805ca77c0d2a01292\", \"got_dawn_revision\": \"4b316994ad01ab6b87032c5fd9b53c6d18956d8d\", \"got_nacl_revision\": \"cb31feffa500401c95c6c44d88b7c358236bca36\", \"got_revision\": \"bc9b7fe2b7507b1959f098cb6b5ca984b79d6121\", \"got_revision_cp\": \"refs/heads/master@{#844754}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"8da0f82baf359e2b91464f111306a094f86fe71b\", \"got_v8_revision\": \"035dfc7b4d7aa680a6e9be05ce752231ae227bea\", \"got_v8_revision_cp\": \"refs/heads/9.0.12@{#1}\", \"got_webrtc_revision\": \"29bd8638adb0a7294f7b8ae589fdd24077539dbb\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33030}\", \"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\": \"bc9b7fe2b7507b1959f098cb6b5ca984b79d6121\", \"swarm_hashes_refs/heads/master(at){#844754}_without_patch\": {\"absl_hardening_tests\": \"4767f927d898e6fd5b931ab9ba7c7b063651b93c\", \"accessibility_unittests\": \"83f9230a6002c58aad4e8e91a4ff299da1cd6fa8\", \"angle_unittests\": \"bd202ff555510f9e9fa3c8584e2d3704b5a4d4c3\", \"app_list_unittests\": \"033e4785a46de3064e15c4695ea0fd10e8ff7ef3\", \"app_shell_unittests\": \"952bb74e9570b17d7ef239842f98fc293c351861\", \"ash_unittests\": \"9080fb5f8fc05321e422fdd38c000d82ec111503\", \"aura_unittests\": \"7818308c9b0da5585f215d737587bdfa0cfb03c6\", \"base_unittests\": \"c27b011408ad3effc8905a31ca51d57cc1e4c3c7\", \"base_util_unittests\": \"f997a83cfd9d6aa6af78e38edf59725b845a69d4\", \"blink_common_unittests\": \"7cc8360b16c6a45fd5018c0673eac6cfa4862e87\", \"blink_fuzzer_unittests\": \"bba48329a18b7a24d1ea85ef573407e97eafcab4\", \"blink_heap_unittests\": \"c353c83050fb1aa18fdc62e0687ecc73cdc73b26\", \"blink_platform_unittests\": \"2f8ebc98af075e0333f4ec9362c000aa0c67b21b\", \"blink_unittests\": \"a55cb4d4ac0520e4f7589be0403a9e21659792dc\", \"boringssl_crypto_tests\": \"607aeda92f0a65ed54f5277d0014f4c93b429942\", \"boringssl_ssl_tests\": \"e49e5de5a0ccd0e64126f1b02db682ade2596fde\", \"browser_tests\": \"cd62cd9bbdca9dfa9527f01cd5fed179a277de53\", \"capture_unittests\": \"1f49bd9a23b2767900382c8d9390bb462ae5811e\", \"cast_unittests\": \"31b6b195c257d5cf1300b5976f78948d437deff8\", \"cc_unittests\": \"2452ffbdb1fcc4995fec35fad3bc1a20c2cfa0c9\", \"chrome_app_unittests\": \"a3234831cbfc6cd18a2fe776b809b6c460001452\", \"chromedriver_unittests\": \"8213b753121c14ca83e3424dcfa2473f993d9e60\", \"chromeos_components_unittests\": \"3eb904ba7e6285ea360f400ce8fd5fffabe262a9\", \"chromeos_unittests\": \"82e7010be43a75c204c281e5ccfc86ebeb0488a9\", \"components_browsertests\": \"2c730c95b8a5ae99a03b51541ceb9308166299ca\", \"components_unittests\": \"8768875c1b28f0f67b9438b075572bfa2141de1b\", \"compositor_unittests\": \"dafff482d733fa6a5e1a388806388b02448d5120\", \"content_browsertests\": \"864819d1905c1a6533b13e48ac681f743ff58875\", \"content_unittests\": \"1cfb9057919e08821876cbe6e0700d0d2007fce8\", \"crashpad_tests\": \"c8b8f194332e0def8feafb74f1533fc7cc2fcc5d\", \"crypto_unittests\": \"43be19d903c8f56137a631d541c68dd9ddccceea\", \"dbus_unittests\": \"d9856378c21d3e8a6a76a2008496e5abc5de4027\", \"device_unittests\": \"4ef7267a76131d244bacbb1ba88cd41fa37674ba\", \"display_unittests\": \"f69eb7abb78873554a6294df797ed067c2904f8d\", \"events_unittests\": \"ba7ac40f68e98073709ed1facd7fd007d8e75926\", \"exo_unittests\": \"a106eeec1bf212021e17e6bc536bb96ccea337a3\", \"extensions_browsertests\": \"58250467ce7ff73e2d1e1ddb64e2d2c58be33f82\", \"extensions_unittests\": \"970a85f1306aac2f31d2b2f87fd55f0e31acf82d\", \"filesystem_service_unittests\": \"c97d17a4221db2f02829897d7b3390d398792a07\", \"gcm_unit_tests\": \"8c73a8a798f47b1778639f21cd966b9e7a2dd252\", \"gfx_unittests\": \"8eb753e8bb19351835b327408269e1c10ea82827\", \"gin_unittests\": \"4eb9f3d93cc183bdbbcb3a65d92a2d59fe4000d8\", \"gl_unittests_ozone\": \"bff72ec5c309ee7f8a6bfb5555f3a96cd3bc1237\", \"google_apis_unittests\": \"a76389fb51675283bcc30f023f671aa6bd5327c1\", \"gpu_unittests\": \"28b2d23f0cb0d96bf5fc76225e5142193240c061\", \"gwp_asan_unittests\": \"ce06764402fcba2e4b55dec0279c3f70c0818ad7\", \"interactive_ui_tests\": \"1e0352ded705b0e1eeda820cd464bb35f89f96a3\", \"ipc_tests\": \"ac01b2b3e044a4a237454a3e105081161481fa1f\", \"jingle_unittests\": \"652470fa4b3eb86c2274ae4e6c4d9c71e2e2865d\", \"keyboard_unittests\": \"aa577b883de1dc632614225e7bd2ee3a08fbc1b9\", \"latency_unittests\": \"1d7afbf397aa7a4cf129eed08f309ce9f4ef512c\", \"libjingle_xmpp_unittests\": \"c11faac12f1c20ac7eba0721a6ac93b77611a610\", \"liburlpattern_unittests\": \"a6bfbf9d2e627cdaec9a4ec0f1e79418c813b938\", \"media_blink_unittests\": \"3d3be2feb85e2c9371008b8684483e66255f9ae6\", \"media_unittests\": \"7b06c079c3c5c2e844ba884aa41e90650187390d\", \"message_center_unittests\": \"4b1c60fa2c8b4a74f8177328651459b28c74dca4\", \"midi_unittests\": \"45d3d0228125533f3a492f70b80a068ff2be3121\", \"mojo_core_unittests\": \"71d8272c942951dd3a4f1184612d586f79b8dcd0\", \"mojo_unittests\": \"5728aec415f95e8874dd00b28477d1a94ce396ab\", \"nacl_helper_nonsfi_unittests\": \"34bdd76a5dfa2e0c6deac254a2236e146c103472\", \"nacl_loader_unittests\": \"e2a70c68f69f42c159dcea8e8cfcc7b1eea83c85\", \"native_theme_unittests\": \"6a799851cc0b8430d636449f77fccc7ab0018ff4\", \"net_unittests\": \"26601edee10bebe6fbcf38f9b4da5520c68fd662\", \"ozone_gl_unittests\": \"6aaa198fd017cf95715fd86e1a43619ccb5a7b43\", \"ozone_unittests\": \"e54f5c45b862613115e5f5cd51f2e93e2e0f8744\", \"ozone_x11_unittests\": \"9ae9d8cf6af50d386a63f09e9f993e8ca42f523f\", \"pdf_unittests\": \"667b272acb744dc87edfa9d73a067eb577dd3d05\", \"perfetto_unittests\": \"d6b58341369a218ce386244fd703c85942cd4097\", \"ppapi_unittests\": \"4741322cab93f2ffaa47331c9d2c0ecedbb62ed0\", \"printing_unittests\": \"87f2b4c1f47d5917648c7ce0139c9159b6c99d20\", \"remoting_unittests\": \"349ceb3e3148ee7110651e3ce04a7010fdb5434f\", \"sandbox_linux_unittests\": \"f7d6383e207fb40d3cae571fd0d29c16c02c45cc\", \"service_manager_unittests\": \"e969a993c30cf0c394b7c72323ff6af33ce0ab2b\", \"services_unittests\": \"b75074fbf7867099f07db6e31044f4d710d37839\", \"shell_dialogs_unittests\": \"f83fc27257fddc13d4d05faad3d7baa48a7f0782\", \"shell_encryption_unittests\": \"17fc0f877c05ae9d47c5512d00a4e178642e21d9\", \"skia_unittests\": \"c283a0e71658da0f3b9820820588f14a198d6169\", \"snapshot_unittests\": \"8bc1432457949cd6859e0a8fc57a6a51cb682821\", \"sql_unittests\": \"a56e2a2978fba995525a6ee1e96bcece0f0e588c\", \"storage_unittests\": \"adbf0d74b0e913a86392047402f0ca8c29fd41e8\", \"sync_integration_tests\": \"708718c6b10cfb32e9cc1e66f3428b1a44d76851\", \"traffic_annotation_auditor_unittests\": \"129682d3678d909ee3340699b41077dfc6c5337a\", \"ui_base_unittests\": \"2efc6bc34663f381c0f3dbd4e7306e29bc7c2f74\", \"ui_chromeos_unittests\": \"caa1e231ddd07fb1d5b86f3d4b348939fcf61c47\", \"ui_touch_selection_unittests\": \"5076b07273ae0f6fc4b8d0ada983e40efdb5aa45\", \"unit_tests\": \"ee7bd16768bb9eadc6273d9e24f0e7439e3a32de\", \"url_unittests\": \"e9fdbfc58b66e5853ff2af5b99e0edc452be2bf0\", \"usage_time_limit_unittests\": \"aa6124a8188db762afa83f28915927d38bf7ada9\", \"views_unittests\": \"5c1841c0d7bf5783a2bbfc1952427c392bec4a9b\", \"viz_unittests\": \"fe9a1e3b9596ca90bb5f0331368f6af5e6740cb3\", \"wayland_client_perftests\": \"ecc837b03122be732d6974cb2b619d5e8d8922bb\", \"wm_unittests\": \"d41b421695554f59d5bcc69df8d913180871cced\", \"wtf_unittests\": \"089cc225c8c7737c53c46ad0f41544c3eb0be354\", \"zlib_unittests\": \"dff1d2dad7cdcce911d3f679ff07a49374997c16\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_2295bac873f7edd8c9763e89419d85b38a875955550d968c436d775d3f06377b_v2\", \"builder_4026221a727cf2e856e94035b8eeb49c4c65d62e8974e693170551540c198531_v2\", \"builder_4bb8df61718ee3173d82ea540402eadde21707c87b43c35a861cfb17546437c1_v2\", \"builder_94fa3119a28f4651cdd4fc5b981a64c69b26c151212072333439362290628993_v2\", \"builder_97b15bccca8d35086c1bc50eecf0b53d98289cdc65be75a287efcc384d1f34ed_v2\", \"builder_980e79f61a9eb10b3ecd5f710f08f0308a5299da9889db3d2b6a7010c019cc1f_v2\", \"builder_d1ade7eeaf5d4bfec46cdcc4260ff7266c5ca5ac9f935f1643683e9e2b66e303_v2\", \"builder_fe96da9c6a19fa2fc3ad0c2b7020b7cb68e4a92161f4cd6cb7dc1d1e2d5af5dc_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-2-c7p5\"], \"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\": [\"5666-679c9bd\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-d\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1611064537028604",
"status": "COMPLETED",
"status_changed_ts": "1611068199214938",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/44737",
"builder:linux-chromeos-rel",
"buildset:commit/git/bc9b7fe2b7507b1959f098cb6b5ca984b79d6121",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/bc9b7fe2b7507b1959f098cb6b5ca984b79d6121",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023100175161931808",
"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/8857670113067302400/+/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:5133bf74ebb9e810",
"user_agent:luci-scheduler"
],
"updated_ts": "1611068199227879",
"url": "https://ci.chromium.org/b/8857670113067302400",
"utcnow_ts": "1611068200523723"
}
}
[14:56:40.548] Invocation finished in 1h1m9.752063833s with status SUCCEEDED