[22:13:26.198] New invocation is queued and will start shortly
[22:13:27.347] Starting the invocation (attempt 1)
[22:13:27.470] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[22:13:27.470] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[22:13:27.470] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9027417502934781184",
"parameters_json": "{\"builder_name\":\"linux-chromeos-rel\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"fc4adbaeddf1b07529fda1a8f2071b1863ccffe1\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-rel",
"scheduler_invocation_id:9027417502934781184",
"scheduler_job_id:chromium/linux-chromeos-rel",
"user_agent:luci-scheduler",
"buildset:commit/git/fc4adbaeddf1b07529fda1a8f2071b1863ccffe1",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/fc4adbaeddf1b07529fda1a8f2071b1863ccffe1",
"gitiles_ref:refs/heads/master"
]
}
[22:13:28.456] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1606947207626565",
"id": "8861987440775119024",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"fc4adbaeddf1b07529fda1a8f2071b1863ccffe1\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1606947208172593",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/43391",
"builder:linux-chromeos-rel",
"buildset:commit/git/fc4adbaeddf1b07529fda1a8f2071b1863ccffe1",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/fc4adbaeddf1b07529fda1a8f2071b1863ccffe1",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9027417502934781184",
"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/8861987440775119024/+/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": "1606947208172763",
"url": "https://ci.chromium.org/b/8861987440775119024",
"utcnow_ts": "1606947208424091"
}
}
[22:13:28.456] Task URL: https://ci.chromium.org/b/8861987440775119024
[22:13:28.456] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027417502934781184:2:0) after 1m0s
[22:13:59.364] Received PubSub notification, asking Buildbucket for the build status
[22:13:59.445] Build 8861987440775119024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:14:28.476] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027417502934781184:2:0)
[22:14:28.476] Timer tick, asking Buildbucket for the build status
[22:14:28.711] Build 8861987440775119024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:14:28.711] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027417502934781184:4:0) after 1m0s
[22:15:28.776] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027417502934781184:4:0)
[22:15:28.776] Timer tick, asking Buildbucket for the build status
[22:15:28.931] Build 8861987440775119024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:15:28.931] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027417502934781184:5:0) after 1m0s
[22:16:28.943] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027417502934781184:5:0)
[22:16:28.943] Timer tick, asking Buildbucket for the build status
[22:16:29.095] Build 8861987440775119024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:16:29.095] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027417502934781184:6:0) after 1m0s
[22:17:29.197] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027417502934781184:6:0)
[22:17:29.198] Timer tick, asking Buildbucket for the build status
[22:17:29.354] Build 8861987440775119024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:17:29.354] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027417502934781184:7:0) after 1m0s
[22:18:29.396] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027417502934781184:7:0)
[22:18:29.396] Timer tick, asking Buildbucket for the build status
[22:18:29.498] Build 8861987440775119024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:18:29.499] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027417502934781184:8:0) after 1m0s
[22:19:29.576] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027417502934781184:8:0)
[22:19:29.576] Timer tick, asking Buildbucket for the build status
[22:19:29.648] Build 8861987440775119024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:19:29.648] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027417502934781184:9:0) after 1m0s
[22:20:29.693] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027417502934781184:9:0)
[22:20:29.693] Timer tick, asking Buildbucket for the build status
[22:20:29.774] Build 8861987440775119024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:20:29.774] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027417502934781184:10:0) after 1m0s
[22:21:29.834] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027417502934781184:10:0)
[22:21:29.834] Timer tick, asking Buildbucket for the build status
[22:21:35.359] Build 8861987440775119024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:21:35.359] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027417502934781184:11:0) after 1m0s
[22:22:35.484] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027417502934781184:11:0)
[22:22:35.484] Timer tick, asking Buildbucket for the build status
[22:22:35.642] Build 8861987440775119024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:22:35.642] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027417502934781184:12:0) after 1m0s
[22:23:35.687] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027417502934781184:12:0)
[22:23:35.687] Timer tick, asking Buildbucket for the build status
[22:23:35.788] Build 8861987440775119024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:23:35.788] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027417502934781184:13:0) after 1m0s
[22:24:35.867] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027417502934781184:13:0)
[22:24:35.867] Timer tick, asking Buildbucket for the build status
[22:24:35.947] Build 8861987440775119024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:24:35.947] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027417502934781184:14:0) after 1m0s
[22:25:35.975] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027417502934781184:14:0)
[22:25:35.975] Timer tick, asking Buildbucket for the build status
[22:25:36.741] Build 8861987440775119024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:25:36.741] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027417502934781184:15:0) after 1m0s
[22:26:36.725] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027417502934781184:15:0)
[22:26:36.725] Timer tick, asking Buildbucket for the build status
[22:26:36.807] Build 8861987440775119024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:26:36.807] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027417502934781184:16:0) after 1m0s
[22:27:36.855] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027417502934781184:16:0)
[22:27:36.855] Timer tick, asking Buildbucket for the build status
[22:27:38.375] Build 8861987440775119024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:27:38.375] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027417502934781184:17:0) after 1m0s
[22:28:38.413] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027417502934781184:17:0)
[22:28:38.413] Timer tick, asking Buildbucket for the build status
[22:28:38.527] Build 8861987440775119024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:28:38.527] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027417502934781184:18:0) after 1m0s
[22:29:38.569] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027417502934781184:18:0)
[22:29:38.569] Timer tick, asking Buildbucket for the build status
[22:29:38.731] Build 8861987440775119024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:29:38.731] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027417502934781184:19:0) after 1m0s
[22:30:38.765] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027417502934781184:19:0)
[22:30:38.765] Timer tick, asking Buildbucket for the build status
[22:30:38.852] Build 8861987440775119024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:30:38.852] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027417502934781184:20:0) after 1m0s
[22:31:38.834] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027417502934781184:20:0)
[22:31:38.834] Timer tick, asking Buildbucket for the build status
[22:31:39.071] Build 8861987440775119024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:31:39.071] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027417502934781184:21:0) after 1m0s
[22:32:39.059] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027417502934781184:21:0)
[22:32:39.059] Timer tick, asking Buildbucket for the build status
[22:32:39.419] Build 8861987440775119024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:32:39.419] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027417502934781184:22:0) after 1m0s
[22:33:39.471] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027417502934781184:22:0)
[22:33:39.471] Timer tick, asking Buildbucket for the build status
[22:33:39.610] Build 8861987440775119024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:33:39.610] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027417502934781184:23:0) after 1m0s
[22:34:39.682] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027417502934781184:23:0)
[22:34:39.682] Timer tick, asking Buildbucket for the build status
[22:34:39.740] Build 8861987440775119024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:34:39.740] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027417502934781184:24:0) after 1m0s
[22:35:39.793] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027417502934781184:24:0)
[22:35:39.793] Timer tick, asking Buildbucket for the build status
[22:35:39.873] Build 8861987440775119024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:35:39.873] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027417502934781184:25:0) after 1m0s
[22:36:39.920] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027417502934781184:25:0)
[22:36:39.920] Timer tick, asking Buildbucket for the build status
[22:36:39.995] Build 8861987440775119024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:36:39.995] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027417502934781184:26:0) after 1m0s
[22:37:40.057] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027417502934781184:26:0)
[22:37:40.057] Timer tick, asking Buildbucket for the build status
[22:37:40.130] Build 8861987440775119024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:37:40.130] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027417502934781184:27:0) after 1m0s
[22:38:40.192] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027417502934781184:27:0)
[22:38:40.192] Timer tick, asking Buildbucket for the build status
[22:38:40.647] Build 8861987440775119024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:38:40.647] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027417502934781184:28:0) after 1m0s
[22:39:40.693] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027417502934781184:28:0)
[22:39:40.693] Timer tick, asking Buildbucket for the build status
[22:39:41.018] Build 8861987440775119024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:39:41.018] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027417502934781184:29:0) after 1m0s
[22:40:41.091] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027417502934781184:29:0)
[22:40:41.091] Timer tick, asking Buildbucket for the build status
[22:40:41.160] Build 8861987440775119024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:40:41.160] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027417502934781184:30:0) after 1m0s
[22:41:41.284] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027417502934781184:30:0)
[22:41:41.284] Timer tick, asking Buildbucket for the build status
[22:41:41.342] Build 8861987440775119024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:41:41.342] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027417502934781184:31:0) after 1m0s
[22:42:41.413] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027417502934781184:31:0)
[22:42:41.413] Timer tick, asking Buildbucket for the build status
[22:42:41.474] Build 8861987440775119024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:42:41.474] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027417502934781184:32:0) after 1m0s
[22:43:41.511] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027417502934781184:32:0)
[22:43:41.511] Timer tick, asking Buildbucket for the build status
[22:43:41.895] Build 8861987440775119024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:43:41.895] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027417502934781184:33:0) after 1m0s
[22:44:42.048] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027417502934781184:33:0)
[22:44:42.048] Timer tick, asking Buildbucket for the build status
[22:44:42.116] Build 8861987440775119024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:44:42.116] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027417502934781184:34:0) after 1m0s
[22:45:42.336] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027417502934781184:34:0)
[22:45:42.336] Timer tick, asking Buildbucket for the build status
[22:45:42.426] Build 8861987440775119024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:45:42.426] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027417502934781184:35:0) after 1m0s
[22:46:43.086] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027417502934781184:35:0)
[22:46:43.086] Timer tick, asking Buildbucket for the build status
[22:46:43.161] Build 8861987440775119024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:46:43.161] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027417502934781184:36:0) after 1m0s
[22:47:43.248] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027417502934781184:36:0)
[22:47:43.248] Timer tick, asking Buildbucket for the build status
[22:47:43.310] Build 8861987440775119024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:47:43.310] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027417502934781184:37:0) after 1m0s
[22:48:43.572] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027417502934781184:37:0)
[22:48:43.572] Timer tick, asking Buildbucket for the build status
[22:48:43.809] Build 8861987440775119024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:48:43.810] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027417502934781184:38:0) after 1m0s
[22:49:43.930] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027417502934781184:38:0)
[22:49:43.930] Timer tick, asking Buildbucket for the build status
[22:49:44.179] Build 8861987440775119024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:49:44.179] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027417502934781184:39:0) after 1m0s
[22:50:44.291] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027417502934781184:39:0)
[22:50:44.291] Timer tick, asking Buildbucket for the build status
[22:50:44.391] Build 8861987440775119024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:50:44.391] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027417502934781184:40:0) after 1m0s
[22:51:44.685] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027417502934781184:40:0)
[22:51:44.685] Timer tick, asking Buildbucket for the build status
[22:51:44.803] Build 8861987440775119024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:51:44.803] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027417502934781184:41:0) after 1m0s
[22:52:44.825] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027417502934781184:41:0)
[22:52:44.825] Timer tick, asking Buildbucket for the build status
[22:52:44.940] Build 8861987440775119024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:52:44.940] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027417502934781184:42:0) after 1m0s
[22:53:45.013] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027417502934781184:42:0)
[22:53:45.013] Timer tick, asking Buildbucket for the build status
[22:53:45.180] Build 8861987440775119024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:53:45.180] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027417502934781184:43:0) after 1m0s
[22:54:45.236] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027417502934781184:43:0)
[22:54:45.236] Timer tick, asking Buildbucket for the build status
[22:54:45.359] Build 8861987440775119024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:54:45.359] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027417502934781184:44:0) after 1m0s
[22:55:45.452] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027417502934781184:44:0)
[22:55:45.452] Timer tick, asking Buildbucket for the build status
[22:55:46.093] Build 8861987440775119024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:55:46.093] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027417502934781184:45:0) after 1m0s
[22:56:46.170] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027417502934781184:45:0)
[22:56:46.170] Timer tick, asking Buildbucket for the build status
[22:56:47.830] Build 8861987440775119024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:56:47.830] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027417502934781184:46:0) after 1m0s
[22:57:47.904] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027417502934781184:46:0)
[22:57:47.904] Timer tick, asking Buildbucket for the build status
[22:57:48.053] Build 8861987440775119024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:57:48.053] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027417502934781184:47:0) after 1m0s
[22:58:48.101] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027417502934781184:47:0)
[22:58:48.101] Timer tick, asking Buildbucket for the build status
[22:58:48.212] Build 8861987440775119024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:58:48.212] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027417502934781184:48:0) after 1m0s
[22:59:48.256] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027417502934781184:48:0)
[22:59:48.256] Timer tick, asking Buildbucket for the build status
[22:59:48.380] Build 8861987440775119024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:59:48.380] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027417502934781184:49:0) after 1m0s
[23:00:48.417] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027417502934781184:49:0)
[23:00:48.417] Timer tick, asking Buildbucket for the build status
[23:00:48.838] Build 8861987440775119024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:00:48.838] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027417502934781184:50:0) after 1m0s
[23:01:48.907] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027417502934781184:50:0)
[23:01:48.907] Timer tick, asking Buildbucket for the build status
[23:01:49.741] Build 8861987440775119024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:01:49.741] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027417502934781184:51:0) after 1m0s
[23:02:49.766] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027417502934781184:51:0)
[23:02:49.766] Timer tick, asking Buildbucket for the build status
[23:02:49.905] Build 8861987440775119024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:02:49.905] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027417502934781184:52:0) after 1m0s
[23:03:49.980] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027417502934781184:52:0)
[23:03:49.980] Timer tick, asking Buildbucket for the build status
[23:03:50.306] Build 8861987440775119024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:03:50.306] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027417502934781184:53:0) after 1m0s
[23:04:50.427] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027417502934781184:53:0)
[23:04:50.427] Timer tick, asking Buildbucket for the build status
[23:04:50.516] Build 8861987440775119024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:04:50.516] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027417502934781184:54:0) after 1m0s
[23:05:50.567] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027417502934781184:54:0)
[23:05:50.567] Timer tick, asking Buildbucket for the build status
[23:05:50.843] Build 8861987440775119024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:05:50.843] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027417502934781184:55:0) after 1m0s
[23:06:50.918] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027417502934781184:55:0)
[23:06:50.918] Timer tick, asking Buildbucket for the build status
[23:06:51.027] Build 8861987440775119024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:06:51.027] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027417502934781184:56:0) after 1m0s
[23:07:51.038] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027417502934781184:56:0)
[23:07:51.038] Timer tick, asking Buildbucket for the build status
[23:07:51.654] Build 8861987440775119024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:07:51.654] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027417502934781184:57:0) after 1m0s
[23:08:51.700] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027417502934781184:57:0)
[23:08:51.700] Timer tick, asking Buildbucket for the build status
[23:08:52.040] Build 8861987440775119024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:08:52.040] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027417502934781184:58:0) after 1m0s
[23:09:52.114] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027417502934781184:58:0)
[23:09:52.114] Timer tick, asking Buildbucket for the build status
[23:09:52.595] Build 8861987440775119024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:09:52.595] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027417502934781184:59:0) after 1m0s
[23:10:52.625] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027417502934781184:59:0)
[23:10:52.625] Timer tick, asking Buildbucket for the build status
[23:10:52.747] Build 8861987440775119024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:10:52.747] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9027417502934781184:60:0) after 1m0s
[23:11:27.147] Received PubSub notification, asking Buildbucket for the build status
[23:11:27.409] Build 8861987440775119024: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[23:11:27.409] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1606950685565661",
"created_by": "project:chromium",
"created_ts": "1606947207626565",
"id": "8861987440775119024",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"fc4adbaeddf1b07529fda1a8f2071b1863ccffe1\"}}",
"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\": \"fc4adbaeddf1b07529fda1a8f2071b1863ccffe1\", \"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-54-oga7\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-rel\", \"buildnumber\": 43391, \"got_angle_revision\": \"0c47015b57a03230e0cab10532c3deed80604512\", \"got_dawn_revision\": \"429a12542f160a36d87c1daa6c3102bce072207e\", \"got_nacl_revision\": \"3ac0b060c794b64531b47dfded8ff8d951a5c648\", \"got_revision\": \"fc4adbaeddf1b07529fda1a8f2071b1863ccffe1\", \"got_revision_cp\": \"refs/heads/master@{#832993}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"bfb211914759cc5deffbe9419cd1c2cae0573488\", \"got_v8_revision\": \"b3456dd37acf448bb7e41b1dfc6eee5d28f38081\", \"got_v8_revision_cp\": \"refs/heads/8.9.75@{#1}\", \"got_webrtc_revision\": \"01b3e24a83564584ab0736074776e634c5ca6bb0\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32741}\", \"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\": \"fc4adbaeddf1b07529fda1a8f2071b1863ccffe1\", \"swarm_hashes_refs/heads/master(at){#832993}_without_patch\": {\"absl_hardening_tests\": \"84205a29443fc94432f8341dfb3f16c2b35eb76e\", \"accessibility_unittests\": \"f20a6997207a7e61591837a3fe2a0d469c7d5d24\", \"angle_unittests\": \"02ee7c4f3dbe8ba2e5d113ab629cb43601cc82fb\", \"app_list_unittests\": \"8fa26eec55be5775451cd7c0b2f7a3bbef45823c\", \"app_shell_unittests\": \"e4da3747146d3d4f96777f4d836ce5cf54e9b0a3\", \"ash_unittests\": \"4d4cbe4c16927a2a0365062e64d0f8fa2ba601fd\", \"aura_unittests\": \"3f69dc9b23f9f0745de5390a5dc4c0186f8d8178\", \"base_unittests\": \"7c0dbc7df3b2768e325d6b044bb156ce44a6fb8d\", \"base_util_unittests\": \"8f27d6ee1b707db6052d0705bf850b4e5fd8bea6\", \"blink_common_unittests\": \"f08bdc3ee15a46ca8c52acf2b39829d5b020b24a\", \"blink_fuzzer_unittests\": \"7c88ba8b796b4e0deb5f09f3a0c447b08ba3fc58\", \"blink_heap_unittests\": \"febb5abe4faff24c36d118851d8e1830a3e34bb6\", \"blink_platform_unittests\": \"90c38bc876c12ed25312f81a889d090a6b964315\", \"blink_unittests\": \"41f9e7b13c9d1a0779c2d01a873f77be3fe6862b\", \"boringssl_crypto_tests\": \"cbebce271deb1de6055cca8691fb2dfa26db633f\", \"boringssl_ssl_tests\": \"e233ca1cfc65480cf271f272700d8cc0aabadd11\", \"browser_tests\": \"60104c59290698e3010cb149f529f0588d0bf149\", \"capture_unittests\": \"3a7aa8da0d4af510137acffeca24425c5e68a540\", \"cast_unittests\": \"1a53fb1a73b23ba78252867255afd85d43fbf392\", \"cc_unittests\": \"cea2118d082c04898442cacf2826e60485dae451\", \"chrome_app_unittests\": \"689259b54df14fc7ba4626a428dbe1ef7befbcd4\", \"chromedriver_unittests\": \"eddd102b486e7854c44cb8115d76d585b1a72ff5\", \"chromeos_components_unittests\": \"07d66c0a111e406e2789f3fe587c8b29b5fded3b\", \"chromeos_unittests\": \"dd466df66d7b10f54b9944b93129d629eac8a055\", \"components_browsertests\": \"e2114551934cf36ecd6118ebbb86efc55f271af7\", \"components_unittests\": \"6734845fba7c3744de967153abae4ae1874b770a\", \"compositor_unittests\": \"f2c89ab382e3ce3b35d183d2fb28fd7d3b580319\", \"content_browsertests\": \"ea2bcf4e43ceddc69eb549c518021e60274f1954\", \"content_unittests\": \"2d4298591062ba15221cbf69fd50245566662dd5\", \"crashpad_tests\": \"9be2b1c317910078a68758669b76fcbfb84713cf\", \"crypto_unittests\": \"f56a0bd0cd460f62908b3e36457e91a8fea55811\", \"dbus_unittests\": \"4cb30793d6883fba360cc6c10fbc9e6e5027a86b\", \"device_unittests\": \"c317d91cea260bdc8aa5226a0bf0f80a78e88a39\", \"display_unittests\": \"d6733cabd6d8dea96b156fccb836e5750257f1f3\", \"events_unittests\": \"71577f0b1badf6deff5534bde15ed66c403846ce\", \"exo_unittests\": \"7b7c079350d6fb9d95e881e11b2ae3dc859e6655\", \"extensions_browsertests\": \"1870fceae93966ee29a0658c139385ec6d9e613e\", \"extensions_unittests\": \"e924ed12db8e3d62d54d350fd79d2433747af8cb\", \"filesystem_service_unittests\": \"db49a3a63c06c5cc70f1f1a9316aa3d2b155d6c6\", \"gcm_unit_tests\": \"6173c79237e2447359ff7e4a027dd8b329ea199c\", \"gfx_unittests\": \"61fe8422c3157cb648d6af4e3ddcf7c1afe1970d\", \"gin_unittests\": \"7d5744b981362dc1b6b4b923739e96fac571d41c\", \"gl_unittests_ozone\": \"4f27a3c387f5865209b2872efe75f429680fa24f\", \"google_apis_unittests\": \"0ba062bbbb25301be2ab5f0707e3e8ad1dcd590e\", \"gpu_unittests\": \"6cb812bd1910d8ac9f73c9ffded2b23886a37fa6\", \"gwp_asan_unittests\": \"9ac4069f428ccd4415758b2e19b04269e7bbfc23\", \"interactive_ui_tests\": \"af16c91d505b142b14e36a6794dceb7d7e6e2b0b\", \"ipc_tests\": \"585fea4fa5b9877493a6f226978a4191029018d8\", \"jingle_unittests\": \"e0d89016c9186b86d3dbc635bbaaf5a08bc082f7\", \"keyboard_unittests\": \"cf63c1254edbcae5eff266c6554a96b9c40ea73c\", \"latency_unittests\": \"4076b84ef6e145a319ba329817c0f7622cbe2ba8\", \"libjingle_xmpp_unittests\": \"95e17ba4523eb667a115c69b431e925f343ba9e1\", \"liburlpattern_unittests\": \"9fe9dd5cea9a761b3892db04db617b915a26348b\", \"media_blink_unittests\": \"9e5e8799e727a760417865803a1ff681bbe8e44a\", \"media_unittests\": \"f61dcb03fa97d2e5c53d13c37915271b9f73e15b\", \"message_center_unittests\": \"4064da1cd567c7680e0e0f550c6a81119cbc4eae\", \"midi_unittests\": \"e9bcaeea63e60b7e57edc6377b0e10ba68b22a24\", \"mojo_core_unittests\": \"66e819f886bbffe02948edc6cc9c0277b6f497bc\", \"mojo_unittests\": \"e83578f345d8877c1d86a0d4ae14920c6f2c1fc6\", \"nacl_helper_nonsfi_unittests\": \"174f7461ec2d2caac333a65275fb1816332b436f\", \"nacl_loader_unittests\": \"08e63b606329836dbfac6aefbd1e711210f3aac6\", \"native_theme_unittests\": \"b0972a9e70aa60e6513a139906c255169eca6b88\", \"net_unittests\": \"8ee06118b6277eed73b5e3eeb48376b2881ac35e\", \"ozone_gl_unittests\": \"913027af33936bfe1957050f38a0b42aa0bcb24c\", \"ozone_unittests\": \"8c601baccb2549bbdb5670b6c95696c86f4528f4\", \"ozone_x11_unittests\": \"07b246ed6c1d81e241494e8af4dd64b8dd27f1e9\", \"pdf_unittests\": \"7c1b6d722f771f581a73e9d6ce22b1b0e07ee6e4\", \"perfetto_unittests\": \"5a8bdd2561524539e7be90801129acc76b5e4956\", \"ppapi_unittests\": \"801875faed159b4cc886f88678a467ae2179821f\", \"printing_unittests\": \"e32f386b3359df45f66fc43e0006e108847bf40c\", \"remoting_unittests\": \"4db6293c9b928011417afa32c4930559061c0043\", \"sandbox_linux_unittests\": \"ef4358997aa7087caad2b5c56243e4862efd3ec0\", \"service_manager_unittests\": \"eedd97b8f768a302f49c9e253789e714b774c5fd\", \"services_unittests\": \"d721d5691417056731a7fd86ae887b83e90cb2ef\", \"shell_dialogs_unittests\": \"16680fb74818ec0aad4ddf1ea39783a4a2cb9bdf\", \"shell_encryption_unittests\": \"700578588359e1f892125fdcf9be2eb31a67e5e3\", \"skia_unittests\": \"31ebdd3a5e4db1f315e37297a67aaa539dcd982e\", \"snapshot_unittests\": \"66f291ede170337817e19e15e2b7777d96e40dfc\", \"sql_unittests\": \"11b2eb99452414adac2d3ff7c9f934ab903282c7\", \"storage_unittests\": \"e9fb3d864b2865a41988690e29e062d0cddb95af\", \"sync_integration_tests\": \"2bafb40dd596dad457baf08f13ff1f5a5488f217\", \"traffic_annotation_auditor_unittests\": \"f1708be298b5789aa57a3c360f903610d206c55a\", \"ui_base_unittests\": \"5a66aa9b5cb9d8e709db46f198c58347eef66b17\", \"ui_chromeos_unittests\": \"fc4d2538e12c7a17a866a9525c4382c504d52074\", \"ui_touch_selection_unittests\": \"12024d00d926c2948054f89bb45fd596c72c6b5b\", \"unit_tests\": \"4e13deede07dd7354b73b03ddb2bb65a4ceafddd\", \"url_unittests\": \"a07aa7181081a2d87b7dca82682f2a5d25c23ab3\", \"usage_time_limit_unittests\": \"0e81f3b77290c0b7551d0959a421c0817790f17c\", \"views_unittests\": \"cc386a6380f0b61265c655776c623b55c5e49b69\", \"viz_unittests\": \"11bcf9eb432ab880333ede4b9245ac7332ba0ba3\", \"wayland_client_perftests\": \"2c0cb6c00c0f1d531e5f29e3b097c7ed6c2ad691\", \"wm_unittests\": \"94972081e7d1a7f706e3f7cd1105729b1e4cbaad\", \"wtf_unittests\": \"472533ed132bdddfe3892696c8bd15bd3ae09dbc\", \"zlib_unittests\": \"53186e441f4d4cd4cb380a5dcc9bef8f672da098\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_0e4559ffd6169ab721e778dfe83867ca08424e9b4d480f5c0e6f91286240fc3f_v2\", \"builder_1b8c0e295e0040ae60b46798151163eecc632ef935bd1ea9777aec71f94caa4f_v2\", \"builder_544ce64b59aa2a62f93b2d1058d8c643105c17650fa0dc2da4c94ddccafb95f6_v2\", \"builder_94fa3119a28f4651cdd4fc5b981a64c69b26c151212072333439362290628993_v2\", \"builder_980e79f61a9eb10b3ecd5f710f08f0308a5299da9889db3d2b6a7010c019cc1f_v2\", \"builder_9cda2f8c21edca8a77ce5edebdfeeced1c5c1bb5bdbdcfdc91d50b975e168f53_v2\", \"builder_a15c792d02d243f2865d956ab51326d361a8fcfed3c44b30d209df7676d4718c_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-54-oga7\"], \"image\": [\"chrome-xenial-20112200-f7cdea8df03\"], \"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\": [\"5640-d0eb544\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-d\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1606947238475850",
"status": "COMPLETED",
"status_changed_ts": "1606950686562493",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/43391",
"builder:linux-chromeos-rel",
"buildset:commit/git/fc4adbaeddf1b07529fda1a8f2071b1863ccffe1",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/fc4adbaeddf1b07529fda1a8f2071b1863ccffe1",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9027417502934781184",
"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/8861987440775119024/+/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:503e5605ac3d7c10",
"user_agent:luci-scheduler"
],
"updated_ts": "1606950686567765",
"url": "https://ci.chromium.org/b/8861987440775119024",
"utcnow_ts": "1606950687394829"
}
}
[23:11:27.409] Invocation finished in 58m1.227115317s with status SUCCEEDED