[16:21:42.861] New invocation is queued and will start shortly
[16:21:43.978] Starting the invocation (attempt 1)
[16:21:44.016] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[16:21:44.016] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[16:21:44.016] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9022819186104230000",
"parameters_json": "{\"builder_name\":\"linux-chromeos-dbg\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"e33136ac0d01da02a268398d6e5feb42db259ddd\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-dbg",
"scheduler_invocation_id:9022819186104230000",
"scheduler_job_id:chromium/linux-chromeos-dbg",
"user_agent:luci-scheduler",
"buildset:commit/git/e33136ac0d01da02a268398d6e5feb42db259ddd",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/e33136ac0d01da02a268398d6e5feb42db259ddd",
"gitiles_ref:refs/heads/master"
]
}
[16:21:44.641] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1611332504102602",
"id": "8857389124136707024",
"parameters_json": "{\"builder_name\": \"linux-chromeos-dbg\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"e33136ac0d01da02a268398d6e5feb42db259ddd\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1611332504513205",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-dbg/22304",
"builder:linux-chromeos-dbg",
"buildset:commit/git/e33136ac0d01da02a268398d6e5feb42db259ddd",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/e33136ac0d01da02a268398d6e5feb42db259ddd",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022819186104230000",
"scheduler_job_id:chromium/linux-chromeos-dbg",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8857389124136707024/+/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": "1611332504513340",
"url": "https://ci.chromium.org/b/8857389124136707024",
"utcnow_ts": "1611332504624491"
}
}
[16:21:44.641] Task URL: https://ci.chromium.org/b/8857389124136707024
[16:21:44.641] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:2:0) after 1m0s
[16:22:44.696] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:2:0)
[16:22:44.696] Timer tick, asking Buildbucket for the build status
[16:22:44.910] Build 8857389124136707024: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:22:44.910] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:3:0) after 1m0s
[16:23:18.850] Received PubSub notification, asking Buildbucket for the build status
[16:23:19.205] Build 8857389124136707024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:23:45.039] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:3:0)
[16:23:45.039] Timer tick, asking Buildbucket for the build status
[16:23:45.144] Build 8857389124136707024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:23:45.144] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:5:0) after 1m0s
[16:24:45.877] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:5:0)
[16:24:45.877] Timer tick, asking Buildbucket for the build status
[16:24:46.229] Build 8857389124136707024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:24:46.229] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:6:0) after 1m0s
[16:25:46.336] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:6:0)
[16:25:46.336] Timer tick, asking Buildbucket for the build status
[16:25:46.614] Build 8857389124136707024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:25:46.614] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:7:0) after 1m0s
[16:26:46.801] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:7:0)
[16:26:46.801] Timer tick, asking Buildbucket for the build status
[16:26:46.958] Build 8857389124136707024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:26:46.958] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:8:0) after 1m0s
[16:27:47.174] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:8:0)
[16:27:47.174] Timer tick, asking Buildbucket for the build status
[16:27:47.304] Build 8857389124136707024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:27:47.304] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:9:0) after 1m0s
[16:28:47.547] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:9:0)
[16:28:47.547] Timer tick, asking Buildbucket for the build status
[16:28:48.056] Build 8857389124136707024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:28:48.056] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:10:0) after 1m0s
[16:29:48.072] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:10:0)
[16:29:48.072] Timer tick, asking Buildbucket for the build status
[16:29:48.188] Build 8857389124136707024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:29:48.188] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:11:0) after 1m0s
[16:30:48.386] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:11:0)
[16:30:48.386] Timer tick, asking Buildbucket for the build status
[16:30:49.298] Build 8857389124136707024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:30:49.298] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:12:0) after 1m0s
[16:31:49.336] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:12:0)
[16:31:49.336] Timer tick, asking Buildbucket for the build status
[16:31:49.444] Build 8857389124136707024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:31:49.444] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:13:0) after 1m0s
[16:32:49.582] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:13:0)
[16:32:49.582] Timer tick, asking Buildbucket for the build status
[16:32:49.656] Build 8857389124136707024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:32:49.656] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:14:0) after 1m0s
[16:33:49.837] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:14:0)
[16:33:49.837] Timer tick, asking Buildbucket for the build status
[16:33:50.268] Build 8857389124136707024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:33:50.268] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:15:0) after 1m0s
[16:34:50.339] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:15:0)
[16:34:50.339] Timer tick, asking Buildbucket for the build status
[16:34:50.481] Build 8857389124136707024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:34:50.481] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:16:0) after 1m0s
[16:35:50.525] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:16:0)
[16:35:50.525] Timer tick, asking Buildbucket for the build status
[16:35:50.801] Build 8857389124136707024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:35:50.801] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:17:0) after 1m0s
[16:36:50.880] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:17:0)
[16:36:50.880] Timer tick, asking Buildbucket for the build status
[16:36:51.296] Build 8857389124136707024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:36:51.296] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:18:0) after 1m0s
[16:37:51.385] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:18:0)
[16:37:51.385] Timer tick, asking Buildbucket for the build status
[16:37:51.634] Build 8857389124136707024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:37:51.634] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:19:0) after 1m0s
[16:38:51.686] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:19:0)
[16:38:51.686] Timer tick, asking Buildbucket for the build status
[16:38:51.765] Build 8857389124136707024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:38:51.765] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:20:0) after 1m0s
[16:39:51.863] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:20:0)
[16:39:51.863] Timer tick, asking Buildbucket for the build status
[16:39:52.547] Build 8857389124136707024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:39:52.547] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:21:0) after 1m0s
[16:40:52.565] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:21:0)
[16:40:52.565] Timer tick, asking Buildbucket for the build status
[16:40:52.954] Build 8857389124136707024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:40:52.954] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:22:0) after 1m0s
[16:41:53.021] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:22:0)
[16:41:53.021] Timer tick, asking Buildbucket for the build status
[16:41:53.311] Build 8857389124136707024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:41:53.312] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:23:0) after 1m0s
[16:42:53.335] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:23:0)
[16:42:53.335] Timer tick, asking Buildbucket for the build status
[16:42:53.540] Build 8857389124136707024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:42:53.540] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:24:0) after 1m0s
[16:43:53.860] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:24:0)
[16:43:53.860] Timer tick, asking Buildbucket for the build status
[16:43:54.263] Build 8857389124136707024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:43:54.263] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:25:0) after 1m0s
[16:44:54.303] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:25:0)
[16:44:54.303] Timer tick, asking Buildbucket for the build status
[16:44:54.386] Build 8857389124136707024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:44:54.386] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:26:0) after 1m0s
[16:45:54.503] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:26:0)
[16:45:54.503] Timer tick, asking Buildbucket for the build status
[16:45:55.240] Build 8857389124136707024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:45:55.240] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:27:0) after 1m0s
[16:46:55.402] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:27:0)
[16:46:55.402] Timer tick, asking Buildbucket for the build status
[16:46:55.714] Build 8857389124136707024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:46:55.714] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:28:0) after 1m0s
[16:47:55.830] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:28:0)
[16:47:55.830] Timer tick, asking Buildbucket for the build status
[16:47:55.918] Build 8857389124136707024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:47:55.918] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:29:0) after 1m0s
[16:48:55.984] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:29:0)
[16:48:55.984] Timer tick, asking Buildbucket for the build status
[16:48:56.053] Build 8857389124136707024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:48:56.053] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:30:0) after 1m0s
[16:49:56.177] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:30:0)
[16:49:56.177] Timer tick, asking Buildbucket for the build status
[16:49:56.270] Build 8857389124136707024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:49:56.270] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:31:0) after 1m0s
[16:50:56.355] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:31:0)
[16:50:56.355] Timer tick, asking Buildbucket for the build status
[16:50:56.507] Build 8857389124136707024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:50:56.507] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:32:0) after 1m0s
[16:51:56.546] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:32:0)
[16:51:56.546] Timer tick, asking Buildbucket for the build status
[16:51:58.357] Build 8857389124136707024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:51:58.357] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:33:0) after 1m0s
[16:52:58.364] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:33:0)
[16:52:58.364] Timer tick, asking Buildbucket for the build status
[16:52:58.547] Build 8857389124136707024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:52:58.547] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:34:0) after 1m0s
[16:53:58.594] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:34:0)
[16:53:58.594] Timer tick, asking Buildbucket for the build status
[16:53:58.688] Build 8857389124136707024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:53:58.688] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:35:0) after 1m0s
[16:54:58.694] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:35:0)
[16:54:58.694] Timer tick, asking Buildbucket for the build status
[16:54:58.960] Build 8857389124136707024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:54:58.960] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:36:0) after 1m0s
[16:55:59.056] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:36:0)
[16:55:59.056] Timer tick, asking Buildbucket for the build status
[16:55:59.258] Build 8857389124136707024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:55:59.258] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:37:0) after 1m0s
[16:56:59.313] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:37:0)
[16:56:59.313] Timer tick, asking Buildbucket for the build status
[16:56:59.531] Build 8857389124136707024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:56:59.531] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:38:0) after 1m0s
[16:57:59.567] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:38:0)
[16:57:59.567] Timer tick, asking Buildbucket for the build status
[16:57:59.740] Build 8857389124136707024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:57:59.740] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:39:0) after 1m0s
[16:58:59.865] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:39:0)
[16:58:59.865] Timer tick, asking Buildbucket for the build status
[16:59:00.175] Build 8857389124136707024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:59:00.176] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:40:0) after 1m0s
[17:00:00.199] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:40:0)
[17:00:00.199] Timer tick, asking Buildbucket for the build status
[17:00:00.368] Build 8857389124136707024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:00:00.368] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:41:0) after 1m0s
[17:01:00.642] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:41:0)
[17:01:00.642] Timer tick, asking Buildbucket for the build status
[17:01:01.029] Build 8857389124136707024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:01:01.029] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:42:0) after 1m0s
[17:02:01.195] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:42:0)
[17:02:01.195] Timer tick, asking Buildbucket for the build status
[17:02:01.947] Build 8857389124136707024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:02:01.947] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:43:0) after 1m0s
[17:03:01.938] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:43:0)
[17:03:01.938] Timer tick, asking Buildbucket for the build status
[17:03:02.152] Build 8857389124136707024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:03:02.152] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:44:0) after 1m0s
[17:04:02.185] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:44:0)
[17:04:02.185] Timer tick, asking Buildbucket for the build status
[17:04:02.288] Build 8857389124136707024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:04:02.288] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:45:0) after 1m0s
[17:05:02.321] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:45:0)
[17:05:02.321] Timer tick, asking Buildbucket for the build status
[17:05:02.417] Build 8857389124136707024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:05:02.417] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:46:0) after 1m0s
[17:06:02.523] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:46:0)
[17:06:02.523] Timer tick, asking Buildbucket for the build status
[17:06:03.037] Build 8857389124136707024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:06:03.037] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:47:0) after 1m0s
[17:07:03.064] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:47:0)
[17:07:03.064] Timer tick, asking Buildbucket for the build status
[17:07:04.081] Build 8857389124136707024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:07:04.081] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:48:0) after 1m0s
[17:08:04.132] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:48:0)
[17:08:04.132] Timer tick, asking Buildbucket for the build status
[17:08:04.443] Build 8857389124136707024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:08:04.443] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:49:0) after 1m0s
[17:09:04.494] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:49:0)
[17:09:04.494] Timer tick, asking Buildbucket for the build status
[17:09:04.693] Build 8857389124136707024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:09:04.693] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:50:0) after 1m0s
[17:10:04.767] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:50:0)
[17:10:04.767] Timer tick, asking Buildbucket for the build status
[17:10:05.358] Build 8857389124136707024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:10:05.358] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:51:0) after 1m0s
[17:11:05.429] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:51:0)
[17:11:05.429] Timer tick, asking Buildbucket for the build status
[17:11:05.648] Build 8857389124136707024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:11:05.648] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:52:0) after 1m0s
[17:12:05.702] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:52:0)
[17:12:05.702] Timer tick, asking Buildbucket for the build status
[17:12:06.047] Build 8857389124136707024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:12:06.048] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:53:0) after 1m0s
[17:13:06.108] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:53:0)
[17:13:06.108] Timer tick, asking Buildbucket for the build status
[17:13:06.545] Build 8857389124136707024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:13:06.545] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:54:0) after 1m0s
[17:14:06.587] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:54:0)
[17:14:06.587] Timer tick, asking Buildbucket for the build status
[17:14:06.778] Build 8857389124136707024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:14:06.778] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:55:0) after 1m0s
[17:15:06.855] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:55:0)
[17:15:06.855] Timer tick, asking Buildbucket for the build status
[17:15:06.933] Build 8857389124136707024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:15:06.933] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:56:0) after 1m0s
[17:16:07.019] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:56:0)
[17:16:07.019] Timer tick, asking Buildbucket for the build status
[17:16:07.146] Build 8857389124136707024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:16:07.146] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:57:0) after 1m0s
[17:17:07.250] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:57:0)
[17:17:07.250] Timer tick, asking Buildbucket for the build status
[17:17:07.934] Build 8857389124136707024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:17:07.934] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:58:0) after 1m0s
[17:18:07.972] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:58:0)
[17:18:07.972] Timer tick, asking Buildbucket for the build status
[17:18:08.381] Build 8857389124136707024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:18:08.381] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:59:0) after 1m0s
[17:19:08.441] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:59:0)
[17:19:08.441] Timer tick, asking Buildbucket for the build status
[17:19:08.546] Build 8857389124136707024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:19:08.546] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:60:0) after 1m0s
[17:20:08.740] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:60:0)
[17:20:08.740] Timer tick, asking Buildbucket for the build status
[17:20:09.995] Build 8857389124136707024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:20:09.995] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:61:0) after 1m0s
[17:21:10.030] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:61:0)
[17:21:10.030] Timer tick, asking Buildbucket for the build status
[17:21:10.782] Build 8857389124136707024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:21:10.782] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:62:0) after 1m0s
[17:22:10.881] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:62:0)
[17:22:10.881] Timer tick, asking Buildbucket for the build status
[17:22:11.134] Build 8857389124136707024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:22:11.134] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:63:0) after 1m0s
[17:23:11.338] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:63:0)
[17:23:11.338] Timer tick, asking Buildbucket for the build status
[17:23:11.422] Build 8857389124136707024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:23:11.422] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:64:0) after 1m0s
[17:24:11.588] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:64:0)
[17:24:11.588] Timer tick, asking Buildbucket for the build status
[17:24:12.028] Build 8857389124136707024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:24:12.028] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:65:0) after 1m0s
[17:25:12.115] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:65:0)
[17:25:12.115] Timer tick, asking Buildbucket for the build status
[17:25:12.311] Build 8857389124136707024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:25:12.311] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:66:0) after 1m0s
[17:26:12.396] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:66:0)
[17:26:12.396] Timer tick, asking Buildbucket for the build status
[17:26:12.496] Build 8857389124136707024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:26:12.496] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:67:0) after 1m0s
[17:27:12.510] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:67:0)
[17:27:12.510] Timer tick, asking Buildbucket for the build status
[17:27:12.835] Build 8857389124136707024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:27:12.835] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:68:0) after 1m0s
[17:28:12.881] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:68:0)
[17:28:12.881] Timer tick, asking Buildbucket for the build status
[17:28:12.965] Build 8857389124136707024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:28:12.965] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:69:0) after 1m0s
[17:29:13.247] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:69:0)
[17:29:13.247] Timer tick, asking Buildbucket for the build status
[17:29:13.590] Build 8857389124136707024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:29:13.590] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:70:0) after 1m0s
[17:30:13.700] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:70:0)
[17:30:13.700] Timer tick, asking Buildbucket for the build status
[17:30:14.923] Build 8857389124136707024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:30:14.923] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:71:0) after 1m0s
[17:31:15.003] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:71:0)
[17:31:15.003] Timer tick, asking Buildbucket for the build status
[17:31:15.077] Build 8857389124136707024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:31:15.077] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:72:0) after 1m0s
[17:32:15.247] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:72:0)
[17:32:15.247] Timer tick, asking Buildbucket for the build status
[17:32:15.954] Build 8857389124136707024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:32:15.954] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:73:0) after 1m0s
[17:33:16.130] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:73:0)
[17:33:16.130] Timer tick, asking Buildbucket for the build status
[17:33:16.217] Build 8857389124136707024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:33:16.217] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:74:0) after 1m0s
[17:34:16.323] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:74:0)
[17:34:16.323] Timer tick, asking Buildbucket for the build status
[17:34:16.463] Build 8857389124136707024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:34:16.463] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:75:0) after 1m0s
[17:35:16.493] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:75:0)
[17:35:16.493] Timer tick, asking Buildbucket for the build status
[17:35:16.652] Build 8857389124136707024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:35:16.652] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:76:0) after 1m0s
[17:36:16.740] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:76:0)
[17:36:16.740] Timer tick, asking Buildbucket for the build status
[17:36:16.821] Build 8857389124136707024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:36:16.821] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:77:0) after 1m0s
[17:37:16.874] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:77:0)
[17:37:16.874] Timer tick, asking Buildbucket for the build status
[17:37:17.043] Build 8857389124136707024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:37:17.043] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:78:0) after 1m0s
[17:38:17.061] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:78:0)
[17:38:17.061] Timer tick, asking Buildbucket for the build status
[17:38:17.673] Build 8857389124136707024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:38:17.673] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:79:0) after 1m0s
[17:39:17.797] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:79:0)
[17:39:17.797] Timer tick, asking Buildbucket for the build status
[17:39:18.161] Build 8857389124136707024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:39:18.161] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:80:0) after 1m0s
[17:40:18.254] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:80:0)
[17:40:18.254] Timer tick, asking Buildbucket for the build status
[17:40:18.330] Build 8857389124136707024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:40:18.330] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:81:0) after 1m0s
[17:41:18.391] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:81:0)
[17:41:18.391] Timer tick, asking Buildbucket for the build status
[17:41:18.459] Build 8857389124136707024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:41:18.459] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:82:0) after 1m0s
[17:42:18.535] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:82:0)
[17:42:18.535] Timer tick, asking Buildbucket for the build status
[17:42:18.800] Build 8857389124136707024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:42:18.800] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:83:0) after 1m0s
[17:43:18.828] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:83:0)
[17:43:18.828] Timer tick, asking Buildbucket for the build status
[17:43:19.370] Build 8857389124136707024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:43:19.370] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:84:0) after 1m0s
[17:44:19.414] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:84:0)
[17:44:19.414] Timer tick, asking Buildbucket for the build status
[17:44:19.499] Build 8857389124136707024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:44:19.499] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:85:0) after 1m0s
[17:45:19.507] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:85:0)
[17:45:19.507] Timer tick, asking Buildbucket for the build status
[17:45:19.694] Build 8857389124136707024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:45:19.694] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:86:0) after 1m0s
[17:46:19.721] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:86:0)
[17:46:19.722] Timer tick, asking Buildbucket for the build status
[17:46:19.930] Build 8857389124136707024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:46:19.930] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:87:0) after 1m0s
[17:47:20.411] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:87:0)
[17:47:20.411] Timer tick, asking Buildbucket for the build status
[17:47:20.509] Build 8857389124136707024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:47:20.509] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:88:0) after 1m0s
[17:48:20.536] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:88:0)
[17:48:20.536] Timer tick, asking Buildbucket for the build status
[17:48:20.631] Build 8857389124136707024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:48:20.631] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:89:0) after 1m0s
[17:49:20.647] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:89:0)
[17:49:20.647] Timer tick, asking Buildbucket for the build status
[17:49:20.718] Build 8857389124136707024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:49:20.718] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:90:0) after 1m0s
[17:50:20.773] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:90:0)
[17:50:20.773] Timer tick, asking Buildbucket for the build status
[17:50:20.901] Build 8857389124136707024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:50:20.901] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:91:0) after 1m0s
[17:51:20.954] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:91:0)
[17:51:20.954] Timer tick, asking Buildbucket for the build status
[17:51:21.048] Build 8857389124136707024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:51:21.048] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:92:0) after 1m0s
[17:52:21.115] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:92:0)
[17:52:21.115] Timer tick, asking Buildbucket for the build status
[17:52:21.453] Build 8857389124136707024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:52:21.453] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:93:0) after 1m0s
[17:53:21.517] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:93:0)
[17:53:21.517] Timer tick, asking Buildbucket for the build status
[17:53:21.851] Build 8857389124136707024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:53:21.851] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:94:0) after 1m0s
[17:54:21.878] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:94:0)
[17:54:21.878] Timer tick, asking Buildbucket for the build status
[17:54:21.952] Build 8857389124136707024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:54:21.952] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:95:0) after 1m0s
[17:55:22.051] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:95:0)
[17:55:22.051] Timer tick, asking Buildbucket for the build status
[17:55:22.722] Build 8857389124136707024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:55:22.722] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:96:0) after 1m0s
[17:56:22.792] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:96:0)
[17:56:22.792] Timer tick, asking Buildbucket for the build status
[17:56:22.929] Build 8857389124136707024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:56:22.929] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:97:0) after 1m0s
[17:57:22.923] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:97:0)
[17:57:22.923] Timer tick, asking Buildbucket for the build status
[17:57:23.394] Build 8857389124136707024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:57:23.394] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:98:0) after 1m0s
[17:58:23.428] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:98:0)
[17:58:23.428] Timer tick, asking Buildbucket for the build status
[17:58:23.931] Build 8857389124136707024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:58:23.931] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:99:0) after 1m0s
[17:59:24.006] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:99:0)
[17:59:24.006] Timer tick, asking Buildbucket for the build status
[17:59:24.462] Build 8857389124136707024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:59:24.462] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:100:0) after 1m0s
[18:00:24.493] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:100:0)
[18:00:24.493] Timer tick, asking Buildbucket for the build status
[18:00:24.677] Build 8857389124136707024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:00:24.677] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:101:0) after 1m0s
[18:01:24.714] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:101:0)
[18:01:24.714] Timer tick, asking Buildbucket for the build status
[18:01:24.958] Build 8857389124136707024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:01:24.958] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022819186104230000:102:0) after 1m0s
[18:02:16.441] Received PubSub notification, asking Buildbucket for the build status
[18:02:16.580] Build 8857389124136707024: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[18:02:16.580] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1611338535757107",
"created_by": "project:chromium",
"created_ts": "1611332504102602",
"id": "8857389124136707024",
"parameters_json": "{\"builder_name\": \"linux-chromeos-dbg\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"e33136ac0d01da02a268398d6e5feb42db259ddd\"}}",
"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\": \"e33136ac0d01da02a268398d6e5feb42db259ddd\", \"project\": \"chromium/src\"}], \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-chromium-ci-xenial-us-central1-b-8-89-mvey\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-dbg\", \"buildnumber\": 22304, \"got_angle_revision\": \"c827ccbb58b8ceef91628527bad74fc90b135b61\", \"got_dawn_revision\": \"72cd1a5e8953292dd97c4006973850c880d39db8\", \"got_nacl_revision\": \"cb31feffa500401c95c6c44d88b7c358236bca36\", \"got_revision\": \"e33136ac0d01da02a268398d6e5feb42db259ddd\", \"got_revision_cp\": \"refs/heads/master@{#846139}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"1d5174b9dc3be500d63daf227b163b5e86bbc7b5\", \"got_v8_revision\": \"fbab010f750a89cd201b80da97f378dadde58ff4\", \"got_v8_revision_cp\": \"refs/heads/9.0.50@{#1}\", \"got_webrtc_revision\": \"11215febb9500c597c940a40f1b9fe86c939413f\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33056}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mirrored_builders\": [\"tryserver.chromium.chromiumos:linux-chromeos-dbg\", \"tryserver.chromium.chromiumos:linux-chromeos-compile-dbg\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"e33136ac0d01da02a268398d6e5feb42db259ddd\", \"swarm_hashes_refs/heads/master(at){#846139}_without_patch\": {\"absl_hardening_tests\": \"893f6d4f623a64c7f7482fc7b80dc1b5c838d5d5\", \"accessibility_unittests\": \"571999480a31c8501d022235c62e08e68a13e0ca\", \"angle_unittests\": \"b2e8169197f0d6ea203d4379892d438db4005d5c\", \"app_list_unittests\": \"3997421cd632c16e0aaf20ca9b012eebe25678ef\", \"app_shell_unittests\": \"131aac2f4944b01da52a4c57031c15921acaf995\", \"ash_unittests\": \"378b6c5249e0209f2d93c09980c8cd4fd8d9629d\", \"aura_unittests\": \"64a3c400145e5834be05043aee5b895628114486\", \"base_unittests\": \"81002c18840de003478228ce14ebd76b7c155c65\", \"base_util_unittests\": \"b6a0543565e784396a286b0209cfa35c688f85a7\", \"blink_common_unittests\": \"14e5c9722fa9a16e1f21b535205f914b2a281d7e\", \"blink_fuzzer_unittests\": \"8418afb08fd3031b2abfa0907baf3a7580c0830b\", \"blink_heap_unittests\": \"0cde85f9df97afc769fb1a91331f6313e9d0f209\", \"blink_platform_unittests\": \"1931974708a1c54243f3824ed53c80dfa173dd8f\", \"blink_unittests\": \"d9062435d12662c05cfff5d1b8b2e6c22f996751\", \"boringssl_crypto_tests\": \"be23843b67a7a48dc37fc8e8a493809cc0e3e6d9\", \"boringssl_ssl_tests\": \"4a4ec90b497642ce097e5119d60277a0c42652fb\", \"browser_tests\": \"c10a59ad54c10c055e6339a87ef3d799426b2213\", \"capture_unittests\": \"6c455355053ee0d2306790e7ff88b69d41aba267\", \"cast_unittests\": \"96040c6e71610cf62f3e47671c6cb069cbbc39c2\", \"cc_unittests\": \"a1fe8a04613144ec0ebb6d1e229140fc5af1df1c\", \"chrome_app_unittests\": \"0bfa9e3d2ae18b943dd2fc31a6b7c0effe345362\", \"chromedriver_unittests\": \"a711b7dc915be22f654ed4f56d6f7624a74f1231\", \"chromeos_components_unittests\": \"2551e9c0f791079ef5fb23a076c9ccb0b421710e\", \"chromeos_unittests\": \"11113ae4e900a33cc52557f17ebf6c87c845dceb\", \"components_browsertests\": \"a4395bf9eee18a1312f379740446cf8591444820\", \"components_unittests\": \"31cffe3ae1db81bdcb0d24ea67adb8657691f358\", \"compositor_unittests\": \"b134461f677dfdad2d7ed54130ee9cf16c2353ee\", \"content_browsertests\": \"de031019bcedd4903eff0d2089851804314a6039\", \"content_unittests\": \"e2cf40699ece36580e3863d8b8b53b83e03786c9\", \"crashpad_tests\": \"12a04f900081d94a7678e91f6d7f86438e84fbdc\", \"crypto_unittests\": \"8aef006acd7a7fbd2bd9e35aa321e7f9f614434f\", \"dbus_unittests\": \"5520f863007e306350dbea70c84ff557f6329ca4\", \"device_unittests\": \"a00c073175279d6e16485c89e58ba1777bc90ef2\", \"display_unittests\": \"dd0a613f82eccea144dfd448c55ec432bd4d99bb\", \"events_unittests\": \"a455aed10ca5b25badc213c08b5f902785ed7968\", \"exo_unittests\": \"6a183ea5e37951baeba3c162baebfdfc3bf95594\", \"extensions_browsertests\": \"117d6b4a38145771e9b027d78b4b1c8d9d86be36\", \"extensions_unittests\": \"0e20f96cab86c1dfcdc22b35d7e1668f4ba501a6\", \"filesystem_service_unittests\": \"50893d91d93fcdc2610f057929a3d9fcbceecd49\", \"gcm_unit_tests\": \"528e28c9b120093676c176e211aa320022f2c392\", \"gfx_unittests\": \"1494e1961c71d69fee65167e281a2ed1b17eb2cf\", \"gin_unittests\": \"542e27c3f4102c6332a5629d54380ebd24917f1f\", \"gl_unittests_ozone\": \"132132bd28f0fe4eabc7624eeeb63c2288d126a4\", \"google_apis_unittests\": \"7195e5d8e694bc6d6f16a2d5031f2edc6781b9f0\", \"gpu_unittests\": \"30015dcce50f1a22e0f4df6c3c1836a0d82b395a\", \"gwp_asan_unittests\": \"679eacee9b4a5a9f17219b3817ed30aa86870961\", \"interactive_ui_tests\": \"0eb932d1651842fd368ab6105eb49a14e0ba4990\", \"ipc_tests\": \"cce9e5f9e360a8fc5a973d46bf64dcd595049d51\", \"jingle_unittests\": \"4b9c108a00311a092df5ee318873086520200bdb\", \"keyboard_unittests\": \"8bbd65a98ada0b4f234fe79084017a2eae2f4149\", \"latency_unittests\": \"ed78a6e1aeeef87abb57e802fc3e815c5fbfeea1\", \"libjingle_xmpp_unittests\": \"c95e35f46c4f9c17003f6b43f84c3d0ba3be5484\", \"liburlpattern_unittests\": \"3c95cb52b349c006ab7e564e5c6763b98006f165\", \"media_blink_unittests\": \"82e49e077c548465acd58755de68f6dc0e8b2501\", \"media_unittests\": \"b9b58101c731f95518780143f0be0644f9d9a254\", \"message_center_unittests\": \"d18b22fbc3a7dd8812c5f639468b75e07110d5bc\", \"midi_unittests\": \"68e4c9672e82bc604ceb051d814c1a9b562c3b2e\", \"mojo_core_unittests\": \"65c092115341ac5d68877cb822245533305fb344\", \"mojo_unittests\": \"ee74b6c5c1d5ce6cb6a398fc52a77bdc2e45897a\", \"nacl_helper_nonsfi_unittests\": \"b854a75110db91ac9ca395aef0213c1786c25d04\", \"nacl_loader_unittests\": \"b3c959818248e828542440d01e8bea87a618917d\", \"native_theme_unittests\": \"33684297159d2afd2ed0fc638cba0b17c12041ff\", \"net_unittests\": \"0e215be6012070b33c009ada31896a33847e4f3c\", \"ozone_gl_unittests\": \"4438e6bc933aad0f8fd2c47a8c80b0e146ad6dbb\", \"ozone_unittests\": \"c3b8e1802598eac162ff69bdbdc09eb89b57ad16\", \"ozone_x11_unittests\": \"84fdc0033e02d1f8c3123f07fa0eaead03c6bd03\", \"pdf_unittests\": \"4b8cc59f54e94cb1893806ab896da9076455e09a\", \"perfetto_unittests\": \"7df7dae79836f93fe6d17b8d63cf70f3b037e7c7\", \"ppapi_unittests\": \"8b2c47b671a68ebcef0314f6c92366c54b5276bf\", \"printing_unittests\": \"e71c228e91b0c08c1d9193f9e66cc3fca90adf2f\", \"remoting_unittests\": \"442158073c37859d1fa94731a3f99c9007e6b882\", \"sandbox_linux_unittests\": \"fec269f6819987fe0abfad16fbb59b6d40f9d9c3\", \"service_manager_unittests\": \"5de4cfe9a981c6c4a2779e7173d231c36a47089f\", \"services_unittests\": \"47afc5ba540c4bebb752f277cdb38d9173d80cb3\", \"shell_dialogs_unittests\": \"e5caa9efe7bd87dce9e9757f0eda3dbad79ee1dd\", \"shell_encryption_unittests\": \"40fc652578921086555b8794126891bf0149875a\", \"skia_unittests\": \"b0320c54f09399ce04b767e4a0a25c09ab69f5da\", \"snapshot_unittests\": \"ceb3d91e70e3d993c191b8976044191bb9165871\", \"sql_unittests\": \"f210fd501ff6086d941ba22b1379f7b82a2c5bd1\", \"storage_unittests\": \"545f4626661d2f3adefdf43e17e018c450f3d19a\", \"sync_integration_tests\": \"cadd947a2cc84b036fab55a45818b16dd936a297\", \"traffic_annotation_auditor_unittests\": \"7e8d181d7e07a57c36406dc2afeed317e513cef7\", \"ui_base_unittests\": \"7f6e904a20367dea085806c5327fbc3b76d49c65\", \"ui_chromeos_unittests\": \"d5363d91c139ff044dbb15a0497ae01c416f01b1\", \"ui_touch_selection_unittests\": \"63d21eaf968c98615a442066055261a952c166c9\", \"unit_tests\": \"944a549b3d52731734088795ab206eff777258a6\", \"url_unittests\": \"fda7857348e6f27632131ff9a88fdf3cc146dacb\", \"usage_time_limit_unittests\": \"a9d494487ae98d56b8a40f5f7b9a13ef716cd8be\", \"views_unittests\": \"ef0489f6eef76ccf8e882093acce1f7b42da78aa\", \"viz_unittests\": \"418639715c199254803a375ccc749917f6de799f\", \"wm_unittests\": \"9ce4a0864e80773de165013bcd4f91e17c7ce91b\", \"wtf_unittests\": \"8ca7118acefd2d480d616a0475403f394836bcc5\", \"zlib_unittests\": \"ad43758b90fd0c753afebd4e72539f7b56511c0f\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_093e8a6336a6e6d4670d6f5128e7c73730afadf3bd7f9284d26d8f79182f8ce5_v2\", \"builder_4973d939604d11dcbaec68575e69c80c756ff63e355ab013b29b8bca26434b7c_v2\", \"builder_61faa0d0c1c079ecf670d8cfa4f78ad3b1687a5534233c7377462c3ee6819708_v2\", \"builder_8e7ad9a10a7772feba5b42dda56992e918fe88f23579c3ddaf696dde66ee4238_v2\", \"builder_db6a6033e460ced730b3940170fe4cf923f58c29fb56731f98470df5edf70e65_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-xenial-us-central1-b-8-89-mvey\"], \"image\": [\"chrome-xenial-21011100-9488e34e558\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"e2-standard-8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16\", \"Ubuntu-16.04\", \"Ubuntu-16.04.6\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.12\"], \"server_version\": [\"5671-1d9dea8\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1611332597941723",
"status": "COMPLETED",
"status_changed_ts": "1611338536105912",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-dbg/22304",
"builder:linux-chromeos-dbg",
"buildset:commit/git/e33136ac0d01da02a268398d6e5feb42db259ddd",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/e33136ac0d01da02a268398d6e5feb42db259ddd",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022819186104230000",
"scheduler_job_id:chromium/linux-chromeos-dbg",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8857389124136707024/+/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:5143b854dad64a10",
"user_agent:luci-scheduler"
],
"updated_ts": "1611338536110787",
"url": "https://ci.chromium.org/b/8857389124136707024",
"utcnow_ts": "1611338536568494"
}
}
[18:02:16.580] Invocation finished in 1h40m33.738412062s with status SUCCEEDED