[01:09:42.845] New invocation is queued and will start shortly
[01:09:44.299] Starting the invocation (attempt 1)
[01:09:44.332] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[01:09:44.335] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[01:09:44.336] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9024144921728219376",
"parameters_json": "{\"builder_name\":\"linux-chromeos-rel\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"7187698b6505ad0787d26f6c4273219f8dc69089\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-rel",
"scheduler_invocation_id:9024144921728219376",
"scheduler_job_id:chromium/linux-chromeos-rel",
"user_agent:luci-scheduler",
"buildset:commit/git/7187698b6505ad0787d26f6c4273219f8dc69089",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/7187698b6505ad0787d26f6c4273219f8dc69089",
"gitiles_ref:refs/heads/master"
]
}
[01:09:44.972] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1610068184458386",
"id": "8858714859371583552",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"7187698b6505ad0787d26f6c4273219f8dc69089\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1610068184848281",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/44427",
"builder:linux-chromeos-rel",
"buildset:commit/git/7187698b6505ad0787d26f6c4273219f8dc69089",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/7187698b6505ad0787d26f6c4273219f8dc69089",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9024144921728219376",
"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/8858714859371583552/+/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": "1610068184848433",
"url": "https://ci.chromium.org/b/8858714859371583552",
"utcnow_ts": "1610068184965708"
}
}
[01:09:44.972] Task URL: https://ci.chromium.org/b/8858714859371583552
[01:09:44.972] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024144921728219376:2:0) after 1m0s
[01:10:45.001] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024144921728219376:2:0)
[01:10:45.001] Timer tick, asking Buildbucket for the build status
[01:10:45.446] Build 8858714859371583552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:10:45.446] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024144921728219376:3:0) after 1m0s
[01:11:05.534] Received PubSub notification, asking Buildbucket for the build status
[01:11:05.621] Build 8858714859371583552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:11:45.495] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024144921728219376:3:0)
[01:11:45.495] Timer tick, asking Buildbucket for the build status
[01:11:45.683] Build 8858714859371583552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:11:45.683] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024144921728219376:5:0) after 1m0s
[01:12:45.704] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024144921728219376:5:0)
[01:12:45.704] Timer tick, asking Buildbucket for the build status
[01:12:45.814] Build 8858714859371583552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:12:45.814] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024144921728219376:6:0) after 1m0s
[01:13:45.872] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024144921728219376:6:0)
[01:13:45.872] Timer tick, asking Buildbucket for the build status
[01:13:45.947] Build 8858714859371583552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:13:45.947] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024144921728219376:7:0) after 1m0s
[01:14:45.966] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024144921728219376:7:0)
[01:14:45.966] Timer tick, asking Buildbucket for the build status
[01:14:46.086] Build 8858714859371583552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:14:46.086] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024144921728219376:8:0) after 1m0s
[01:15:46.162] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024144921728219376:8:0)
[01:15:46.162] Timer tick, asking Buildbucket for the build status
[01:15:46.308] Build 8858714859371583552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:15:46.308] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024144921728219376:9:0) after 1m0s
[01:16:46.337] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024144921728219376:9:0)
[01:16:46.337] Timer tick, asking Buildbucket for the build status
[01:16:46.470] Build 8858714859371583552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:16:46.470] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024144921728219376:10:0) after 1m0s
[01:17:46.519] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024144921728219376:10:0)
[01:17:46.519] Timer tick, asking Buildbucket for the build status
[01:17:46.844] Build 8858714859371583552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:17:46.844] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024144921728219376:11:0) after 1m0s
[01:18:46.887] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024144921728219376:11:0)
[01:18:46.887] Timer tick, asking Buildbucket for the build status
[01:18:46.980] Build 8858714859371583552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:18:46.980] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024144921728219376:12:0) after 1m0s
[01:19:46.970] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024144921728219376:12:0)
[01:19:46.970] Timer tick, asking Buildbucket for the build status
[01:19:47.093] Build 8858714859371583552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:19:47.093] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024144921728219376:13:0) after 1m0s
[01:20:47.203] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024144921728219376:13:0)
[01:20:47.203] Timer tick, asking Buildbucket for the build status
[01:20:47.322] Build 8858714859371583552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:20:47.322] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024144921728219376:14:0) after 1m0s
[01:21:47.343] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024144921728219376:14:0)
[01:21:47.343] Timer tick, asking Buildbucket for the build status
[01:21:47.434] Build 8858714859371583552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:21:47.434] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024144921728219376:15:0) after 1m0s
[01:22:47.530] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024144921728219376:15:0)
[01:22:47.530] Timer tick, asking Buildbucket for the build status
[01:22:47.606] Build 8858714859371583552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:22:47.606] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024144921728219376:16:0) after 1m0s
[01:23:47.680] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024144921728219376:16:0)
[01:23:47.680] Timer tick, asking Buildbucket for the build status
[01:23:47.752] Build 8858714859371583552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:23:47.752] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024144921728219376:17:0) after 1m0s
[01:24:47.822] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024144921728219376:17:0)
[01:24:47.822] Timer tick, asking Buildbucket for the build status
[01:24:47.939] Build 8858714859371583552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:24:47.939] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024144921728219376:18:0) after 1m0s
[01:25:47.983] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024144921728219376:18:0)
[01:25:47.983] Timer tick, asking Buildbucket for the build status
[01:25:48.103] Build 8858714859371583552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:25:48.103] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024144921728219376:19:0) after 1m0s
[01:26:48.104] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024144921728219376:19:0)
[01:26:48.104] Timer tick, asking Buildbucket for the build status
[01:26:48.176] Build 8858714859371583552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:26:48.176] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024144921728219376:20:0) after 1m0s
[01:27:48.221] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024144921728219376:20:0)
[01:27:48.221] Timer tick, asking Buildbucket for the build status
[01:27:48.301] Build 8858714859371583552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:27:48.301] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024144921728219376:21:0) after 1m0s
[01:28:48.304] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024144921728219376:21:0)
[01:28:48.304] Timer tick, asking Buildbucket for the build status
[01:28:48.357] Build 8858714859371583552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:28:48.357] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024144921728219376:22:0) after 1m0s
[01:29:48.400] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024144921728219376:22:0)
[01:29:48.400] Timer tick, asking Buildbucket for the build status
[01:29:48.511] Build 8858714859371583552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:29:48.511] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024144921728219376:23:0) after 1m0s
[01:30:48.604] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024144921728219376:23:0)
[01:30:48.604] Timer tick, asking Buildbucket for the build status
[01:30:48.679] Build 8858714859371583552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:30:48.679] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024144921728219376:24:0) after 1m0s
[01:31:48.763] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024144921728219376:24:0)
[01:31:48.763] Timer tick, asking Buildbucket for the build status
[01:31:48.821] Build 8858714859371583552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:31:48.822] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024144921728219376:25:0) after 1m0s
[01:32:48.858] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024144921728219376:25:0)
[01:32:48.858] Timer tick, asking Buildbucket for the build status
[01:32:48.930] Build 8858714859371583552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:32:48.930] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024144921728219376:26:0) after 1m0s
[01:33:49.022] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024144921728219376:26:0)
[01:33:49.022] Timer tick, asking Buildbucket for the build status
[01:33:49.091] Build 8858714859371583552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:33:49.091] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024144921728219376:27:0) after 1m0s
[01:34:49.097] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024144921728219376:27:0)
[01:34:49.097] Timer tick, asking Buildbucket for the build status
[01:34:49.221] Build 8858714859371583552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:34:49.221] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024144921728219376:28:0) after 1m0s
[01:35:49.273] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024144921728219376:28:0)
[01:35:49.273] Timer tick, asking Buildbucket for the build status
[01:35:49.331] Build 8858714859371583552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:35:49.331] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024144921728219376:29:0) after 1m0s
[01:36:49.415] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024144921728219376:29:0)
[01:36:49.415] Timer tick, asking Buildbucket for the build status
[01:36:49.509] Build 8858714859371583552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:36:49.509] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024144921728219376:30:0) after 1m0s
[01:37:49.529] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024144921728219376:30:0)
[01:37:49.529] Timer tick, asking Buildbucket for the build status
[01:37:49.591] Build 8858714859371583552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:37:49.591] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024144921728219376:31:0) after 1m0s
[01:38:49.701] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024144921728219376:31:0)
[01:38:49.701] Timer tick, asking Buildbucket for the build status
[01:38:50.097] Build 8858714859371583552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:38:50.098] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024144921728219376:32:0) after 1m0s
[01:39:50.399] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024144921728219376:32:0)
[01:39:50.399] Timer tick, asking Buildbucket for the build status
[01:39:50.626] Build 8858714859371583552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:39:50.626] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024144921728219376:33:0) after 1m0s
[01:40:50.790] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024144921728219376:33:0)
[01:40:50.790] Timer tick, asking Buildbucket for the build status
[01:40:50.858] Build 8858714859371583552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:40:50.858] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024144921728219376:34:0) after 1m0s
[01:41:50.936] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024144921728219376:34:0)
[01:41:50.936] Timer tick, asking Buildbucket for the build status
[01:41:51.032] Build 8858714859371583552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:41:51.032] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024144921728219376:35:0) after 1m0s
[01:42:51.100] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024144921728219376:35:0)
[01:42:51.100] Timer tick, asking Buildbucket for the build status
[01:42:51.167] Build 8858714859371583552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:42:51.167] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024144921728219376:36:0) after 1m0s
[01:43:51.273] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024144921728219376:36:0)
[01:43:51.273] Timer tick, asking Buildbucket for the build status
[01:43:51.797] Build 8858714859371583552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:43:51.797] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024144921728219376:37:0) after 1m0s
[01:44:51.847] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024144921728219376:37:0)
[01:44:51.847] Timer tick, asking Buildbucket for the build status
[01:44:52.174] Build 8858714859371583552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:44:52.174] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024144921728219376:38:0) after 1m0s
[01:45:52.157] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024144921728219376:38:0)
[01:45:52.157] Timer tick, asking Buildbucket for the build status
[01:45:52.399] Build 8858714859371583552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:45:52.399] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024144921728219376:39:0) after 1m0s
[01:46:52.435] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024144921728219376:39:0)
[01:46:52.435] Timer tick, asking Buildbucket for the build status
[01:46:52.506] Build 8858714859371583552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:46:52.506] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024144921728219376:40:0) after 1m0s
[01:47:52.589] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024144921728219376:40:0)
[01:47:52.589] Timer tick, asking Buildbucket for the build status
[01:47:52.647] Build 8858714859371583552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:47:52.647] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024144921728219376:41:0) after 1m0s
[01:48:52.668] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024144921728219376:41:0)
[01:48:52.668] Timer tick, asking Buildbucket for the build status
[01:48:52.750] Build 8858714859371583552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:48:52.750] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024144921728219376:42:0) after 1m0s
[01:49:52.809] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024144921728219376:42:0)
[01:49:52.809] Timer tick, asking Buildbucket for the build status
[01:49:52.918] Build 8858714859371583552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:49:52.918] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024144921728219376:43:0) after 1m0s
[01:50:52.980] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024144921728219376:43:0)
[01:50:52.980] Timer tick, asking Buildbucket for the build status
[01:50:53.145] Build 8858714859371583552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:50:53.145] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024144921728219376:44:0) after 1m0s
[01:51:53.309] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024144921728219376:44:0)
[01:51:53.309] Timer tick, asking Buildbucket for the build status
[01:51:55.228] Build 8858714859371583552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:51:55.228] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024144921728219376:45:0) after 1m0s
[01:52:55.251] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024144921728219376:45:0)
[01:52:55.251] Timer tick, asking Buildbucket for the build status
[01:52:55.696] Build 8858714859371583552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:52:55.696] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024144921728219376:46:0) after 1m0s
[01:53:55.773] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024144921728219376:46:0)
[01:53:55.773] Timer tick, asking Buildbucket for the build status
[01:53:56.131] Build 8858714859371583552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:53:56.131] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024144921728219376:47:0) after 1m0s
[01:54:56.156] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024144921728219376:47:0)
[01:54:56.156] Timer tick, asking Buildbucket for the build status
[01:54:56.324] Build 8858714859371583552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:54:56.324] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024144921728219376:48:0) after 1m0s
[01:55:56.346] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024144921728219376:48:0)
[01:55:56.347] Timer tick, asking Buildbucket for the build status
[01:55:56.464] Build 8858714859371583552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:55:56.464] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024144921728219376:49:0) after 1m0s
[01:56:56.535] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024144921728219376:49:0)
[01:56:56.535] Timer tick, asking Buildbucket for the build status
[01:56:56.679] Build 8858714859371583552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:56:56.679] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024144921728219376:50:0) after 1m0s
[01:57:56.750] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024144921728219376:50:0)
[01:57:56.750] Timer tick, asking Buildbucket for the build status
[01:57:56.864] Build 8858714859371583552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:57:56.864] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024144921728219376:51:0) after 1m0s
[01:58:56.913] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024144921728219376:51:0)
[01:58:56.913] Timer tick, asking Buildbucket for the build status
[01:58:57.036] Build 8858714859371583552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:58:57.036] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024144921728219376:52:0) after 1m0s
[01:59:57.117] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024144921728219376:52:0)
[01:59:57.117] Timer tick, asking Buildbucket for the build status
[01:59:57.238] Build 8858714859371583552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:59:57.238] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024144921728219376:53:0) after 1m0s
[02:00:57.281] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024144921728219376:53:0)
[02:00:57.281] Timer tick, asking Buildbucket for the build status
[02:00:58.554] Build 8858714859371583552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:00:58.555] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024144921728219376:54:0) after 1m0s
[02:01:58.641] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024144921728219376:54:0)
[02:01:58.641] Timer tick, asking Buildbucket for the build status
[02:01:58.769] Build 8858714859371583552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:01:58.769] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024144921728219376:55:0) after 1m0s
[02:02:58.922] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024144921728219376:55:0)
[02:02:58.922] Timer tick, asking Buildbucket for the build status
[02:02:59.084] Build 8858714859371583552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:02:59.084] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024144921728219376:56:0) after 1m0s
[02:03:59.236] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024144921728219376:56:0)
[02:03:59.236] Timer tick, asking Buildbucket for the build status
[02:04:00.184] Build 8858714859371583552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:04:00.184] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024144921728219376:57:0) after 1m0s
[02:05:00.307] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024144921728219376:57:0)
[02:05:00.307] Timer tick, asking Buildbucket for the build status
[02:05:00.968] Build 8858714859371583552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:05:00.968] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024144921728219376:58:0) after 1m0s
[02:06:01.329] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024144921728219376:58:0)
[02:06:01.329] Timer tick, asking Buildbucket for the build status
[02:06:01.504] Build 8858714859371583552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:06:01.504] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024144921728219376:59:0) after 1m0s
[02:07:01.752] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024144921728219376:59:0)
[02:07:01.752] Timer tick, asking Buildbucket for the build status
[02:07:01.895] Build 8858714859371583552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:07:01.895] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024144921728219376:60:0) after 1m0s
[02:08:01.898] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024144921728219376:60:0)
[02:08:01.898] Timer tick, asking Buildbucket for the build status
[02:08:02.354] Build 8858714859371583552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:08:02.354] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9024144921728219376:61:0) after 1m0s
[02:08:33.414] Received PubSub notification, asking Buildbucket for the build status
[02:08:33.486] Build 8858714859371583552: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[02:08:33.486] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1610071712644314",
"created_by": "project:chromium",
"created_ts": "1610068184458386",
"id": "8858714859371583552",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"7187698b6505ad0787d26f6c4273219f8dc69089\"}}",
"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\": \"7187698b6505ad0787d26f6c4273219f8dc69089\", \"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-59-o8p1\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-rel\", \"buildnumber\": 44427, \"got_angle_revision\": \"a7158eb524ddc3015c54340d3c0593d6641d41ad\", \"got_dawn_revision\": \"00fcab636a055c78036ee20bb1a89339b0a28bad\", \"got_nacl_revision\": \"299d4780ec86121ff2a5368ed9435ade0e356e5e\", \"got_revision\": \"7187698b6505ad0787d26f6c4273219f8dc69089\", \"got_revision_cp\": \"refs/heads/master@{#841292}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"937395c1ed5d20af5a5fd999d7e2deddf1b78fe3\", \"got_v8_revision\": \"d87c3006482190728da53f87875b2a528dfb7ef3\", \"got_v8_revision_cp\": \"refs/heads/8.9.217@{#1}\", \"got_webrtc_revision\": \"5e4ae54dec3d04723c47cc6f52499fe3e0a484bc\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32921}\", \"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\": \"7187698b6505ad0787d26f6c4273219f8dc69089\", \"swarm_hashes_refs/heads/master(at){#841292}_without_patch\": {\"absl_hardening_tests\": \"b33794d6a2828c8d85a597f3f6fbf5e7e1f94360\", \"accessibility_unittests\": \"7eecae09e47a48bc882228200dd0c79cf153cfa3\", \"angle_unittests\": \"03691ad236ba300284a05a072e83d3b170a65df9\", \"app_list_unittests\": \"1686454959630337797e88b329dd88daf5774525\", \"app_shell_unittests\": \"94ebbebacb559a22aa9449f0423871f2b1f74395\", \"ash_unittests\": \"df6a01d9fd08d8ee3d51a913b42660356dcbac4f\", \"aura_unittests\": \"90a199d8162574c5856596d0c8dcf366bc1fcb7e\", \"base_unittests\": \"dd4fc7570ef7dd3b3f3318ad119111f546b48d28\", \"base_util_unittests\": \"df88013b2bff400404b3d8b930564ecae765ad34\", \"blink_common_unittests\": \"9d4ba8c93ea32d97b565450ea163f6f1c9a23a2f\", \"blink_fuzzer_unittests\": \"d707477993f135f2ffa35b4f0cb8d6afcc61b26f\", \"blink_heap_unittests\": \"742790fda0fbed702bc2355adfd82ea81a46fd56\", \"blink_platform_unittests\": \"67155a53f3f23788a4a9e63bca17f23036a728bd\", \"blink_unittests\": \"43ca454304810cac0bee7c91737a8f4ce70a93d4\", \"boringssl_crypto_tests\": \"2dc73f39e69244d804024dc68754b9feaf1b8faa\", \"boringssl_ssl_tests\": \"2dfae3e3a6d03edaa124d8ae99f80623709e0734\", \"browser_tests\": \"f1d6e3e957dca3043d8efdf9489a3535a886936d\", \"capture_unittests\": \"573a69bf51518dc965b94dc8f3b50cb1b7910826\", \"cast_unittests\": \"98d21b565ecb59313bcc3531b764260b485503f6\", \"cc_unittests\": \"722b32ac847709a5c50fe00c6820400b37832fe0\", \"chrome_app_unittests\": \"a091393ece9c9f83be6fff30b86026bb74c0b1f1\", \"chromedriver_unittests\": \"14f3535d7236444bc015734f6557668b3f062f97\", \"chromeos_components_unittests\": \"aff683b7b69da69001c3a5c101f8edb02b643257\", \"chromeos_unittests\": \"33df7d0aa715ca1d32c686eb0bd62fbd84fa33fc\", \"components_browsertests\": \"611d1179fe04c7ccd91de07fb56f594991cd510e\", \"components_unittests\": \"209f563ae3fff6ca9a3888105040767171e0d6f0\", \"compositor_unittests\": \"b9efdd861702d1a4026060e918970644adab5f81\", \"content_browsertests\": \"712e765291c0bf6c5384240237c4df0bddaf11d1\", \"content_unittests\": \"4530f3508431c1876f58ff93b69e0f554ae211ae\", \"crashpad_tests\": \"1cb41b0aa60850d62f40d876b8a9c9782a9d84ee\", \"crypto_unittests\": \"ab3d0f863453106d02e26986b70734a4402584a1\", \"dbus_unittests\": \"6fab2d738fcbd8c41f82a785e29063debc5d8ad0\", \"device_unittests\": \"32d170c9df073836bdf1015ed259414482e461dd\", \"display_unittests\": \"47015897c45279ce1b007cf2ba8251ec8233bb71\", \"events_unittests\": \"df9da98a941a9d32c130a2a4b4eded2263fc3119\", \"exo_unittests\": \"eb2dd0291adb2de0a06221039943a56772f9ffa9\", \"extensions_browsertests\": \"0cb1a6124e43eb6d293b4228f6ac0f7730ee3b31\", \"extensions_unittests\": \"29b983153f13251315da5a9b04c35bc0809369e5\", \"filesystem_service_unittests\": \"3ce92343331118c0903fcd82d4295629385cf26e\", \"gcm_unit_tests\": \"0aa9d1ba84c80e4a1f56f7039b8a866585667490\", \"gfx_unittests\": \"0eb65e64b04a20cfa6454619884dbdca2f05a8f9\", \"gin_unittests\": \"37c2c5fb854a556b45940da6c80fd3707583f122\", \"gl_unittests_ozone\": \"4d0aabc0342373505c1b041f7da36af7347a5cdb\", \"google_apis_unittests\": \"009e732bb2fde0e35341493d8c11d0c2b524c6d5\", \"gpu_unittests\": \"3fe2f2eaeeffa9f51ad0040427333bc62b357add\", \"gwp_asan_unittests\": \"a8300fe5a53946aa12ca3e4af6eccbc75104d5b7\", \"interactive_ui_tests\": \"6a0cd73116f9aa775ebe7297aa3e11660dad8c04\", \"ipc_tests\": \"385f77bd0248146d5b414c275868774187436a02\", \"jingle_unittests\": \"6a045f9bb93dbf8079de219e41b999a38a6decdc\", \"keyboard_unittests\": \"978787fbf73e7a4d4ca337ab57fa55fb10260c1e\", \"latency_unittests\": \"48d1bd3b0072a1123fba6c4c1200174b0636b03c\", \"libjingle_xmpp_unittests\": \"5ca9e7ea17f92cb49a305be58a6106fca2c8fc0f\", \"liburlpattern_unittests\": \"1923fcc6cc1ee95d444f6af943f5100ed98e12c9\", \"media_blink_unittests\": \"e1f2c1eee81decba6d2f9e959b908ebaae537786\", \"media_unittests\": \"4bee5332299c51f8a147a064cf7d5e1d1cce1e3f\", \"message_center_unittests\": \"2272b3f31e363e068c9f06068cff151112c3bd93\", \"midi_unittests\": \"d9fd37737bded97cb85ef5768dbb991b947278e4\", \"mojo_core_unittests\": \"5885034feccdff7d21ddc545d0495260237de7ec\", \"mojo_unittests\": \"e4652020604faf3b925062c9a2f61086c260f2a7\", \"nacl_helper_nonsfi_unittests\": \"e99da378a675cbfa9e1fa6dfe70e163498ae9ed1\", \"nacl_loader_unittests\": \"c4b57acc61e1e99b92d1587b4880ba698539db50\", \"native_theme_unittests\": \"0e80eb413c4bf65dc15346b5c47359b2e80d5f8c\", \"net_unittests\": \"aed66bea1bad4c32f6fddb37df069e3ee593b26e\", \"ozone_gl_unittests\": \"295b51485f2cebf53c79c69a4c74b9400eceda31\", \"ozone_unittests\": \"95a2949e2e0077b66eaad8adfd642d31b194b1f2\", \"ozone_x11_unittests\": \"5f4bf823d75632b11a3ab8f217981ff1abef3636\", \"pdf_unittests\": \"88d836a3a4060b00b0e8b962cd03d378e3653ac7\", \"perfetto_unittests\": \"7bb32889457a41052e818c60965e794902be5077\", \"ppapi_unittests\": \"c17f5a14ff8435b336dd47a9c9938b5692888cf1\", \"printing_unittests\": \"fae956b2e5e5b68d4c1cf1634bc0b330f9249a24\", \"remoting_unittests\": \"74da0ce96035a2b9c8a76e1bb72d3727d938b0b2\", \"sandbox_linux_unittests\": \"d79d5666a922b69f61edfe9170a854cccded24cd\", \"service_manager_unittests\": \"9e0af69d00ef2b424196f98551244683bdd0d2ff\", \"services_unittests\": \"ad7f712014a4128a0319e96dcce5b10f80b5c429\", \"shell_dialogs_unittests\": \"b17c7dd3f344fbe7421b032693137c494d7b84f3\", \"shell_encryption_unittests\": \"a8a6d636ede040f1a72cef3dbb94e8de1b804e45\", \"skia_unittests\": \"4478c83ecb06477bf4fa222962167744f356d6a0\", \"snapshot_unittests\": \"b00ae9ae333d69d6ec63d0282938e38b38e09110\", \"sql_unittests\": \"e2edda2fb88a6a38b73c056310650f6178dc6464\", \"storage_unittests\": \"d29306de043d74508405a7fb51593e4910513c44\", \"sync_integration_tests\": \"5c37bc6388769bd76b45494fe60867aa46ee23a8\", \"traffic_annotation_auditor_unittests\": \"696893cd3db96b0b3e7fc97b61adf218d80de35c\", \"ui_base_unittests\": \"912feb3e1247a2714e89d1be907814d2f28c22c0\", \"ui_chromeos_unittests\": \"5416704e978f7ab33eaf3758a939936f2f42df47\", \"ui_touch_selection_unittests\": \"1d3ee5374754ba50f4b780a60a7604702494becc\", \"unit_tests\": \"22158e23ce4cb1554750804a34664ad3aa9b4de7\", \"url_unittests\": \"4dca5069b1964aef01c150278282c20e40c7c354\", \"usage_time_limit_unittests\": \"801b1fda10450c3a94ff0ff7a8951ae8c8be3906\", \"views_unittests\": \"618de97b53dc450102848061ef1e223d7a14c778\", \"viz_unittests\": \"b5af69b89a79129d5c80c38c4d07a231178885cc\", \"wayland_client_perftests\": \"6ecb280db501d5286dac575c111d9f7c13c0bbfd\", \"wm_unittests\": \"db09f3bded539c97700c878267ab3a80858477e7\", \"wtf_unittests\": \"49b4039abdd81f64e62628775d10f0523763e2be\", \"zlib_unittests\": \"3ff7a91253d808902705c6ae6ef5eb808a5a81be\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_78f6c1c6e04ce29f801b19cce5c19f544cdd908f13b658e8d1bab4fa24e10881_v2\", \"builder_94fa3119a28f4651cdd4fc5b981a64c69b26c151212072333439362290628993_v2\", \"builder_e5bbff72983945f6847543519ce1052420cedd06fb8495f4080bfa2e6bcfd13f_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-59-o8p1\"], \"image\": [\"chrome-xenial-20122700-a13233ce10d\"], \"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": "1610068264060466",
"status": "COMPLETED",
"status_changed_ts": "1610071713076542",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/44427",
"builder:linux-chromeos-rel",
"buildset:commit/git/7187698b6505ad0787d26f6c4273219f8dc69089",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/7187698b6505ad0787d26f6c4273219f8dc69089",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9024144921728219376",
"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/8858714859371583552/+/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:50f85c58db008310",
"user_agent:luci-scheduler"
],
"updated_ts": "1610071713082441",
"url": "https://ci.chromium.org/b/8858714859371583552",
"utcnow_ts": "1610071713476550"
}
}
[02:08:33.486] Invocation finished in 58m50.659553587s with status SUCCEEDED