[22:27:36.371] New invocation is queued and will start shortly
[22:27:37.589] Starting the invocation (attempt 1)
[22:27:37.636] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[22:27:37.636] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[22:27:37.636] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9023792732816339136",
"parameters_json": "{\"builder_name\":\"linux-chromeos-rel\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"4450fe11edfaa4308a2c0fec0c8bedc963ed4649\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-rel",
"scheduler_invocation_id:9023792732816339136",
"scheduler_job_id:chromium/linux-chromeos-rel",
"user_agent:luci-scheduler",
"buildset:commit/git/4450fe11edfaa4308a2c0fec0c8bedc963ed4649",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4450fe11edfaa4308a2c0fec0c8bedc963ed4649",
"gitiles_ref:refs/heads/master"
]
}
[22:27:38.506] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1610404057816657",
"id": "8858362670629860672",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"4450fe11edfaa4308a2c0fec0c8bedc963ed4649\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1610404058324284",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/44517",
"builder:linux-chromeos-rel",
"buildset:commit/git/4450fe11edfaa4308a2c0fec0c8bedc963ed4649",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4450fe11edfaa4308a2c0fec0c8bedc963ed4649",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023792732816339136",
"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/8858362670629860672/+/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": "1610404058324540",
"url": "https://ci.chromium.org/b/8858362670629860672",
"utcnow_ts": "1610404058464934"
}
}
[22:27:38.506] Task URL: https://ci.chromium.org/b/8858362670629860672
[22:27:38.506] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023792732816339136:2:0) after 1m0s
[22:27:40.060] Received PubSub notification, asking Buildbucket for the build status
[22:27:40.189] Build 8858362670629860672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:28:38.558] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023792732816339136:2:0)
[22:28:38.558] Timer tick, asking Buildbucket for the build status
[22:28:38.687] Build 8858362670629860672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:28:38.687] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023792732816339136:4:0) after 1m0s
[22:29:38.706] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023792732816339136:4:0)
[22:29:38.706] Timer tick, asking Buildbucket for the build status
[22:29:38.927] Build 8858362670629860672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:29:38.927] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023792732816339136:5:0) after 1m0s
[22:30:38.962] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023792732816339136:5:0)
[22:30:38.962] Timer tick, asking Buildbucket for the build status
[22:30:39.155] Build 8858362670629860672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:30:39.155] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023792732816339136:6:0) after 1m0s
[22:31:39.174] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023792732816339136:6:0)
[22:31:39.174] Timer tick, asking Buildbucket for the build status
[22:31:39.321] Build 8858362670629860672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:31:39.321] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023792732816339136:7:0) after 1m0s
[22:32:39.341] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023792732816339136:7:0)
[22:32:39.341] Timer tick, asking Buildbucket for the build status
[22:32:39.591] Build 8858362670629860672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:32:39.591] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023792732816339136:8:0) after 1m0s
[22:33:40.204] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023792732816339136:8:0)
[22:33:40.204] Timer tick, asking Buildbucket for the build status
[22:33:40.374] Build 8858362670629860672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:33:40.374] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023792732816339136:9:0) after 1m0s
[22:34:40.563] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023792732816339136:9:0)
[22:34:40.563] Timer tick, asking Buildbucket for the build status
[22:34:41.165] Build 8858362670629860672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:34:41.165] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023792732816339136:10:0) after 1m0s
[22:35:41.401] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023792732816339136:10:0)
[22:35:41.401] Timer tick, asking Buildbucket for the build status
[22:35:41.501] Build 8858362670629860672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:35:41.501] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023792732816339136:11:0) after 1m0s
[22:36:41.614] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023792732816339136:11:0)
[22:36:41.614] Timer tick, asking Buildbucket for the build status
[22:36:41.750] Build 8858362670629860672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:36:41.750] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023792732816339136:12:0) after 1m0s
[22:37:41.763] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023792732816339136:12:0)
[22:37:41.763] Timer tick, asking Buildbucket for the build status
[22:37:41.966] Build 8858362670629860672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:37:41.966] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023792732816339136:13:0) after 1m0s
[22:38:42.032] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023792732816339136:13:0)
[22:38:42.032] Timer tick, asking Buildbucket for the build status
[22:38:42.153] Build 8858362670629860672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:38:42.153] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023792732816339136:14:0) after 1m0s
[22:39:42.229] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023792732816339136:14:0)
[22:39:42.229] Timer tick, asking Buildbucket for the build status
[22:39:42.337] Build 8858362670629860672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:39:42.337] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023792732816339136:15:0) after 1m0s
[22:40:42.412] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023792732816339136:15:0)
[22:40:42.412] Timer tick, asking Buildbucket for the build status
[22:40:42.509] Build 8858362670629860672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:40:42.509] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023792732816339136:16:0) after 1m0s
[22:41:42.608] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023792732816339136:16:0)
[22:41:42.608] Timer tick, asking Buildbucket for the build status
[22:41:42.755] Build 8858362670629860672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:41:42.755] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023792732816339136:17:0) after 1m0s
[22:42:42.785] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023792732816339136:17:0)
[22:42:42.785] Timer tick, asking Buildbucket for the build status
[22:42:43.123] Build 8858362670629860672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:42:43.123] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023792732816339136:18:0) after 1m0s
[22:43:43.170] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023792732816339136:18:0)
[22:43:43.170] Timer tick, asking Buildbucket for the build status
[22:43:43.353] Build 8858362670629860672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:43:43.353] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023792732816339136:19:0) after 1m0s
[22:44:43.405] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023792732816339136:19:0)
[22:44:43.405] Timer tick, asking Buildbucket for the build status
[22:44:43.551] Build 8858362670629860672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:44:43.551] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023792732816339136:20:0) after 1m0s
[22:45:43.611] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023792732816339136:20:0)
[22:45:43.611] Timer tick, asking Buildbucket for the build status
[22:45:43.683] Build 8858362670629860672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:45:43.683] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023792732816339136:21:0) after 1m0s
[22:46:43.753] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023792732816339136:21:0)
[22:46:43.753] Timer tick, asking Buildbucket for the build status
[22:46:43.856] Build 8858362670629860672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:46:43.856] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023792732816339136:22:0) after 1m0s
[22:47:43.962] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023792732816339136:22:0)
[22:47:43.962] Timer tick, asking Buildbucket for the build status
[22:47:44.237] Build 8858362670629860672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:47:44.237] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023792732816339136:23:0) after 1m0s
[22:48:44.313] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023792732816339136:23:0)
[22:48:44.313] Timer tick, asking Buildbucket for the build status
[22:48:44.488] Build 8858362670629860672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:48:44.488] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023792732816339136:24:0) after 1m0s
[22:49:44.589] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023792732816339136:24:0)
[22:49:44.589] Timer tick, asking Buildbucket for the build status
[22:49:44.755] Build 8858362670629860672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:49:44.755] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023792732816339136:25:0) after 1m0s
[22:50:44.945] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023792732816339136:25:0)
[22:50:44.945] Timer tick, asking Buildbucket for the build status
[22:50:45.016] Build 8858362670629860672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:50:45.016] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023792732816339136:26:0) after 1m0s
[22:51:45.034] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023792732816339136:26:0)
[22:51:45.034] Timer tick, asking Buildbucket for the build status
[22:51:45.238] Build 8858362670629860672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:51:45.239] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023792732816339136:27:0) after 1m0s
[22:52:45.362] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023792732816339136:27:0)
[22:52:45.362] Timer tick, asking Buildbucket for the build status
[22:52:45.481] Build 8858362670629860672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:52:45.481] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023792732816339136:28:0) after 1m0s
[22:53:45.568] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023792732816339136:28:0)
[22:53:45.568] Timer tick, asking Buildbucket for the build status
[22:53:45.758] Build 8858362670629860672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:53:45.758] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023792732816339136:29:0) after 1m0s
[22:54:45.862] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023792732816339136:29:0)
[22:54:45.862] Timer tick, asking Buildbucket for the build status
[22:54:45.980] Build 8858362670629860672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:54:45.980] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023792732816339136:30:0) after 1m0s
[22:55:46.095] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023792732816339136:30:0)
[22:55:46.095] Timer tick, asking Buildbucket for the build status
[22:55:46.184] Build 8858362670629860672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:55:46.184] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023792732816339136:31:0) after 1m0s
[22:56:46.193] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023792732816339136:31:0)
[22:56:46.193] Timer tick, asking Buildbucket for the build status
[22:56:46.448] Build 8858362670629860672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:56:46.448] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023792732816339136:32:0) after 1m0s
[22:57:46.496] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023792732816339136:32:0)
[22:57:46.496] Timer tick, asking Buildbucket for the build status
[22:57:46.708] Build 8858362670629860672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:57:46.708] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023792732816339136:33:0) after 1m0s
[22:58:46.734] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023792732816339136:33:0)
[22:58:46.734] Timer tick, asking Buildbucket for the build status
[22:58:46.838] Build 8858362670629860672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:58:46.838] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023792732816339136:34:0) after 1m0s
[22:59:46.815] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023792732816339136:34:0)
[22:59:46.815] Timer tick, asking Buildbucket for the build status
[22:59:47.034] Build 8858362670629860672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:59:47.034] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023792732816339136:35:0) after 1m0s
[23:00:47.085] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023792732816339136:35:0)
[23:00:47.085] Timer tick, asking Buildbucket for the build status
[23:00:47.367] Build 8858362670629860672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:00:47.367] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023792732816339136:36:0) after 1m0s
[23:01:47.371] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023792732816339136:36:0)
[23:01:47.371] Timer tick, asking Buildbucket for the build status
[23:01:47.480] Build 8858362670629860672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:01:47.480] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023792732816339136:37:0) after 1m0s
[23:02:47.509] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023792732816339136:37:0)
[23:02:47.509] Timer tick, asking Buildbucket for the build status
[23:02:47.673] Build 8858362670629860672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:02:47.673] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023792732816339136:38:0) after 1m0s
[23:03:47.735] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023792732816339136:38:0)
[23:03:47.735] Timer tick, asking Buildbucket for the build status
[23:03:47.838] Build 8858362670629860672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:03:47.838] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023792732816339136:39:0) after 1m0s
[23:04:47.815] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023792732816339136:39:0)
[23:04:47.815] Timer tick, asking Buildbucket for the build status
[23:04:47.903] Build 8858362670629860672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:04:47.903] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023792732816339136:40:0) after 1m0s
[23:05:48.039] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023792732816339136:40:0)
[23:05:48.039] Timer tick, asking Buildbucket for the build status
[23:05:48.123] Build 8858362670629860672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:05:48.123] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023792732816339136:41:0) after 1m0s
[23:06:48.395] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023792732816339136:41:0)
[23:06:48.395] Timer tick, asking Buildbucket for the build status
[23:06:48.488] Build 8858362670629860672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:06:48.488] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023792732816339136:42:0) after 1m0s
[23:07:48.583] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023792732816339136:42:0)
[23:07:48.583] Timer tick, asking Buildbucket for the build status
[23:07:48.715] Build 8858362670629860672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:07:48.715] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023792732816339136:43:0) after 1m0s
[23:08:48.737] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023792732816339136:43:0)
[23:08:48.738] Timer tick, asking Buildbucket for the build status
[23:08:48.836] Build 8858362670629860672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:08:48.836] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023792732816339136:44:0) after 1m0s
[23:09:48.893] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023792732816339136:44:0)
[23:09:48.893] Timer tick, asking Buildbucket for the build status
[23:09:48.998] Build 8858362670629860672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:09:48.998] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023792732816339136:45:0) after 1m0s
[23:10:49.073] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023792732816339136:45:0)
[23:10:49.073] Timer tick, asking Buildbucket for the build status
[23:10:49.143] Build 8858362670629860672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:10:49.143] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023792732816339136:46:0) after 1m0s
[23:11:49.186] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023792732816339136:46:0)
[23:11:49.186] Timer tick, asking Buildbucket for the build status
[23:11:49.321] Build 8858362670629860672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:11:49.321] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023792732816339136:47:0) after 1m0s
[23:12:49.354] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023792732816339136:47:0)
[23:12:49.354] Timer tick, asking Buildbucket for the build status
[23:12:49.504] Build 8858362670629860672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:12:49.504] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023792732816339136:48:0) after 1m0s
[23:13:49.650] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023792732816339136:48:0)
[23:13:49.650] Timer tick, asking Buildbucket for the build status
[23:13:49.819] Build 8858362670629860672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:13:49.819] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023792732816339136:49:0) after 1m0s
[23:14:49.895] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023792732816339136:49:0)
[23:14:49.895] Timer tick, asking Buildbucket for the build status
[23:14:49.966] Build 8858362670629860672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:14:49.966] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023792732816339136:50:0) after 1m0s
[23:15:50.033] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023792732816339136:50:0)
[23:15:50.033] Timer tick, asking Buildbucket for the build status
[23:15:50.613] Build 8858362670629860672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:15:50.613] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023792732816339136:51:0) after 1m0s
[23:16:50.683] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023792732816339136:51:0)
[23:16:50.683] Timer tick, asking Buildbucket for the build status
[23:16:50.880] Build 8858362670629860672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:16:50.880] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023792732816339136:52:0) after 1m0s
[23:17:50.930] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023792732816339136:52:0)
[23:17:50.930] Timer tick, asking Buildbucket for the build status
[23:17:51.203] Build 8858362670629860672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:17:51.203] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023792732816339136:53:0) after 1m0s
[23:18:51.182] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023792732816339136:53:0)
[23:18:51.182] Timer tick, asking Buildbucket for the build status
[23:18:51.324] Build 8858362670629860672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:18:51.324] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023792732816339136:54:0) after 1m0s
[23:19:51.400] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023792732816339136:54:0)
[23:19:51.400] Timer tick, asking Buildbucket for the build status
[23:19:51.614] Build 8858362670629860672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:19:51.614] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023792732816339136:55:0) after 1m0s
[23:20:51.757] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023792732816339136:55:0)
[23:20:51.757] Timer tick, asking Buildbucket for the build status
[23:20:52.023] Build 8858362670629860672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:20:52.023] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023792732816339136:56:0) after 1m0s
[23:21:52.163] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023792732816339136:56:0)
[23:21:52.163] Timer tick, asking Buildbucket for the build status
[23:21:52.584] Build 8858362670629860672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:21:52.584] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023792732816339136:57:0) after 1m0s
[23:22:52.660] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023792732816339136:57:0)
[23:22:52.660] Timer tick, asking Buildbucket for the build status
[23:22:53.465] Build 8858362670629860672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:22:53.465] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023792732816339136:58:0) after 1m0s
[23:23:53.518] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023792732816339136:58:0)
[23:23:53.518] Timer tick, asking Buildbucket for the build status
[23:23:54.296] Build 8858362670629860672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:23:54.296] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023792732816339136:59:0) after 1m0s
[23:24:54.341] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023792732816339136:59:0)
[23:24:54.341] Timer tick, asking Buildbucket for the build status
[23:24:54.495] Build 8858362670629860672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:24:54.495] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023792732816339136:60:0) after 1m0s
[23:25:54.562] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023792732816339136:60:0)
[23:25:54.562] Timer tick, asking Buildbucket for the build status
[23:25:55.085] Build 8858362670629860672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:25:55.086] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023792732816339136:61:0) after 1m0s
[23:26:55.108] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023792732816339136:61:0)
[23:26:55.108] Timer tick, asking Buildbucket for the build status
[23:26:55.263] Build 8858362670629860672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:26:55.263] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023792732816339136:62:0) after 1m0s
[23:27:55.270] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023792732816339136:62:0)
[23:27:55.270] Timer tick, asking Buildbucket for the build status
[23:27:55.476] Build 8858362670629860672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:27:55.476] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023792732816339136:63:0) after 1m0s
[23:28:55.564] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023792732816339136:63:0)
[23:28:55.564] Timer tick, asking Buildbucket for the build status
[23:28:55.762] Build 8858362670629860672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:28:55.762] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9023792732816339136:64:0) after 1m0s
[23:29:15.195] Received PubSub notification, asking Buildbucket for the build status
[23:29:16.424] Build 8858362670629860672: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[23:29:16.424] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1610407752187592",
"created_by": "project:chromium",
"created_ts": "1610404057816657",
"id": "8858362670629860672",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"4450fe11edfaa4308a2c0fec0c8bedc963ed4649\"}}",
"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\": \"4450fe11edfaa4308a2c0fec0c8bedc963ed4649\", \"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-26-ex6r\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-rel\", \"buildnumber\": 44517, \"got_angle_revision\": \"49d291065feeea710cf05c522c815091e1728aad\", \"got_dawn_revision\": \"99c3a691c23dbd75075eb852d2f6a1c569148826\", \"got_nacl_revision\": \"d28e6c52e05ea2f1b3231de473b6dbfce59bce40\", \"got_revision\": \"4450fe11edfaa4308a2c0fec0c8bedc963ed4649\", \"got_revision_cp\": \"refs/heads/master@{#842217}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"6a59d064a9754156c622fa3a9b93e326dbb304f8\", \"got_v8_revision\": \"271bd5240efa7d2743483df5a2c867c92aa03b42\", \"got_v8_revision_cp\": \"refs/heads/8.9.236@{#1}\", \"got_webrtc_revision\": \"ad0be281a41cc402c247c23206eba81be73ab401\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32937}\", \"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\": \"4450fe11edfaa4308a2c0fec0c8bedc963ed4649\", \"swarm_hashes_refs/heads/master(at){#842217}_without_patch\": {\"absl_hardening_tests\": \"41af09de341f95d56460b28742957fca62dc45f4\", \"accessibility_unittests\": \"1fcfb6bd3cd44b20a624494082d2bfd32aacadef\", \"angle_unittests\": \"c619de9f03311d921ba1f3cda75692005d7947da\", \"app_list_unittests\": \"a53a1a343ee9c898558bada6a87f731482363887\", \"app_shell_unittests\": \"2c5b1f8370df621f084ba7fb2f30ee8cbe9cc9d3\", \"ash_unittests\": \"83ebc121348a731ed7ce2c6519ea3e5c79da6474\", \"aura_unittests\": \"03ece9b7de2aca591cc71eb77026703a2ebe87c0\", \"base_unittests\": \"15016f2081cba7922e1439a706108134a32e91a5\", \"base_util_unittests\": \"69c757d55d1077cc7a12aff4652d6bf03f1939f1\", \"blink_common_unittests\": \"102163e430fab13d522e8e94bf43bdc6160c5f5e\", \"blink_fuzzer_unittests\": \"5926dc0bc9f115ba7d18f22cf7119fdbc21920e2\", \"blink_heap_unittests\": \"18e3f013582d6607259a6ca24de8b1992c2fd403\", \"blink_platform_unittests\": \"bee04b7bb62562b5024d4ccbac8398c95f8b7493\", \"blink_unittests\": \"611c6fb6fdec55c654e0e6ab75f54558856d1fa2\", \"boringssl_crypto_tests\": \"f7241028e36d4063f1867db4171ad5a28ea21bc7\", \"boringssl_ssl_tests\": \"f132564d05295960582ec855ec641b7736dfba49\", \"browser_tests\": \"a59c45fedece11886d3c38dd4a0eeb8099b0dddd\", \"capture_unittests\": \"b8ea0b2fa24554b78086c97a58a74f71b4a3b466\", \"cast_unittests\": \"68dcc7efa1f24d6a696ba60dbe03cd735d7601bf\", \"cc_unittests\": \"e9d73ac0633a5a60882123050e778e106ea829bc\", \"chrome_app_unittests\": \"d76fd7ff3936ab0121e8cf12e0d002a0dd9f63a5\", \"chromedriver_unittests\": \"597fa713cf99877d1c28a5719803fa416ab44b69\", \"chromeos_components_unittests\": \"2209ae2e4f6f89036f68432362898dcf3dadfdcd\", \"chromeos_unittests\": \"1e374c3e995f8361c37f580be085516670b103fa\", \"components_browsertests\": \"e7c3c7c8f7eb586cd47c4ac6cf3b1c23a3f99f08\", \"components_unittests\": \"5cae032832c9a9fd987709b8ffddae3526ab61e1\", \"compositor_unittests\": \"ee44655ede06472088f6bc937a709929ac83dd26\", \"content_browsertests\": \"f2847174b47050c36b0f69314cf2c4727ff03419\", \"content_unittests\": \"4e4099c6751ea3d0a643631f123d01cac5fde0f7\", \"crashpad_tests\": \"e8993b9d48afc6e8c4a909ef896a24009ae13e27\", \"crypto_unittests\": \"e0ce1c62c21c667dec8aa6f272ca5e7beab0f2c0\", \"dbus_unittests\": \"ec3de4e27e0d167fed096f33ab95ecb6a4ad9832\", \"device_unittests\": \"36dab73e6c6ca0ea27df44f2d7b433c3db7bb601\", \"display_unittests\": \"4b773c4c0170b548616873620bedfda9c682080c\", \"events_unittests\": \"4a9912bb2e7c8898577bfbabf0c2d48f4bacab3c\", \"exo_unittests\": \"ee7039467a6725dc5a0ad78a3f0f06fd8f9d3756\", \"extensions_browsertests\": \"b19e0b33b6a3eda6d0c6f310564655dc7869a30e\", \"extensions_unittests\": \"9e0c7676ef608adfb78f3cb9cd5d9a6b2acfa45b\", \"filesystem_service_unittests\": \"5cc9086703ea0ff20d9ac1531410315d2e64e54d\", \"gcm_unit_tests\": \"fbed2a7a0ca5ad89e02f2f5b6f5f11ffbdeed7dd\", \"gfx_unittests\": \"8a50247c9a161632e6dc16751419efc01990b9ca\", \"gin_unittests\": \"8e4729f9f1c3f13e291cc190c1c324df079ac545\", \"gl_unittests_ozone\": \"8e43ea825b4bd3204841379381b6dd72cb98931a\", \"google_apis_unittests\": \"ac38e6b9f3840c75a33a0cbac43f641d0ed1f0fe\", \"gpu_unittests\": \"f9953f3e08a662bcce63d9e451f0b26f1f13ec39\", \"gwp_asan_unittests\": \"09e226a4fd884acd1a80d48f765666c41fceea27\", \"interactive_ui_tests\": \"9b944e43550a4558d3cd3069c67487e03babe8c1\", \"ipc_tests\": \"75b88bd13ba1c645fa18c488e9efe758d440582e\", \"jingle_unittests\": \"dc0e6dcdceb2ddd16ec4a2ad3782301f94921fa1\", \"keyboard_unittests\": \"3112707f1b698d36e5cc1cd4e434d0564e284606\", \"latency_unittests\": \"0acc2c6efe859595e418b53fb7c09fa8076dffee\", \"libjingle_xmpp_unittests\": \"849f0e44f403cd689447cad7e6e14aebcefe0ebe\", \"liburlpattern_unittests\": \"657d8b91743ea818a399ee1f82d3fa9f55587bd3\", \"media_blink_unittests\": \"077ed3ba0def8c3522a00329312d8cc0cb0c4eb9\", \"media_unittests\": \"7d599dda512bb829da47c65837aa5efc37a31bad\", \"message_center_unittests\": \"156971d5ae260ae34418af63156d3e6e3a9dbdc5\", \"midi_unittests\": \"6f8f5d625d6fe132b390aa51f4ae87396654a485\", \"mojo_core_unittests\": \"42fb25c2220222a7228b4a1d13bd151ad71c7f3e\", \"mojo_unittests\": \"e182fff4b035acebcd455bcef48743d5eef867eb\", \"nacl_helper_nonsfi_unittests\": \"9e6bc19f0a5d21e4ca95b703199c7b98daecf9dd\", \"nacl_loader_unittests\": \"1a21caf5e935aff4a2e4c0b9ec7ca0e29469216c\", \"native_theme_unittests\": \"372f61b9945fff16746516173323f4d1950e3dda\", \"net_unittests\": \"a1844eb39e81d82a815a948093b039906ea68891\", \"ozone_gl_unittests\": \"b33a2d8433f82ae94b61487253073f52a4605271\", \"ozone_unittests\": \"e8ff8c3dc73ae4d61e3af73951af7ab0bce941f3\", \"ozone_x11_unittests\": \"957da330dbbf34a04b3dc0be0883d99015de6902\", \"pdf_unittests\": \"26a5a5ad15d86d830bd46dedb1ade98a63da4993\", \"perfetto_unittests\": \"f7a4555d1434f727a99cf3f0753564d996f3b658\", \"ppapi_unittests\": \"76182d339a654ab0ae8648e0c226321092196cf0\", \"printing_unittests\": \"f68b86f8576c430b40efce53be771a48a40145e1\", \"remoting_unittests\": \"d037487d8a5acb5cc734b186d935027ae4bc8467\", \"sandbox_linux_unittests\": \"7f931ca6932abd2d30ef54928e629de64f54a173\", \"service_manager_unittests\": \"6cd003d091ade9a5434d5d52e44619d67bddc224\", \"services_unittests\": \"a3917f08d43451e9dfe25a4bdb2c8dded576afe1\", \"shell_dialogs_unittests\": \"8c07341e3baa24ae5d7c03fe767c586eb098664c\", \"shell_encryption_unittests\": \"32e76053d791a08b1b0b99f0ae5569ad619bb25e\", \"skia_unittests\": \"2b868ee4dc3d050548f05d440222e95bc82ab8ce\", \"snapshot_unittests\": \"240e127055fa7671ab749f826cd0db7f1289948c\", \"sql_unittests\": \"4e64614cf231e36d0d49c695113e05535c9c10c0\", \"storage_unittests\": \"19eeb091702104839b1de4bbc1166057247b86cc\", \"sync_integration_tests\": \"f7719d4a84cdfaccc6e77c47d89d919bf0e7b9be\", \"traffic_annotation_auditor_unittests\": \"b6b50ef193a3a3674343a5accc57e24dab82e5e3\", \"ui_base_unittests\": \"2a99b9df92e4b8a7907bd81d907f13df4e784332\", \"ui_chromeos_unittests\": \"fd004e1bb56b3b5598eb026efb9a32a9c1ea6596\", \"ui_touch_selection_unittests\": \"9228153f987f2c5203d7777fb0ea4bdd46899e3e\", \"unit_tests\": \"abda4cf0e6e37dd7c3678bbfb82ee9a543fb9902\", \"url_unittests\": \"9f43b69efa8666996b969f9697e831a45aecc2da\", \"usage_time_limit_unittests\": \"a0dcb20b87d3880698218a7d611df1d0db323c70\", \"views_unittests\": \"e550d0a8a5b982de68d4a38e205716b8f7b2c9cc\", \"viz_unittests\": \"79bc39ba4b7522e515887298e5a925528caf03a0\", \"wayland_client_perftests\": \"8ac1a3e6d7cee89f79d899f410e12fccc0bfdbc7\", \"wm_unittests\": \"585983b6cb5767bdfe4c6098d357701656a33193\", \"wtf_unittests\": \"913182698e849824d5fbc4c9c21a4d8e4aa8dea4\", \"zlib_unittests\": \"f2a7fd2805679c426997ef093ee6c8d0df3a1c97\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_0fb1f456593ae2600bf9c9616e0e5dcc3c87ffc9d4d6fb93e9350aac8ae4516c_v2\", \"builder_11b57e6d03428c2faa53796866d496d474313651f92f95ab80780c64b3558ac7_v2\", \"builder_361cbaa79261c75a92fc63a8f8c6fc117764238b3277286fa51086e6da3301df_v2\", \"builder_3ee79eab7726d87d4f9bf92f4b58b9506b0a7a0d17f466f0c3d67bd50a4697d1_v2\", \"builder_425d15596feb4c1a31f11fc1911a8217c0e7ee1a27198f37227d51247058a439_v2\", \"builder_7d75f6999c07e42a7f7fde2313383264fbd8ed871e2008bde33b278973c1f4c8_v2\", \"builder_94fa3119a28f4651cdd4fc5b981a64c69b26c151212072333439362290628993_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-xenial-us-east1-d-8-26-ex6r\"], \"image\": [\"chrome-xenial-21010300-f0aeabbfea7\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"e2-standard-8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16\", \"Ubuntu-16.04\", \"Ubuntu-16.04.6\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.12\"], \"server_version\": [\"5657-0417a3f\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-d\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1610404059130799",
"status": "COMPLETED",
"status_changed_ts": "1610407753367735",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/44517",
"builder:linux-chromeos-rel",
"buildset:commit/git/4450fe11edfaa4308a2c0fec0c8bedc963ed4649",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4450fe11edfaa4308a2c0fec0c8bedc963ed4649",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023792732816339136",
"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/8858362670629860672/+/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:510c615dd72c3810",
"user_agent:luci-scheduler"
],
"updated_ts": "1610407753373077",
"url": "https://ci.chromium.org/b/8858362670629860672",
"utcnow_ts": "1610407755427666"
}
}
[23:29:16.424] Invocation finished in 1h1m40.077149527s with status SUCCEEDED