[01:47:57.168] New invocation is queued and will start shortly
[01:47:58.472] Starting the invocation (attempt 1)
[01:47:58.508] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[01:47:58.508] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[01:47:58.508] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9022602367524416496",
"parameters_json": "{\"builder_name\":\"linux-chromeos-rel\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"658f1dc51eba25d3edbd6910ac7540e67786e2fb\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-rel",
"scheduler_invocation_id:9022602367524416496",
"scheduler_job_id:chromium/linux-chromeos-rel",
"user_agent:luci-scheduler",
"buildset:commit/git/658f1dc51eba25d3edbd6910ac7540e67786e2fb",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/658f1dc51eba25d3edbd6910ac7540e67786e2fb",
"gitiles_ref:refs/heads/master"
]
}
[01:47:59.360] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1611539278587821",
"id": "8857172305375003760",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"658f1dc51eba25d3edbd6910ac7540e67786e2fb\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1611539279028814",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/44867",
"builder:linux-chromeos-rel",
"buildset:commit/git/658f1dc51eba25d3edbd6910ac7540e67786e2fb",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/658f1dc51eba25d3edbd6910ac7540e67786e2fb",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022602367524416496",
"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/8857172305375003760/+/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": "1611539279028933",
"url": "https://ci.chromium.org/b/8857172305375003760",
"utcnow_ts": "1611539279350728"
}
}
[01:47:59.360] Task URL: https://ci.chromium.org/b/8857172305375003760
[01:47:59.360] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022602367524416496:2:0) after 1m0s
[01:48:31.357] Received PubSub notification, asking Buildbucket for the build status
[01:48:31.520] Build 8857172305375003760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:48:59.367] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022602367524416496:2:0)
[01:48:59.367] Timer tick, asking Buildbucket for the build status
[01:48:59.524] Build 8857172305375003760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:48:59.524] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022602367524416496:4:0) after 1m0s
[01:49:59.611] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022602367524416496:4:0)
[01:49:59.611] Timer tick, asking Buildbucket for the build status
[01:49:59.701] Build 8857172305375003760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:49:59.701] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022602367524416496:5:0) after 1m0s
[01:50:59.766] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022602367524416496:5:0)
[01:50:59.766] Timer tick, asking Buildbucket for the build status
[01:50:59.850] Build 8857172305375003760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:50:59.850] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022602367524416496:6:0) after 1m0s
[01:51:59.945] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022602367524416496:6:0)
[01:51:59.945] Timer tick, asking Buildbucket for the build status
[01:51:59.999] Build 8857172305375003760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:51:59.999] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022602367524416496:7:0) after 1m0s
[01:53:00.120] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022602367524416496:7:0)
[01:53:00.120] Timer tick, asking Buildbucket for the build status
[01:53:00.336] Build 8857172305375003760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:53:00.336] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022602367524416496:8:0) after 1m0s
[01:54:00.418] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022602367524416496:8:0)
[01:54:00.418] Timer tick, asking Buildbucket for the build status
[01:54:00.531] Build 8857172305375003760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:54:00.531] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022602367524416496:9:0) after 1m0s
[01:55:01.243] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022602367524416496:9:0)
[01:55:01.243] Timer tick, asking Buildbucket for the build status
[01:55:01.371] Build 8857172305375003760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:55:01.371] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022602367524416496:10:0) after 1m0s
[01:56:01.673] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022602367524416496:10:0)
[01:56:01.673] Timer tick, asking Buildbucket for the build status
[01:56:01.762] Build 8857172305375003760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:56:01.762] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022602367524416496:11:0) after 1m0s
[01:57:01.886] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022602367524416496:11:0)
[01:57:01.886] Timer tick, asking Buildbucket for the build status
[01:57:01.987] Build 8857172305375003760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:57:01.987] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022602367524416496:12:0) after 1m0s
[01:58:02.463] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022602367524416496:12:0)
[01:58:02.463] Timer tick, asking Buildbucket for the build status
[01:58:02.567] Build 8857172305375003760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:58:02.567] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022602367524416496:13:0) after 1m0s
[01:59:02.691] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022602367524416496:13:0)
[01:59:02.691] Timer tick, asking Buildbucket for the build status
[01:59:02.831] Build 8857172305375003760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:59:02.831] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022602367524416496:14:0) after 1m0s
[02:00:02.942] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022602367524416496:14:0)
[02:00:02.942] Timer tick, asking Buildbucket for the build status
[02:00:03.006] Build 8857172305375003760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:00:03.006] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022602367524416496:15:0) after 1m0s
[02:01:03.066] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022602367524416496:15:0)
[02:01:03.066] Timer tick, asking Buildbucket for the build status
[02:01:03.343] Build 8857172305375003760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:01:03.343] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022602367524416496:16:0) after 1m0s
[02:02:03.388] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022602367524416496:16:0)
[02:02:03.388] Timer tick, asking Buildbucket for the build status
[02:02:03.482] Build 8857172305375003760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:02:03.482] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022602367524416496:17:0) after 1m0s
[02:03:03.551] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022602367524416496:17:0)
[02:03:03.551] Timer tick, asking Buildbucket for the build status
[02:03:04.078] Build 8857172305375003760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:03:04.078] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022602367524416496:18:0) after 1m0s
[02:04:04.191] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022602367524416496:18:0)
[02:04:04.191] Timer tick, asking Buildbucket for the build status
[02:04:04.271] Build 8857172305375003760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:04:04.271] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022602367524416496:19:0) after 1m0s
[02:05:04.287] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022602367524416496:19:0)
[02:05:04.287] Timer tick, asking Buildbucket for the build status
[02:05:04.509] Build 8857172305375003760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:05:04.509] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022602367524416496:20:0) after 1m0s
[02:06:04.597] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022602367524416496:20:0)
[02:06:04.597] Timer tick, asking Buildbucket for the build status
[02:06:04.696] Build 8857172305375003760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:06:04.696] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022602367524416496:21:0) after 1m0s
[02:07:04.703] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022602367524416496:21:0)
[02:07:04.703] Timer tick, asking Buildbucket for the build status
[02:07:05.736] Build 8857172305375003760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:07:05.736] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022602367524416496:22:0) after 1m0s
[02:08:05.776] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022602367524416496:22:0)
[02:08:05.776] Timer tick, asking Buildbucket for the build status
[02:08:06.342] Build 8857172305375003760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:08:06.342] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022602367524416496:23:0) after 1m0s
[02:09:06.431] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022602367524416496:23:0)
[02:09:06.431] Timer tick, asking Buildbucket for the build status
[02:09:06.574] Build 8857172305375003760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:09:06.575] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022602367524416496:24:0) after 1m0s
[02:10:06.738] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022602367524416496:24:0)
[02:10:06.738] Timer tick, asking Buildbucket for the build status
[02:10:06.826] Build 8857172305375003760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:10:06.826] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022602367524416496:25:0) after 1m0s
[02:11:06.975] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022602367524416496:25:0)
[02:11:06.975] Timer tick, asking Buildbucket for the build status
[02:11:07.047] Build 8857172305375003760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:11:07.047] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022602367524416496:26:0) after 1m0s
[02:12:07.095] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022602367524416496:26:0)
[02:12:07.095] Timer tick, asking Buildbucket for the build status
[02:12:07.177] Build 8857172305375003760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:12:07.177] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022602367524416496:27:0) after 1m0s
[02:13:07.297] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022602367524416496:27:0)
[02:13:07.297] Timer tick, asking Buildbucket for the build status
[02:13:07.515] Build 8857172305375003760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:13:07.515] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022602367524416496:28:0) after 1m0s
[02:14:07.676] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022602367524416496:28:0)
[02:14:07.676] Timer tick, asking Buildbucket for the build status
[02:14:07.742] Build 8857172305375003760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:14:07.742] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022602367524416496:29:0) after 1m0s
[02:15:07.712] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022602367524416496:29:0)
[02:15:07.712] Timer tick, asking Buildbucket for the build status
[02:15:07.970] Build 8857172305375003760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:15:07.970] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022602367524416496:30:0) after 1m0s
[02:16:08.065] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022602367524416496:30:0)
[02:16:08.065] Timer tick, asking Buildbucket for the build status
[02:16:08.155] Build 8857172305375003760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:16:08.156] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022602367524416496:31:0) after 1m0s
[02:17:08.181] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022602367524416496:31:0)
[02:17:08.181] Timer tick, asking Buildbucket for the build status
[02:17:08.398] Build 8857172305375003760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:17:08.399] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022602367524416496:32:0) after 1m0s
[02:18:08.417] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022602367524416496:32:0)
[02:18:08.417] Timer tick, asking Buildbucket for the build status
[02:18:08.524] Build 8857172305375003760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:18:08.524] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022602367524416496:33:0) after 1m0s
[02:19:08.570] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022602367524416496:33:0)
[02:19:08.570] Timer tick, asking Buildbucket for the build status
[02:19:08.627] Build 8857172305375003760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:19:08.627] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022602367524416496:34:0) after 1m0s
[02:20:08.651] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022602367524416496:34:0)
[02:20:08.651] Timer tick, asking Buildbucket for the build status
[02:20:08.752] Build 8857172305375003760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:20:08.752] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022602367524416496:35:0) after 1m0s
[02:21:08.755] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022602367524416496:35:0)
[02:21:08.755] Timer tick, asking Buildbucket for the build status
[02:21:08.852] Build 8857172305375003760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:21:08.852] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022602367524416496:36:0) after 1m0s
[02:22:08.873] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022602367524416496:36:0)
[02:22:08.873] Timer tick, asking Buildbucket for the build status
[02:22:08.939] Build 8857172305375003760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:22:08.939] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022602367524416496:37:0) after 1m0s
[02:23:08.997] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022602367524416496:37:0)
[02:23:08.997] Timer tick, asking Buildbucket for the build status
[02:23:09.454] Build 8857172305375003760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:23:09.454] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022602367524416496:38:0) after 1m0s
[02:24:09.474] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022602367524416496:38:0)
[02:24:09.474] Timer tick, asking Buildbucket for the build status
[02:24:09.538] Build 8857172305375003760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:24:09.538] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022602367524416496:39:0) after 1m0s
[02:25:09.594] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022602367524416496:39:0)
[02:25:09.594] Timer tick, asking Buildbucket for the build status
[02:25:09.647] Build 8857172305375003760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:25:09.647] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022602367524416496:40:0) after 1m0s
[02:26:09.926] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022602367524416496:40:0)
[02:26:09.926] Timer tick, asking Buildbucket for the build status
[02:26:10.058] Build 8857172305375003760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:26:10.058] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022602367524416496:41:0) after 1m0s
[02:27:10.191] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022602367524416496:41:0)
[02:27:10.191] Timer tick, asking Buildbucket for the build status
[02:27:10.348] Build 8857172305375003760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:27:10.348] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022602367524416496:42:0) after 1m0s
[02:28:10.430] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022602367524416496:42:0)
[02:28:10.430] Timer tick, asking Buildbucket for the build status
[02:28:10.556] Build 8857172305375003760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:28:10.556] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022602367524416496:43:0) after 1m0s
[02:29:10.798] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022602367524416496:43:0)
[02:29:10.798] Timer tick, asking Buildbucket for the build status
[02:29:10.925] Build 8857172305375003760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:29:10.925] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022602367524416496:44:0) after 1m0s
[02:30:11.084] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022602367524416496:44:0)
[02:30:11.084] Timer tick, asking Buildbucket for the build status
[02:30:11.189] Build 8857172305375003760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:30:11.189] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022602367524416496:45:0) after 1m0s
[02:31:11.358] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022602367524416496:45:0)
[02:31:11.358] Timer tick, asking Buildbucket for the build status
[02:31:11.603] Build 8857172305375003760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:31:11.603] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022602367524416496:46:0) after 1m0s
[02:32:11.803] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022602367524416496:46:0)
[02:32:11.803] Timer tick, asking Buildbucket for the build status
[02:32:11.934] Build 8857172305375003760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:32:11.934] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022602367524416496:47:0) after 1m0s
[02:33:12.056] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022602367524416496:47:0)
[02:33:12.056] Timer tick, asking Buildbucket for the build status
[02:33:12.285] Build 8857172305375003760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:33:12.285] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022602367524416496:48:0) after 1m0s
[02:34:12.309] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022602367524416496:48:0)
[02:34:12.309] Timer tick, asking Buildbucket for the build status
[02:34:12.658] Build 8857172305375003760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:34:12.658] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022602367524416496:49:0) after 1m0s
[02:35:12.711] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022602367524416496:49:0)
[02:35:12.711] Timer tick, asking Buildbucket for the build status
[02:35:13.027] Build 8857172305375003760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:35:13.027] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022602367524416496:50:0) after 1m0s
[02:36:13.115] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022602367524416496:50:0)
[02:36:13.115] Timer tick, asking Buildbucket for the build status
[02:36:13.266] Build 8857172305375003760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:36:13.266] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022602367524416496:51:0) after 1m0s
[02:37:13.405] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022602367524416496:51:0)
[02:37:13.405] Timer tick, asking Buildbucket for the build status
[02:37:13.578] Build 8857172305375003760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:37:13.578] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022602367524416496:52:0) after 1m0s
[02:38:13.757] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022602367524416496:52:0)
[02:38:13.757] Timer tick, asking Buildbucket for the build status
[02:38:13.914] Build 8857172305375003760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:38:13.914] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022602367524416496:53:0) after 1m0s
[02:39:14.181] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022602367524416496:53:0)
[02:39:14.181] Timer tick, asking Buildbucket for the build status
[02:39:14.262] Build 8857172305375003760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:39:14.262] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022602367524416496:54:0) after 1m0s
[02:40:14.440] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022602367524416496:54:0)
[02:40:14.440] Timer tick, asking Buildbucket for the build status
[02:40:14.829] Build 8857172305375003760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:40:14.829] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9022602367524416496:55:0) after 1m0s
[02:41:04.086] Received PubSub notification, asking Buildbucket for the build status
[02:41:04.183] Build 8857172305375003760: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[02:41:04.183] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1611542462759991",
"created_by": "project:chromium",
"created_ts": "1611539278587821",
"id": "8857172305375003760",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"658f1dc51eba25d3edbd6910ac7540e67786e2fb\"}}",
"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\": \"658f1dc51eba25d3edbd6910ac7540e67786e2fb\", \"project\": \"chromium/src\"}], \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-chromium-ci-xenial-us-central1-b-8-207-xtuo\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-rel\", \"buildnumber\": 44867, \"got_angle_revision\": \"3c3b82abcf0a5c18b4d97ae49726045ce6d3b832\", \"got_dawn_revision\": \"f1f8394de04ea9532de763dfb2f968fc9610683b\", \"got_nacl_revision\": \"cb31feffa500401c95c6c44d88b7c358236bca36\", \"got_revision\": \"658f1dc51eba25d3edbd6910ac7540e67786e2fb\", \"got_revision_cp\": \"refs/heads/master@{#846616}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"413f7cb17c77c95e4cc3ba141f3c0f049ac2ecb2\", \"got_v8_revision\": \"f2b2f28304b332e59226ee6c0688307ed9982884\", \"got_v8_revision_cp\": \"refs/heads/9.0.57@{#1}\", \"got_webrtc_revision\": \"90776cb0ef72d437983c8d29e5a7f2ac264c107d\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33064}\", \"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\": \"658f1dc51eba25d3edbd6910ac7540e67786e2fb\", \"swarm_hashes_refs/heads/master(at){#846616}_without_patch\": {\"absl_hardening_tests\": \"dc8b38ebc42465c367c58e6d3e8de1108c0485ea\", \"accessibility_unittests\": \"b9ad57a3c4bc93a08fbb911db162846d78b75ff3\", \"angle_unittests\": \"150b62cd2bb404a34f8a3847addd087549785132\", \"app_list_unittests\": \"c44c5bb080d135853190814107412ef19ce34040\", \"app_shell_unittests\": \"12f47e4400386554ecc50b8909b74022bd86c264\", \"ash_unittests\": \"65a40fad57ee3c4c25b4914554dcedbacbfe5de9\", \"aura_unittests\": \"ba922def537c790d99557ef7019c87540f47e66b\", \"base_unittests\": \"ad61d12b62b807ed754ac21718f539363dec8040\", \"base_util_unittests\": \"1711cec61f972988ee4a3bc0a4fa5ba8f3a6254f\", \"blink_common_unittests\": \"3406eb424daffffaa67443d2cf99ebc5dcfb0edb\", \"blink_fuzzer_unittests\": \"f68755bed112b02132d5c4cd90ebc5a2cb30fbcf\", \"blink_heap_unittests\": \"8f4a273d3e36200c8de2794845aa9955b2ebf966\", \"blink_platform_unittests\": \"c5fb28a5d37a601cd7678dc4d3745c1543864dfc\", \"blink_unittests\": \"a992c5d13937db4171718074a5cc1b3b8b389cdc\", \"boringssl_crypto_tests\": \"abb21a9d81ef53bf0aa894159ec6c993d37d86c5\", \"boringssl_ssl_tests\": \"893eaaf4e8dbe8dc13ffca109d8c377487c21d4b\", \"browser_tests\": \"e6e80c1293acb5d3e8a805fbc310d509034e8d1d\", \"capture_unittests\": \"b6689aedbc6aa71af33578b15c3e035c1b5ecb12\", \"cast_unittests\": \"7e076a49e77cc659e00b077daf3697c9379080bd\", \"cc_unittests\": \"0db8dee86439c34b4e19d2ad2e758a03a72e0c64\", \"chrome_app_unittests\": \"c764408a4dd4924b8d6f3bc00f4b655107aab72b\", \"chromedriver_unittests\": \"16b1108365ebc62498c9abd6235574a369032a6d\", \"chromeos_components_unittests\": \"554d035d191c3db6803feafc7b0f5a6482c36770\", \"chromeos_unittests\": \"801a0190213d6a936df6b73d7db710f7acc27623\", \"components_browsertests\": \"99d8a528f501a4655124bfa2ff4ff237e3da9433\", \"components_unittests\": \"f3d0180517bf6ad2804b9f497b5ae8eb3d94c93f\", \"compositor_unittests\": \"48fbea81ef5fd110456b8cf5adbc18be8fbb3eda\", \"content_browsertests\": \"82a6fa6afaf3f7937f9799df27360fb0d9f00afa\", \"content_unittests\": \"b5a5a1ffec27ee9ebfaefe1dfc1458117b5153cd\", \"crashpad_tests\": \"9b0ec78c8de3edbf9854f1759e7d6f0aa43016e2\", \"crypto_unittests\": \"d1924de36263a039e18e935829cd5e9b67652807\", \"dbus_unittests\": \"dad2c97c0f50008c7bfcc704b4a207e35b5ad83e\", \"device_unittests\": \"0a88452b5346f6b468faf7a0c07edd31b409a1ef\", \"display_unittests\": \"3a65532af0783629e2caa2588c57d7ca1506052f\", \"events_unittests\": \"d1df346bc136fe0c0f586a5ed14b341bf58f8879\", \"exo_unittests\": \"7f781b603d39d193cb17fed8dc305addc9676339\", \"extensions_browsertests\": \"1eda89f92635087d2491978abe04f1a4e185558a\", \"extensions_unittests\": \"e04bb6f5856f847f4bcc9b739036650c007e9972\", \"filesystem_service_unittests\": \"f3645f858beab7608ca334ff15b113cf9e9b42e8\", \"gcm_unit_tests\": \"c1437ab002075bf14882ab3c4518b86f9c4c800c\", \"gfx_unittests\": \"3dc3f01e8a6ee667299d5cd502c54c7ee16772e1\", \"gin_unittests\": \"d2e5afbe6176c203345d4ca09d431e5b91d3baba\", \"gl_unittests_ozone\": \"70cd95422ebfabc6ec83a7405d965deb214764a7\", \"google_apis_unittests\": \"51d3b25c6a004acb28342bec7070e11843e7252d\", \"gpu_unittests\": \"4c81b2c87b99e9d57f7178c9ced402942e18b912\", \"gwp_asan_unittests\": \"f1be1054495054629e5b1666c62451b9efd14910\", \"interactive_ui_tests\": \"523e97598a59185c895771c45b23dea3168b5140\", \"ipc_tests\": \"17177319d58a31df42cfe2c44ed8a99a3bdc4b56\", \"jingle_unittests\": \"c6a702f1a6fc0da5a00d87a9bbe2ceebf9847d97\", \"keyboard_unittests\": \"1241329e3abfe4f79633a134d8df84d674c17867\", \"latency_unittests\": \"344bc5028fa0ffbb6374b17540575f3c464b6b3d\", \"libjingle_xmpp_unittests\": \"f92be00472da8c5bb4dcd6e8b46e7a9124ad8c30\", \"liburlpattern_unittests\": \"ebbcdd83367f4cc4f1ee0c8590d0f132f678d58a\", \"media_blink_unittests\": \"6f9152754bd77a6cbb49b94c1262caac83ce6655\", \"media_unittests\": \"b41b8df55f45e277abee8deb4163c539a066cc28\", \"message_center_unittests\": \"49be46948ac72efd0e16026850a455a3f47f4a87\", \"midi_unittests\": \"00c270958fb03755e27064786c3f2f65cd82ee1e\", \"mojo_core_unittests\": \"072efe9d4d0051115acbb7e59472dd8bac1422f2\", \"mojo_unittests\": \"3447a6522ea38b0682a25ab481583401e3ee64b4\", \"nacl_helper_nonsfi_unittests\": \"1c91a45e57519f253e3e0eaa82fc0a196a74723f\", \"nacl_loader_unittests\": \"421ce6be46972ea4e62b6e2a39381962b49c086d\", \"native_theme_unittests\": \"91000e586dee6cef31f7fa4018b576247b52ab78\", \"net_unittests\": \"6c1514a870fe08884ead4e8500197b6f4daa9e0d\", \"ozone_gl_unittests\": \"556ab1adecf2e20886323ff182e02e413244f090\", \"ozone_unittests\": \"be28c19d450a0520709b95bfd33581a235199222\", \"ozone_x11_unittests\": \"137ea0135eb53b2a8105a33d5f9a055a8b18b87c\", \"pdf_unittests\": \"d20e1ce73a737ccf84b65ed5e34461cafc746a2f\", \"perfetto_unittests\": \"afb4b605b6316af17fe3f54831f593c5b49f6a52\", \"ppapi_unittests\": \"e911b2a219a1a73e7f40f76f0deab172f3ad341d\", \"printing_unittests\": \"3313af5f7a5de2de2c58b6620086b09c93235af3\", \"remoting_unittests\": \"0b66856a5c22925197900d9a53bcc427fd5cbec3\", \"sandbox_linux_unittests\": \"c753f31b215c943850c9af3838cacfb82cc24135\", \"service_manager_unittests\": \"7c7b4aa12d84dfd12806df3777ba3f7318f8085f\", \"services_unittests\": \"38ce5fbf87d10ef2951e18f11ef5f772b071cdb2\", \"shell_dialogs_unittests\": \"4a0ead8303b89ef018908fe0111ef31266ea0bd4\", \"shell_encryption_unittests\": \"5c6e7d145dcaf0909beb772831a165eb19f4301c\", \"skia_unittests\": \"d2062b8af815f2470e69d5b1ddff13fc4c10d2f1\", \"snapshot_unittests\": \"099aff37abc613457f2cb48d4a6564e86b359b7b\", \"sql_unittests\": \"51b5c2ab9ad5fa676ca57ffc7a6fdea2b6eda0b7\", \"storage_unittests\": \"0c94469fb7b12cd171f3c4c2401f9dc396cebe90\", \"sync_integration_tests\": \"0ac82a57d091ded62df1e9e7c1e0e98393e37e37\", \"traffic_annotation_auditor_unittests\": \"880b58cca83668ee7dcf246a408f488fe0b62326\", \"ui_base_unittests\": \"36139f1bfcdf517c5610a83b6a82efad5ec2274f\", \"ui_chromeos_unittests\": \"56e93f404a5ac1234872d19e22b4769d7ae7319d\", \"ui_touch_selection_unittests\": \"2ea6568f28a1c18cf8b09b6b0d139f5ea64e52c1\", \"unit_tests\": \"a256fbd5d3798b0d76b4ff891807c279197e1272\", \"url_unittests\": \"548723fc2aa32fe271c926ecca3d206a846c84cc\", \"usage_time_limit_unittests\": \"1d811a801c90e4bedbc5d6da943659ef3113f8a0\", \"views_unittests\": \"889205b535519537a3c90e4af6e868933264dd58\", \"viz_unittests\": \"6f0e0fcb258b71695fd45dbcbe25b2d2f29cdd7b\", \"wayland_client_perftests\": \"81159e2a75a07781ec3d9a629ee32bfae4a824de\", \"wm_unittests\": \"da146fb822397d39631f4ed2f99a7c8dd7c57dbc\", \"wtf_unittests\": \"5847b5a1f333bd7c6d1d84ded9f872d27708ba1d\", \"zlib_unittests\": \"f294d5f47ce4afba382588c892ea0fa6666a16e0\"}}, \"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-central1-b-8-207-xtuo\"], \"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\": [\"5671-1d9dea8\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1611539310536214",
"status": "COMPLETED",
"status_changed_ts": "1611542463137195",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/44867",
"builder:linux-chromeos-rel",
"buildset:commit/git/658f1dc51eba25d3edbd6910ac7540e67786e2fb",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/658f1dc51eba25d3edbd6910ac7540e67786e2fb",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022602367524416496",
"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/8857172305375003760/+/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:51500b76e63e6410",
"user_agent:luci-scheduler"
],
"updated_ts": "1611542463142061",
"url": "https://ci.chromium.org/b/8857172305375003760",
"utcnow_ts": "1611542464175172"
}
}
[02:41:04.183] Invocation finished in 53m7.030300531s with status SUCCEEDED