[01:35:36.678] New invocation is queued and will start shortly
[01:35:37.784] Starting the invocation (attempt 1)
[01:35:37.823] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[01:35:37.823] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[01:35:37.823] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9028491946802719216",
"parameters_json": "{\"builder_name\":\"linux-chromeos-rel\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"8169705488b1606f6bd061f87b72771d13baf93a\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-rel",
"scheduler_invocation_id:9028491946802719216",
"scheduler_job_id:chromium/linux-chromeos-rel",
"user_agent:luci-scheduler",
"buildset:commit/git/8169705488b1606f6bd061f87b72771d13baf93a",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/8169705488b1606f6bd061f87b72771d13baf93a",
"gitiles_ref:refs/heads/master"
]
}
[01:35:38.414] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1605922537896039",
"id": "8863061884861866848",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"8169705488b1606f6bd061f87b72771d13baf93a\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1605922538280284",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/43038",
"builder:linux-chromeos-rel",
"buildset:commit/git/8169705488b1606f6bd061f87b72771d13baf93a",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/8169705488b1606f6bd061f87b72771d13baf93a",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9028491946802719216",
"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/8863061884861866848/+/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": "1605922538280443",
"url": "https://ci.chromium.org/b/8863061884861866848",
"utcnow_ts": "1605922538404894"
}
}
[01:35:38.414] Task URL: https://ci.chromium.org/b/8863061884861866848
[01:35:38.414] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028491946802719216:2:0) after 1m0s
[01:36:38.413] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028491946802719216:2:0)
[01:36:38.413] Timer tick, asking Buildbucket for the build status
[01:36:38.641] Build 8863061884861866848: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:36:38.641] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028491946802719216:3:0) after 1m0s
[01:37:21.967] Received PubSub notification, asking Buildbucket for the build status
[01:37:22.074] Build 8863061884861866848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:37:38.659] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028491946802719216:3:0)
[01:37:38.659] Timer tick, asking Buildbucket for the build status
[01:37:38.725] Build 8863061884861866848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:37:38.725] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028491946802719216:5:0) after 1m0s
[01:38:38.764] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028491946802719216:5:0)
[01:38:38.764] Timer tick, asking Buildbucket for the build status
[01:38:38.930] Build 8863061884861866848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:38:38.930] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028491946802719216:6:0) after 1m0s
[01:39:39.059] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028491946802719216:6:0)
[01:39:39.059] Timer tick, asking Buildbucket for the build status
[01:39:39.123] Build 8863061884861866848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:39:39.123] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028491946802719216:7:0) after 1m0s
[01:40:39.195] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028491946802719216:7:0)
[01:40:39.195] Timer tick, asking Buildbucket for the build status
[01:40:39.301] Build 8863061884861866848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:40:39.301] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028491946802719216:8:0) after 1m0s
[01:41:39.288] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028491946802719216:8:0)
[01:41:39.288] Timer tick, asking Buildbucket for the build status
[01:41:39.385] Build 8863061884861866848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:41:39.385] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028491946802719216:9:0) after 1m0s
[01:42:39.441] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028491946802719216:9:0)
[01:42:39.441] Timer tick, asking Buildbucket for the build status
[01:42:39.612] Build 8863061884861866848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:42:39.612] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028491946802719216:10:0) after 1m0s
[01:43:39.701] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028491946802719216:10:0)
[01:43:39.701] Timer tick, asking Buildbucket for the build status
[01:43:39.820] Build 8863061884861866848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:43:39.820] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028491946802719216:11:0) after 1m0s
[01:44:39.851] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028491946802719216:11:0)
[01:44:39.851] Timer tick, asking Buildbucket for the build status
[01:44:39.931] Build 8863061884861866848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:44:39.931] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028491946802719216:12:0) after 1m0s
[01:45:40.105] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028491946802719216:12:0)
[01:45:40.105] Timer tick, asking Buildbucket for the build status
[01:45:40.167] Build 8863061884861866848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:45:40.167] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028491946802719216:13:0) after 1m0s
[01:46:40.439] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028491946802719216:13:0)
[01:46:40.439] Timer tick, asking Buildbucket for the build status
[01:46:40.543] Build 8863061884861866848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:46:40.543] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028491946802719216:14:0) after 1m0s
[01:47:40.698] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028491946802719216:14:0)
[01:47:40.698] Timer tick, asking Buildbucket for the build status
[01:47:41.224] Build 8863061884861866848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:47:41.224] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028491946802719216:15:0) after 1m0s
[01:48:41.345] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028491946802719216:15:0)
[01:48:41.345] Timer tick, asking Buildbucket for the build status
[01:48:41.405] Build 8863061884861866848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:48:41.405] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028491946802719216:16:0) after 1m0s
[01:49:41.557] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028491946802719216:16:0)
[01:49:41.557] Timer tick, asking Buildbucket for the build status
[01:49:41.617] Build 8863061884861866848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:49:41.618] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028491946802719216:17:0) after 1m0s
[01:50:41.695] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028491946802719216:17:0)
[01:50:41.695] Timer tick, asking Buildbucket for the build status
[01:50:43.383] Build 8863061884861866848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:50:43.383] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028491946802719216:18:0) after 1m0s
[01:51:43.478] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028491946802719216:18:0)
[01:51:43.478] Timer tick, asking Buildbucket for the build status
[01:51:43.926] Build 8863061884861866848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:51:43.926] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028491946802719216:19:0) after 1m0s
[01:52:44.001] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028491946802719216:19:0)
[01:52:44.001] Timer tick, asking Buildbucket for the build status
[01:52:44.061] Build 8863061884861866848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:52:44.061] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028491946802719216:20:0) after 1m0s
[01:53:44.115] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028491946802719216:20:0)
[01:53:44.115] Timer tick, asking Buildbucket for the build status
[01:53:44.172] Build 8863061884861866848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:53:44.172] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028491946802719216:21:0) after 1m0s
[01:54:44.234] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028491946802719216:21:0)
[01:54:44.234] Timer tick, asking Buildbucket for the build status
[01:54:44.287] Build 8863061884861866848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:54:44.287] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028491946802719216:22:0) after 1m0s
[01:55:44.429] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028491946802719216:22:0)
[01:55:44.429] Timer tick, asking Buildbucket for the build status
[01:55:44.540] Build 8863061884861866848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:55:44.540] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028491946802719216:23:0) after 1m0s
[01:56:44.842] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028491946802719216:23:0)
[01:56:44.842] Timer tick, asking Buildbucket for the build status
[01:56:44.903] Build 8863061884861866848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:56:44.903] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028491946802719216:24:0) after 1m0s
[01:57:44.955] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028491946802719216:24:0)
[01:57:44.955] Timer tick, asking Buildbucket for the build status
[01:57:45.264] Build 8863061884861866848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:57:45.265] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028491946802719216:25:0) after 1m0s
[01:58:45.273] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028491946802719216:25:0)
[01:58:45.273] Timer tick, asking Buildbucket for the build status
[01:58:45.346] Build 8863061884861866848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:58:45.346] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028491946802719216:26:0) after 1m0s
[01:59:45.398] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028491946802719216:26:0)
[01:59:45.398] Timer tick, asking Buildbucket for the build status
[01:59:45.470] Build 8863061884861866848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:59:45.470] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028491946802719216:27:0) after 1m0s
[02:00:45.658] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028491946802719216:27:0)
[02:00:45.658] Timer tick, asking Buildbucket for the build status
[02:00:45.799] Build 8863061884861866848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:00:45.799] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028491946802719216:28:0) after 1m0s
[02:01:45.844] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028491946802719216:28:0)
[02:01:45.844] Timer tick, asking Buildbucket for the build status
[02:01:45.933] Build 8863061884861866848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:01:45.933] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028491946802719216:29:0) after 1m0s
[02:02:45.962] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028491946802719216:29:0)
[02:02:45.962] Timer tick, asking Buildbucket for the build status
[02:02:46.024] Build 8863061884861866848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:02:46.024] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028491946802719216:30:0) after 1m0s
[02:03:46.145] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028491946802719216:30:0)
[02:03:46.145] Timer tick, asking Buildbucket for the build status
[02:03:46.222] Build 8863061884861866848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:03:46.222] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028491946802719216:31:0) after 1m0s
[02:04:46.298] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028491946802719216:31:0)
[02:04:46.298] Timer tick, asking Buildbucket for the build status
[02:04:46.426] Build 8863061884861866848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:04:46.426] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028491946802719216:32:0) after 1m0s
[02:05:46.477] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028491946802719216:32:0)
[02:05:46.477] Timer tick, asking Buildbucket for the build status
[02:05:46.968] Build 8863061884861866848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:05:46.968] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028491946802719216:33:0) after 1m0s
[02:06:47.010] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028491946802719216:33:0)
[02:06:47.010] Timer tick, asking Buildbucket for the build status
[02:06:47.248] Build 8863061884861866848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:06:47.248] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028491946802719216:34:0) after 1m0s
[02:07:47.233] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028491946802719216:34:0)
[02:07:47.233] Timer tick, asking Buildbucket for the build status
[02:07:47.449] Build 8863061884861866848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:07:47.449] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028491946802719216:35:0) after 1m0s
[02:08:47.476] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028491946802719216:35:0)
[02:08:47.476] Timer tick, asking Buildbucket for the build status
[02:08:47.567] Build 8863061884861866848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:08:47.567] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028491946802719216:36:0) after 1m0s
[02:09:47.599] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028491946802719216:36:0)
[02:09:47.599] Timer tick, asking Buildbucket for the build status
[02:09:47.814] Build 8863061884861866848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:09:47.814] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028491946802719216:37:0) after 1m0s
[02:10:47.886] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028491946802719216:37:0)
[02:10:47.886] Timer tick, asking Buildbucket for the build status
[02:10:47.979] Build 8863061884861866848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:10:47.979] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028491946802719216:38:0) after 1m0s
[02:11:48.026] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028491946802719216:38:0)
[02:11:48.026] Timer tick, asking Buildbucket for the build status
[02:11:48.207] Build 8863061884861866848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:11:48.207] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028491946802719216:39:0) after 1m0s
[02:12:48.273] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028491946802719216:39:0)
[02:12:48.273] Timer tick, asking Buildbucket for the build status
[02:12:48.523] Build 8863061884861866848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:12:48.523] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028491946802719216:40:0) after 1m0s
[02:13:48.588] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028491946802719216:40:0)
[02:13:48.588] Timer tick, asking Buildbucket for the build status
[02:13:48.899] Build 8863061884861866848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:13:48.899] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028491946802719216:41:0) after 1m0s
[02:14:48.939] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028491946802719216:41:0)
[02:14:48.939] Timer tick, asking Buildbucket for the build status
[02:14:49.236] Build 8863061884861866848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:14:49.236] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028491946802719216:42:0) after 1m0s
[02:15:49.305] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028491946802719216:42:0)
[02:15:49.305] Timer tick, asking Buildbucket for the build status
[02:15:49.438] Build 8863061884861866848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:15:49.438] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028491946802719216:43:0) after 1m0s
[02:16:49.462] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028491946802719216:43:0)
[02:16:49.462] Timer tick, asking Buildbucket for the build status
[02:16:49.536] Build 8863061884861866848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:16:49.536] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028491946802719216:44:0) after 1m0s
[02:17:49.612] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028491946802719216:44:0)
[02:17:49.612] Timer tick, asking Buildbucket for the build status
[02:17:49.877] Build 8863061884861866848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:17:49.877] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028491946802719216:45:0) after 1m0s
[02:18:49.910] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028491946802719216:45:0)
[02:18:49.910] Timer tick, asking Buildbucket for the build status
[02:18:50.036] Build 8863061884861866848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:18:50.036] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028491946802719216:46:0) after 1m0s
[02:19:50.091] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028491946802719216:46:0)
[02:19:50.091] Timer tick, asking Buildbucket for the build status
[02:19:50.287] Build 8863061884861866848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:19:50.287] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028491946802719216:47:0) after 1m0s
[02:20:50.351] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028491946802719216:47:0)
[02:20:50.351] Timer tick, asking Buildbucket for the build status
[02:20:50.457] Build 8863061884861866848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:20:50.457] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028491946802719216:48:0) after 1m0s
[02:21:50.624] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028491946802719216:48:0)
[02:21:50.625] Timer tick, asking Buildbucket for the build status
[02:21:50.738] Build 8863061884861866848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:21:50.738] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028491946802719216:49:0) after 1m0s
[02:22:50.756] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028491946802719216:49:0)
[02:22:50.756] Timer tick, asking Buildbucket for the build status
[02:22:50.863] Build 8863061884861866848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:22:50.863] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028491946802719216:50:0) after 1m0s
[02:23:50.921] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028491946802719216:50:0)
[02:23:50.921] Timer tick, asking Buildbucket for the build status
[02:23:51.440] Build 8863061884861866848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:23:51.440] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028491946802719216:51:0) after 1m0s
[02:24:51.457] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028491946802719216:51:0)
[02:24:51.457] Timer tick, asking Buildbucket for the build status
[02:24:52.095] Build 8863061884861866848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:24:52.095] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028491946802719216:52:0) after 1m0s
[02:25:52.177] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028491946802719216:52:0)
[02:25:52.177] Timer tick, asking Buildbucket for the build status
[02:25:52.328] Build 8863061884861866848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:25:52.329] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028491946802719216:53:0) after 1m0s
[02:26:52.325] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028491946802719216:53:0)
[02:26:52.326] Timer tick, asking Buildbucket for the build status
[02:26:52.434] Build 8863061884861866848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:26:52.435] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028491946802719216:54:0) after 1m0s
[02:27:52.459] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028491946802719216:54:0)
[02:27:52.459] Timer tick, asking Buildbucket for the build status
[02:27:52.565] Build 8863061884861866848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:27:52.565] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028491946802719216:55:0) after 1m0s
[02:28:52.606] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028491946802719216:55:0)
[02:28:52.606] Timer tick, asking Buildbucket for the build status
[02:28:53.565] Build 8863061884861866848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:28:53.565] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028491946802719216:56:0) after 1m0s
[02:29:53.746] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028491946802719216:56:0)
[02:29:53.746] Timer tick, asking Buildbucket for the build status
[02:29:53.867] Build 8863061884861866848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:29:53.867] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9028491946802719216:57:0) after 1m0s
[02:30:18.991] Received PubSub notification, asking Buildbucket for the build status
[02:30:19.121] Build 8863061884861866848: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[02:30:19.121] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1605925814242451",
"created_by": "project:chromium",
"created_ts": "1605922537896039",
"id": "8863061884861866848",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"8169705488b1606f6bd061f87b72771d13baf93a\"}}",
"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\": \"8169705488b1606f6bd061f87b72771d13baf93a\", \"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-116-wt5t\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-rel\", \"buildnumber\": 43038, \"got_angle_revision\": \"c4ca12e32e6aef9f5f8eb06c4b361264bec19f8d\", \"got_dawn_revision\": \"41b3f9c1e4aaaa3d56d205d81e61deaebdd40d75\", \"got_nacl_revision\": \"3ac0b060c794b64531b47dfded8ff8d951a5c648\", \"got_revision\": \"8169705488b1606f6bd061f87b72771d13baf93a\", \"got_revision_cp\": \"refs/heads/master@{#829914}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"6d612051c083238db89541be4fbb2d624a9baef4\", \"got_v8_revision\": \"8a682e08571d84f1d93ef73a07cfac7a089676c9\", \"got_v8_revision_cp\": \"refs/heads/8.9.28@{#1}\", \"got_webrtc_revision\": \"ce4be1e64050943f47d5944dc0dead3875615184\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32657}\", \"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\": \"8169705488b1606f6bd061f87b72771d13baf93a\", \"swarm_hashes_refs/heads/master(at){#829914}_without_patch\": {\"absl_hardening_tests\": \"1275f5b9dc7d3b2cf668af4b1e5dd0b0802fd348\", \"accessibility_unittests\": \"4d77e9db53312261b8c7e8b51a83561dcd79185e\", \"angle_unittests\": \"dbc2c64d2674601269f4f626feedcd21f48e585d\", \"app_list_unittests\": \"2ba45b1a908d06d1735c01fbad1d5306efb5b6ce\", \"app_shell_unittests\": \"21c8315e52f12efc15423cd0341a931f686d7dfd\", \"ash_unittests\": \"3124d5701cb9e9c71f79eff02a894408ab39ddb4\", \"aura_unittests\": \"459bcc17f40ad2287d0c6057efd4c8f0aa6c75e1\", \"base_unittests\": \"eca4275fe92d343934b801d2eb500df0caa70794\", \"base_util_unittests\": \"0639181a0c5a7521769fed74dfecbcef59efc9dc\", \"blink_common_unittests\": \"402c9a88a5d7a576c6dfe4858086254b44df9de6\", \"blink_fuzzer_unittests\": \"d5461ed4c20a8bd851e8220547c1fabac617db4a\", \"blink_heap_unittests\": \"1d331bebef37355044766cc1d7ec43b3757681d8\", \"blink_platform_unittests\": \"479d6d66c32560ff1f068ee25750834d19da283f\", \"blink_unittests\": \"7ae751ce4a3c2baf4c908ba7805d294f1f157b04\", \"boringssl_crypto_tests\": \"8fd5161edf0c75d2bbef10080e86312301e4a7a3\", \"boringssl_ssl_tests\": \"4f47fed6a41556e792b7cc407473d2c34df24c09\", \"browser_tests\": \"0bd793b4a7525de56b388050d24e3d573abc435e\", \"capture_unittests\": \"0ec232016208923f0b0e82d6837d2c219ebccd12\", \"cast_unittests\": \"0941f18b96038da8db9f2da14a1bef03fa91b3c9\", \"cc_unittests\": \"5a2e2f12936905d1ce3560b62beefcc55b2f20f2\", \"chrome_app_unittests\": \"656351789ccef5939d91730273be59b086ce75dc\", \"chromedriver_unittests\": \"d1a4b1bfca4caa392d2501467350bfcdd7992dd5\", \"chromeos_components_unittests\": \"bcb81da50b71fa20df9cdc54910505733bba0b14\", \"chromeos_unittests\": \"8cf684e2886d9da5bf0eff81182b07a7963d9024\", \"components_browsertests\": \"d5c4e2a3a83650a7d3fca06a5f5b239678f293c0\", \"components_unittests\": \"58f743a04b979e3e0cbb77b6bfd7eba59a21850c\", \"compositor_unittests\": \"a4625aa000d1b34a603580c91213959c367380a1\", \"content_browsertests\": \"e13e64872b1dde9e85ead7f41423f508ebb9dd4e\", \"content_unittests\": \"f244f8951d149a1e59552f7425c4b8667e6cd3a2\", \"crashpad_tests\": \"fc01b6dbf72c3bc891c0765a9629172e2636c93e\", \"crypto_unittests\": \"ffbea861ff40ca63557bf9e0dbdece399f089f71\", \"dbus_unittests\": \"b30fda846fb981fced9fe364e0f5741785b30239\", \"device_unittests\": \"d0c41a1c26e880965a89a2f44a74c718660ce590\", \"display_unittests\": \"4681535bf35173d98570bd7f23326466247107a1\", \"events_unittests\": \"48492910dc000dfcc8607689d84c966877dd0967\", \"exo_unittests\": \"f5b26bcdbfbd7cf4f964a91aba741e58ca2e732b\", \"extensions_browsertests\": \"d36a2cfd5fcb467d1c4e4704c3396c359875f130\", \"extensions_unittests\": \"e40dd97a340336c4cfeea29e1e122db4da10b4f2\", \"filesystem_service_unittests\": \"efcd9a97647a9416f196c764861d75c4c30bf656\", \"gcm_unit_tests\": \"c6b86559dfc93148764f7d9fa801c76f46020aa3\", \"gfx_unittests\": \"ffce5af75cdfc1ec05904a14cdf298c4ba2be938\", \"gin_unittests\": \"986364de1bfd9b2ff5f8bf92b2b1103addd203e2\", \"gl_unittests_ozone\": \"d40977a610498547981cc4b589f9789bd96c7345\", \"google_apis_unittests\": \"b6ef7857fad368a52409519ad5c78c70aa162b4d\", \"gpu_unittests\": \"2c117b5b485722f1ab894bb7e2b45b2d9621d292\", \"gwp_asan_unittests\": \"d65b1d2535acc5e12b11ac1f06edeaabbd0199d7\", \"interactive_ui_tests\": \"2f432c80f8db2b84371d46de6a1ce46d28a987c6\", \"ipc_tests\": \"8b87262f9970ed84970a72a764462bbc11f023f6\", \"jingle_unittests\": \"598d800fce0afea0004af258d9d3b5badc2216c0\", \"keyboard_unittests\": \"745121b0c3fee3063851aaee2d71e0f8c195501d\", \"latency_unittests\": \"26fa0db39c471498c5dd20b6ffd7e35f79592b1e\", \"libjingle_xmpp_unittests\": \"b5db31201688aef291126f97c63d9709e9f70629\", \"liburlpattern_unittests\": \"c4f95f31208321e19a0917611331c145f1753f84\", \"media_blink_unittests\": \"8b318fe31a5dc0e1d832536b778b5d3e552e14c5\", \"media_unittests\": \"dae8c18d77f7e12d02ca36d7c7b4c1c4bc2ef51f\", \"message_center_unittests\": \"022e3a0a1629256016994ffee2bc3ec045aa2ed5\", \"midi_unittests\": \"75adc5694b22900af5ede6584d24a2f60ba48b43\", \"mojo_core_unittests\": \"0213e1ede82a4c76754387df6291734a114302e9\", \"mojo_unittests\": \"84ab38b650250bebb3091028d38a00ca8541d28d\", \"nacl_helper_nonsfi_unittests\": \"0c529112e84422506f83264a7850ab1510f252a0\", \"nacl_loader_unittests\": \"993223b22b1cab58c3e164d12be6a7422b9d2483\", \"native_theme_unittests\": \"99d0b68f8cee1afa99f338f0678bab3ac3f7c78b\", \"net_unittests\": \"e9beaabd879f9c20ba56bcd56a852335e7f16102\", \"ozone_gl_unittests\": \"dcc4fe47ba72c18420e3b9d90dc3c29518c38beb\", \"ozone_unittests\": \"fd6f54fd638805e6fd56b3d3922eabe3c486a112\", \"ozone_x11_unittests\": \"7b0a29d72997bbe89816a5f29cb38dc98df97ebe\", \"pdf_unittests\": \"2444641e31c4054d3b3798c0d836c1c31fa5a836\", \"perfetto_unittests\": \"0e17c8f024261b8610ac61a4acb8aa0d6337da8f\", \"ppapi_unittests\": \"ccdb73df0f81ba854c10b250632037f47781ad96\", \"printing_unittests\": \"45fdd23e7365c1bc0a2d7f7463dd67abe3caec13\", \"remoting_unittests\": \"5fa7a30bea75b5e74c75dfaf07208cab661a2ba6\", \"sandbox_linux_unittests\": \"e6f29da3918a2b546731c585ec11a6845e2751de\", \"service_manager_unittests\": \"be563711c659a8faa8fa5b6d0a0c69f01df74541\", \"services_unittests\": \"ba20fe7a29d1286bccf29e024ebaa6d4e48e4f91\", \"shell_dialogs_unittests\": \"ba4df88ee8d6d71cf82dcd7658b91f0ab504a097\", \"skia_unittests\": \"49b36a4b860686e267004da0cfaec79d53244886\", \"snapshot_unittests\": \"f6f50214406316e899338378ab5bdfef11ec8927\", \"sql_unittests\": \"8dcd0679665edbd2df178372b0cb764bfc7b6ed0\", \"storage_unittests\": \"aaf52168a8a3ecd2054704a25aabead79666b4b8\", \"sync_integration_tests\": \"91188ea8bd3e19fc3a0b3c6ef323ea1513d57ab1\", \"traffic_annotation_auditor_unittests\": \"33f59bcc85789ab8b3d213943ed9b0a732656615\", \"ui_base_unittests\": \"0ddf48f602df966daaf3380e3bc50019a25069bf\", \"ui_chromeos_unittests\": \"8a9d78f7fef0ebf7ffe580942743761eaf02a5e2\", \"ui_touch_selection_unittests\": \"4180c580f60bc4af57484398037f637bab2978a8\", \"unit_tests\": \"01dc48ea0fccc7087962b116f543b03b25a8e628\", \"url_unittests\": \"2e5f5ef36b98cc3bc69d5dd9fbeff02d2c8b890d\", \"usage_time_limit_unittests\": \"571aaaafee5ce095a92207d1888b092ada91200c\", \"views_unittests\": \"d01a10fc077d2d92b8f1d20648647b4f6f346bdf\", \"viz_unittests\": \"040d375090410a21328d137c3f36ccb25bd27f15\", \"wayland_client_perftests\": \"602a6558d6a9b74d9861e550b561187daa17afe9\", \"wm_unittests\": \"f1fd386fb82effe35f0100d284e52f9950a0335d\", \"wtf_unittests\": \"03380dcb65b6b7b20da4a95c8b1ce291a92e8f76\", \"zlib_unittests\": \"012c3225ce92d10544c0d497e95c4b5ee6655e43\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_0fb1f456593ae2600bf9c9616e0e5dcc3c87ffc9d4d6fb93e9350aac8ae4516c_v2\", \"builder_6cd5ded23cefc640ae511b7a529237cb2561e39c1afb96e401c63016ddcf35a6_v2\", \"builder_70f3607e3f7d84b5c3698c0eed74813ee5c700477d5aec13835ae2ecc5ee2d5b_v2\", \"builder_7b27d20986eac2bbcc1513665f8799c69dbe398236c21423fd80a70475184e3c_v2\", \"builder_94fa3119a28f4651cdd4fc5b981a64c69b26c151212072333439362290628993_v2\", \"builder_9efb94071a208cb32f222f9589b2bf8c6e9d4ebc7884b1b49bd19cd2b21dc48e_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-116-wt5t\"], \"image\": [\"chrome-xenial-20110800-08efb743065\"], \"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\": [\"5637-4d8b832\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-d\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1605922641022673",
"status": "COMPLETED",
"status_changed_ts": "1605925817079308",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/43038",
"builder:linux-chromeos-rel",
"buildset:commit/git/8169705488b1606f6bd061f87b72771d13baf93a",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/8169705488b1606f6bd061f87b72771d13baf93a",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9028491946802719216",
"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/8863061884861866848/+/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:500142dcf3499910",
"user_agent:luci-scheduler"
],
"updated_ts": "1605925817125687",
"url": "https://ci.chromium.org/b/8863061884861866848",
"utcnow_ts": "1605925819107250"
}
}
[02:30:19.121] Invocation finished in 54m42.460661493s with status SUCCEEDED