[05:30:14.297] New invocation is queued and will start shortly
[05:30:15.435] Starting the invocation (attempt 1)
[05:30:15.521] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[05:30:15.521] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[05:30:15.521] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9029201961085361040",
"parameters_json": "{\"builder_name\":\"linux-chromeos-dbg\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"e03792f4f58ce450894178bc3224e7874e1e006b\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-dbg",
"scheduler_invocation_id:9029201961085361040",
"scheduler_job_id:chromium/linux-chromeos-dbg",
"user_agent:luci-scheduler",
"buildset:commit/git/e03792f4f58ce450894178bc3224e7874e1e006b",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/e03792f4f58ce450894178bc3224e7874e1e006b",
"gitiles_ref:refs/heads/master"
]
}
[05:30:16.500] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1605245415696628",
"id": "8863771898949528928",
"parameters_json": "{\"builder_name\": \"linux-chromeos-dbg\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"e03792f4f58ce450894178bc3224e7874e1e006b\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1605245416317744",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-dbg/21278",
"builder:linux-chromeos-dbg",
"buildset:commit/git/e03792f4f58ce450894178bc3224e7874e1e006b",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/e03792f4f58ce450894178bc3224e7874e1e006b",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9029201961085361040",
"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/8863771898949528928/+/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": "1605245416317880",
"url": "https://ci.chromium.org/b/8863771898949528928",
"utcnow_ts": "1605245416488952"
}
}
[05:30:16.500] Task URL: https://ci.chromium.org/b/8863771898949528928
[05:30:16.500] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:2:0) after 1m0s
[05:31:16.489] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:2:0)
[05:31:16.489] Timer tick, asking Buildbucket for the build status
[05:31:17.252] Build 8863771898949528928: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:31:17.252] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:3:0) after 1m0s
[05:31:39.654] Received PubSub notification, asking Buildbucket for the build status
[05:31:39.845] Build 8863771898949528928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:32:17.288] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:3:0)
[05:32:17.288] Timer tick, asking Buildbucket for the build status
[05:32:17.440] Build 8863771898949528928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:32:17.440] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:5:0) after 1m0s
[05:33:17.472] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:5:0)
[05:33:17.472] Timer tick, asking Buildbucket for the build status
[05:33:17.694] Build 8863771898949528928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:33:17.694] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:6:0) after 1m0s
[05:34:17.722] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:6:0)
[05:34:17.722] Timer tick, asking Buildbucket for the build status
[05:34:18.021] Build 8863771898949528928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:34:18.021] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:7:0) after 1m0s
[05:35:18.071] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:7:0)
[05:35:18.071] Timer tick, asking Buildbucket for the build status
[05:35:18.464] Build 8863771898949528928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:35:18.464] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:8:0) after 1m0s
[05:36:18.546] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:8:0)
[05:36:18.546] Timer tick, asking Buildbucket for the build status
[05:36:18.687] Build 8863771898949528928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:36:18.687] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:9:0) after 1m0s
[05:37:18.699] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:9:0)
[05:37:18.699] Timer tick, asking Buildbucket for the build status
[05:37:18.949] Build 8863771898949528928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:37:18.949] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:10:0) after 1m0s
[05:38:18.990] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:10:0)
[05:38:18.991] Timer tick, asking Buildbucket for the build status
[05:38:19.298] Build 8863771898949528928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:38:19.298] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:11:0) after 1m0s
[05:39:19.353] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:11:0)
[05:39:19.353] Timer tick, asking Buildbucket for the build status
[05:39:19.473] Build 8863771898949528928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:39:19.473] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:12:0) after 1m0s
[05:40:19.527] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:12:0)
[05:40:19.527] Timer tick, asking Buildbucket for the build status
[05:40:19.650] Build 8863771898949528928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:40:19.650] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:13:0) after 1m0s
[05:41:19.762] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:13:0)
[05:41:19.762] Timer tick, asking Buildbucket for the build status
[05:41:19.827] Build 8863771898949528928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:41:19.827] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:14:0) after 1m0s
[05:42:19.935] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:14:0)
[05:42:19.935] Timer tick, asking Buildbucket for the build status
[05:42:20.550] Build 8863771898949528928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:42:20.550] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:15:0) after 1m0s
[05:43:20.593] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:15:0)
[05:43:20.593] Timer tick, asking Buildbucket for the build status
[05:43:20.668] Build 8863771898949528928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:43:20.668] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:16:0) after 1m0s
[05:44:20.714] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:16:0)
[05:44:20.714] Timer tick, asking Buildbucket for the build status
[05:44:20.910] Build 8863771898949528928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:44:20.910] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:17:0) after 1m0s
[05:45:21.003] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:17:0)
[05:45:21.003] Timer tick, asking Buildbucket for the build status
[05:45:21.192] Build 8863771898949528928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:45:21.192] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:18:0) after 1m0s
[05:46:21.268] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:18:0)
[05:46:21.268] Timer tick, asking Buildbucket for the build status
[05:46:22.280] Build 8863771898949528928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:46:22.280] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:19:0) after 1m0s
[05:47:22.448] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:19:0)
[05:47:22.448] Timer tick, asking Buildbucket for the build status
[05:47:22.706] Build 8863771898949528928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:47:22.706] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:20:0) after 1m0s
[05:48:22.766] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:20:0)
[05:48:22.766] Timer tick, asking Buildbucket for the build status
[05:48:23.056] Build 8863771898949528928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:48:23.056] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:21:0) after 1m0s
[05:49:23.222] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:21:0)
[05:49:23.222] Timer tick, asking Buildbucket for the build status
[05:49:23.434] Build 8863771898949528928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:49:23.434] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:22:0) after 1m0s
[05:50:23.521] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:22:0)
[05:50:23.521] Timer tick, asking Buildbucket for the build status
[05:50:23.798] Build 8863771898949528928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:50:23.798] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:23:0) after 1m0s
[05:51:23.942] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:23:0)
[05:51:23.942] Timer tick, asking Buildbucket for the build status
[05:51:24.032] Build 8863771898949528928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:51:24.032] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:24:0) after 1m0s
[05:52:24.081] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:24:0)
[05:52:24.081] Timer tick, asking Buildbucket for the build status
[05:52:24.140] Build 8863771898949528928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:52:24.140] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:25:0) after 1m0s
[05:53:24.186] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:25:0)
[05:53:24.186] Timer tick, asking Buildbucket for the build status
[05:53:24.267] Build 8863771898949528928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:53:24.267] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:26:0) after 1m0s
[05:54:24.322] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:26:0)
[05:54:24.322] Timer tick, asking Buildbucket for the build status
[05:54:24.481] Build 8863771898949528928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:54:24.481] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:27:0) after 1m0s
[05:55:24.535] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:27:0)
[05:55:24.535] Timer tick, asking Buildbucket for the build status
[05:55:24.644] Build 8863771898949528928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:55:24.645] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:28:0) after 1m0s
[05:56:24.644] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:28:0)
[05:56:24.644] Timer tick, asking Buildbucket for the build status
[05:56:24.719] Build 8863771898949528928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:56:24.719] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:29:0) after 1m0s
[05:57:24.773] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:29:0)
[05:57:24.773] Timer tick, asking Buildbucket for the build status
[05:57:24.844] Build 8863771898949528928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:57:24.844] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:30:0) after 1m0s
[05:58:24.956] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:30:0)
[05:58:24.956] Timer tick, asking Buildbucket for the build status
[05:58:25.122] Build 8863771898949528928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:58:25.122] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:31:0) after 1m0s
[05:59:25.264] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:31:0)
[05:59:25.264] Timer tick, asking Buildbucket for the build status
[05:59:25.390] Build 8863771898949528928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:59:25.390] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:32:0) after 1m0s
[06:00:25.431] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:32:0)
[06:00:25.431] Timer tick, asking Buildbucket for the build status
[06:00:25.647] Build 8863771898949528928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:00:25.647] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:33:0) after 1m0s
[06:01:34.270] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:33:0)
[06:01:34.270] Timer tick, asking Buildbucket for the build status
[06:01:34.388] Build 8863771898949528928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:01:34.388] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:34:0) after 1m0s
[06:02:34.512] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:34:0)
[06:02:34.512] Timer tick, asking Buildbucket for the build status
[06:02:34.575] Build 8863771898949528928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:02:34.575] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:35:0) after 1m0s
[06:03:34.705] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:35:0)
[06:03:34.705] Timer tick, asking Buildbucket for the build status
[06:03:34.771] Build 8863771898949528928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:03:34.771] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:36:0) after 1m0s
[06:04:34.857] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:36:0)
[06:04:34.857] Timer tick, asking Buildbucket for the build status
[06:04:34.926] Build 8863771898949528928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:04:34.927] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:37:0) after 1m0s
[06:05:34.942] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:37:0)
[06:05:34.942] Timer tick, asking Buildbucket for the build status
[06:05:35.069] Build 8863771898949528928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:05:35.069] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:38:0) after 1m0s
[06:06:35.140] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:38:0)
[06:06:35.140] Timer tick, asking Buildbucket for the build status
[06:06:35.432] Build 8863771898949528928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:06:35.432] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:39:0) after 1m0s
[06:07:35.466] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:39:0)
[06:07:35.466] Timer tick, asking Buildbucket for the build status
[06:07:35.583] Build 8863771898949528928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:07:35.583] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:40:0) after 1m0s
[06:08:35.682] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:40:0)
[06:08:35.682] Timer tick, asking Buildbucket for the build status
[06:08:35.831] Build 8863771898949528928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:08:35.831] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:41:0) after 1m0s
[06:09:35.856] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:41:0)
[06:09:35.856] Timer tick, asking Buildbucket for the build status
[06:09:36.286] Build 8863771898949528928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:09:36.286] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:42:0) after 1m0s
[06:10:36.329] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:42:0)
[06:10:36.329] Timer tick, asking Buildbucket for the build status
[06:10:36.431] Build 8863771898949528928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:10:36.431] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:43:0) after 1m0s
[06:11:36.506] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:43:0)
[06:11:36.506] Timer tick, asking Buildbucket for the build status
[06:11:37.133] Build 8863771898949528928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:11:37.133] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:44:0) after 1m0s
[06:12:37.263] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:44:0)
[06:12:37.263] Timer tick, asking Buildbucket for the build status
[06:12:37.377] Build 8863771898949528928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:12:37.377] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:45:0) after 1m0s
[06:13:37.446] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:45:0)
[06:13:37.446] Timer tick, asking Buildbucket for the build status
[06:13:37.639] Build 8863771898949528928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:13:37.639] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:46:0) after 1m0s
[06:14:37.728] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:46:0)
[06:14:37.728] Timer tick, asking Buildbucket for the build status
[06:14:37.799] Build 8863771898949528928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:14:37.799] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:47:0) after 1m0s
[06:15:37.890] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:47:0)
[06:15:37.890] Timer tick, asking Buildbucket for the build status
[06:15:38.221] Build 8863771898949528928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:15:38.221] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:48:0) after 1m0s
[06:16:38.262] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:48:0)
[06:16:38.262] Timer tick, asking Buildbucket for the build status
[06:16:38.359] Build 8863771898949528928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:16:38.359] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:49:0) after 1m0s
[06:17:38.456] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:49:0)
[06:17:38.456] Timer tick, asking Buildbucket for the build status
[06:17:38.880] Build 8863771898949528928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:17:38.880] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:50:0) after 1m0s
[06:18:38.986] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:50:0)
[06:18:38.986] Timer tick, asking Buildbucket for the build status
[06:18:39.159] Build 8863771898949528928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:18:39.159] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:51:0) after 1m0s
[06:19:39.204] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:51:0)
[06:19:39.204] Timer tick, asking Buildbucket for the build status
[06:19:39.339] Build 8863771898949528928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:19:39.339] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:52:0) after 1m0s
[06:20:39.408] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:52:0)
[06:20:39.408] Timer tick, asking Buildbucket for the build status
[06:20:39.527] Build 8863771898949528928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:20:39.527] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:53:0) after 1m0s
[06:21:39.544] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:53:0)
[06:21:39.544] Timer tick, asking Buildbucket for the build status
[06:21:39.635] Build 8863771898949528928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:21:39.635] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:54:0) after 1m0s
[06:22:39.711] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:54:0)
[06:22:39.711] Timer tick, asking Buildbucket for the build status
[06:22:40.098] Build 8863771898949528928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:22:40.098] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:55:0) after 1m0s
[06:23:40.128] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:55:0)
[06:23:40.128] Timer tick, asking Buildbucket for the build status
[06:23:40.364] Build 8863771898949528928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:23:40.364] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:56:0) after 1m0s
[06:24:40.418] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:56:0)
[06:24:40.418] Timer tick, asking Buildbucket for the build status
[06:24:40.811] Build 8863771898949528928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:24:40.811] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:57:0) after 1m0s
[06:25:41.003] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:57:0)
[06:25:41.003] Timer tick, asking Buildbucket for the build status
[06:25:41.693] Build 8863771898949528928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:25:41.693] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:58:0) after 1m0s
[06:26:41.800] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:58:0)
[06:26:41.800] Timer tick, asking Buildbucket for the build status
[06:26:42.209] Build 8863771898949528928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:26:42.209] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:59:0) after 1m0s
[06:27:42.281] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:59:0)
[06:27:42.281] Timer tick, asking Buildbucket for the build status
[06:27:42.981] Build 8863771898949528928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:27:42.981] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:60:0) after 1m0s
[06:28:43.007] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:60:0)
[06:28:43.007] Timer tick, asking Buildbucket for the build status
[06:28:43.294] Build 8863771898949528928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:28:43.294] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:61:0) after 1m0s
[06:29:43.339] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:61:0)
[06:29:43.339] Timer tick, asking Buildbucket for the build status
[06:29:43.518] Build 8863771898949528928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:29:43.518] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:62:0) after 1m0s
[06:30:43.534] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:62:0)
[06:30:43.534] Timer tick, asking Buildbucket for the build status
[06:30:44.011] Build 8863771898949528928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:30:44.011] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:63:0) after 1m0s
[06:31:43.996] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:63:0)
[06:31:43.996] Timer tick, asking Buildbucket for the build status
[06:31:44.078] Build 8863771898949528928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:31:44.078] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:64:0) after 1m0s
[06:32:44.149] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:64:0)
[06:32:44.149] Timer tick, asking Buildbucket for the build status
[06:32:44.366] Build 8863771898949528928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:32:44.366] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:65:0) after 1m0s
[06:33:44.411] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:65:0)
[06:33:44.411] Timer tick, asking Buildbucket for the build status
[06:33:44.511] Build 8863771898949528928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:33:44.511] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:66:0) after 1m0s
[06:34:44.599] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:66:0)
[06:34:44.599] Timer tick, asking Buildbucket for the build status
[06:34:44.780] Build 8863771898949528928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:34:44.780] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:67:0) after 1m0s
[06:35:44.936] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:67:0)
[06:35:44.936] Timer tick, asking Buildbucket for the build status
[06:35:45.068] Build 8863771898949528928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:35:45.068] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:68:0) after 1m0s
[06:36:45.197] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:68:0)
[06:36:45.197] Timer tick, asking Buildbucket for the build status
[06:36:45.303] Build 8863771898949528928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:36:45.303] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:69:0) after 1m0s
[06:37:45.311] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:69:0)
[06:37:45.311] Timer tick, asking Buildbucket for the build status
[06:37:45.496] Build 8863771898949528928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:37:45.496] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:70:0) after 1m0s
[06:38:45.485] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:70:0)
[06:38:45.485] Timer tick, asking Buildbucket for the build status
[06:38:45.633] Build 8863771898949528928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:38:45.633] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:71:0) after 1m0s
[06:39:45.665] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:71:0)
[06:39:45.665] Timer tick, asking Buildbucket for the build status
[06:39:45.772] Build 8863771898949528928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:39:45.772] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:72:0) after 1m0s
[06:40:45.825] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:72:0)
[06:40:45.825] Timer tick, asking Buildbucket for the build status
[06:40:47.376] Build 8863771898949528928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:40:47.376] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:73:0) after 1m0s
[06:41:47.431] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:73:0)
[06:41:47.431] Timer tick, asking Buildbucket for the build status
[06:41:47.520] Build 8863771898949528928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:41:47.520] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:74:0) after 1m0s
[06:42:47.545] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:74:0)
[06:42:47.545] Timer tick, asking Buildbucket for the build status
[06:42:47.715] Build 8863771898949528928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:42:47.715] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:75:0) after 1m0s
[06:43:47.721] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:75:0)
[06:43:47.721] Timer tick, asking Buildbucket for the build status
[06:43:47.798] Build 8863771898949528928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:43:47.798] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:76:0) after 1m0s
[06:44:47.885] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:76:0)
[06:44:47.885] Timer tick, asking Buildbucket for the build status
[06:44:48.386] Build 8863771898949528928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:44:48.386] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:77:0) after 1m0s
[06:45:48.425] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:77:0)
[06:45:48.425] Timer tick, asking Buildbucket for the build status
[06:45:48.549] Build 8863771898949528928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:45:48.549] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:78:0) after 1m0s
[06:46:48.585] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:78:0)
[06:46:48.585] Timer tick, asking Buildbucket for the build status
[06:46:48.724] Build 8863771898949528928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:46:48.724] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:79:0) after 1m0s
[06:47:48.832] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:79:0)
[06:47:48.832] Timer tick, asking Buildbucket for the build status
[06:47:48.933] Build 8863771898949528928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:47:48.933] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:80:0) after 1m0s
[06:48:49.003] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:80:0)
[06:48:49.003] Timer tick, asking Buildbucket for the build status
[06:48:49.272] Build 8863771898949528928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:48:49.272] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:81:0) after 1m0s
[06:49:49.313] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:81:0)
[06:49:49.313] Timer tick, asking Buildbucket for the build status
[06:49:49.450] Build 8863771898949528928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:49:49.450] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:82:0) after 1m0s
[06:50:49.519] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:82:0)
[06:50:49.519] Timer tick, asking Buildbucket for the build status
[06:50:49.661] Build 8863771898949528928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:50:49.661] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:83:0) after 1m0s
[06:51:49.739] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:83:0)
[06:51:49.739] Timer tick, asking Buildbucket for the build status
[06:51:50.571] Build 8863771898949528928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:51:50.571] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:84:0) after 1m0s
[06:52:50.605] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:84:0)
[06:52:50.605] Timer tick, asking Buildbucket for the build status
[06:52:50.704] Build 8863771898949528928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:52:50.704] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:85:0) after 1m0s
[06:53:50.770] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:85:0)
[06:53:50.770] Timer tick, asking Buildbucket for the build status
[06:53:51.399] Build 8863771898949528928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:53:51.399] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:86:0) after 1m0s
[06:54:51.435] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:86:0)
[06:54:51.435] Timer tick, asking Buildbucket for the build status
[06:54:51.612] Build 8863771898949528928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:54:51.612] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:87:0) after 1m0s
[06:55:51.678] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:87:0)
[06:55:51.678] Timer tick, asking Buildbucket for the build status
[06:55:52.352] Build 8863771898949528928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:55:52.352] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:88:0) after 1m0s
[06:56:52.410] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:88:0)
[06:56:52.410] Timer tick, asking Buildbucket for the build status
[06:56:52.489] Build 8863771898949528928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:56:52.489] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:89:0) after 1m0s
[06:57:52.520] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:89:0)
[06:57:52.520] Timer tick, asking Buildbucket for the build status
[06:57:53.272] Build 8863771898949528928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:57:53.272] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:90:0) after 1m0s
[06:58:53.300] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:90:0)
[06:58:53.300] Timer tick, asking Buildbucket for the build status
[06:58:53.382] Build 8863771898949528928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:58:53.382] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:91:0) after 1m0s
[06:59:53.470] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:91:0)
[06:59:53.470] Timer tick, asking Buildbucket for the build status
[06:59:53.573] Build 8863771898949528928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:59:53.573] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:92:0) after 1m0s
[07:00:53.689] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:92:0)
[07:00:53.689] Timer tick, asking Buildbucket for the build status
[07:00:54.383] Build 8863771898949528928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:00:54.383] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:93:0) after 1m0s
[07:01:54.437] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:93:0)
[07:01:54.437] Timer tick, asking Buildbucket for the build status
[07:01:54.676] Build 8863771898949528928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:01:54.676] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:94:0) after 1m0s
[07:02:54.730] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:94:0)
[07:02:54.730] Timer tick, asking Buildbucket for the build status
[07:02:55.308] Build 8863771898949528928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:02:55.308] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:95:0) after 1m0s
[07:03:55.343] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:95:0)
[07:03:55.343] Timer tick, asking Buildbucket for the build status
[07:03:55.529] Build 8863771898949528928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:03:55.529] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:96:0) after 1m0s
[07:04:55.538] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:96:0)
[07:04:55.538] Timer tick, asking Buildbucket for the build status
[07:04:55.652] Build 8863771898949528928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:04:55.652] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:97:0) after 1m0s
[07:05:55.674] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:97:0)
[07:05:55.674] Timer tick, asking Buildbucket for the build status
[07:05:56.153] Build 8863771898949528928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:05:56.153] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:98:0) after 1m0s
[07:06:56.213] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:98:0)
[07:06:56.213] Timer tick, asking Buildbucket for the build status
[07:06:56.433] Build 8863771898949528928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:06:56.433] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:99:0) after 1m0s
[07:07:56.468] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:99:0)
[07:07:56.468] Timer tick, asking Buildbucket for the build status
[07:07:57.134] Build 8863771898949528928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:07:57.134] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9029201961085361040:100:0) after 1m0s
[07:08:45.568] Received PubSub notification, asking Buildbucket for the build status
[07:08:45.986] Build 8863771898949528928: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[07:08:45.986] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1605251323714948",
"created_by": "project:chromium",
"created_ts": "1605245415696628",
"failure_reason": "BUILD_FAILURE",
"id": "8863771898949528928",
"parameters_json": "{\"builder_name\": \"linux-chromeos-dbg\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"e03792f4f58ce450894178bc3224e7874e1e006b\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"1 Test Suite(s) failed.\\n\\n**browser_tests** failed because of:\\n\\n- ExistingUserControllerActiveDirectoryTest.UserKerberosFilesChangedSignalTriggersFileUpdate\\n\\n- ExistingUserControllerActiveDirectoryTest.ActiveDirectoryOnlineLogin_Success\\n\\n- ExistingUserControllerActiveDirectoryTest.ActiveDirectoryOfflineLogin_Success\\n\\n- PDFTestFiles/PDFExtensionLoadTest.Load/3\\n\\n- ...1 more failure(s) (5 total)...\"}, \"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\": \"e03792f4f58ce450894178bc3224e7874e1e006b\", \"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-87-beu2\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-dbg\", \"buildnumber\": 21278, \"got_angle_revision\": \"f9e59ad078552424ca165644f4da3b4e2687c3dc\", \"got_dawn_revision\": \"8d69298d9b2938dcac52a08451b4f63fb61c31cb\", \"got_nacl_revision\": \"f9e6f257d1a0934dbeed3c5c8f83b651dc4f55e1\", \"got_revision\": \"e03792f4f58ce450894178bc3224e7874e1e006b\", \"got_revision_cp\": \"refs/heads/master@{#827161}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"e4c1a25cc6797a47043fc415c53b8edcd7b3e37e\", \"got_v8_revision\": \"2dbcdc105b963ee2501c82139eef7e0603977ff0\", \"got_v8_revision_cp\": \"refs/heads/8.8.278@{#1}\", \"got_webrtc_revision\": \"daab6896e2938e28f01e305ce2fff038f47554c4\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32599}\", \"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\": \"e03792f4f58ce450894178bc3224e7874e1e006b\", \"swarm_hashes_refs/heads/master(at){#827161}_without_patch\": {\"absl_hardening_tests\": \"ca9f3ce21871bf12995f5e298e1fda509bc8bd53\", \"accessibility_unittests\": \"66ef8db201634b0cc45ba688955a7bfa5e22fb0b\", \"angle_unittests\": \"3385dcefd31dc8f2c9da1b1a5c5c3ccfd0c1ce31\", \"app_list_unittests\": \"5038ea11441971646feec102613e5bad796058e5\", \"app_shell_unittests\": \"523f4d4c5e2b18bd87338869c92445a93096813a\", \"ash_unittests\": \"54ac8eedab3096ab532f6f3ab976b003387f47d8\", \"aura_unittests\": \"6f97f8f95aa6319bf43a06f16d30e1d75b782aa3\", \"base_unittests\": \"60778c56d3cad77224fe7f6ea36371f22fb0ad06\", \"base_util_unittests\": \"360ffcb0e799acc08baa1828af1b93e1d63223d1\", \"blink_common_unittests\": \"071a3494a3107c50cb32e90b5842883c75bfd44b\", \"blink_fuzzer_unittests\": \"89d289dd698331501b4866b26e1a3a8b010baf94\", \"blink_heap_unittests\": \"53d5326f492d9ddcb7aa4d00a5c5abe986127d36\", \"blink_platform_unittests\": \"17c52deaea1c7882e30d7689c07010ec3c0c48dc\", \"blink_unittests\": \"941682e0b6c46d8f94feb07fcd065b39b709637a\", \"boringssl_crypto_tests\": \"a5c45d9c8043b7e4f8a811aea3ffe072956e13d8\", \"boringssl_ssl_tests\": \"a58e1849d5a6c531af414a2a0ce72eddef7ec6da\", \"browser_tests\": \"e6d8205ed863f3daf653b9ef3ca8d81717bf76d2\", \"capture_unittests\": \"26e75730dc0269d716964684de5e54f7ffc5a2d0\", \"cast_unittests\": \"30902d4b655a027128d1667c13ab1c282a847c55\", \"cc_unittests\": \"b5cd831d25a1cba53d6f7d0258f0dccf618e00e5\", \"chrome_app_unittests\": \"2565efc4ad56fd1256267303a4efaf0e9625b675\", \"chromedriver_unittests\": \"10ad6c45d9bbca3a4fbedabf9d619630970e4abb\", \"chromeos_components_unittests\": \"ce2587971e40247a69d14350d6cbd1031cb6e4b0\", \"chromeos_unittests\": \"b13750fc5d4c919aeaeb205a2961627736296047\", \"components_browsertests\": \"7411d5aadbeca695540c1d7ce89766cafdc747ef\", \"components_unittests\": \"24311d2b7e4c589fbe4622a6584a0bc62492bec4\", \"compositor_unittests\": \"da91de4ceee7836f7f66a6335267580ac93bece7\", \"content_browsertests\": \"5a26b537cb17bf5334efc6e9e6255a189d78268c\", \"content_unittests\": \"6567b6b011efb3c44e7fef1a8850f535609453de\", \"crashpad_tests\": \"f3cfe5b46f8d56467520c40f281abef48e717096\", \"crypto_unittests\": \"91d800ea7cf9436cfdc3e453b5480d60fb8de8ba\", \"dbus_unittests\": \"f46f40e03e73059c8ceebccc65d38eeb93aa9f33\", \"device_unittests\": \"2c17a6751a2f352cb1ec1f4bd7b3daefdcbbb2c6\", \"display_unittests\": \"07abbf9ca646bc034f360157eda74c0a3f13c294\", \"events_unittests\": \"8735cda040783700b37dbb87723be1cc3c803b35\", \"exo_unittests\": \"18f7dc7c0690e0a144cf2591e2baee8fe2b96a23\", \"extensions_browsertests\": \"9028811772d5899c40c26519b3ce042970d222b8\", \"extensions_unittests\": \"6cda4fabca9eb043bc3e57ce5f1254e8f45cb31e\", \"filesystem_service_unittests\": \"349321fb92b8029ff614e63965aa54fdd4429ee0\", \"gcm_unit_tests\": \"42d0aa9fbebcaab48eecacaed206015cdd0d7761\", \"gfx_unittests\": \"5314a9ddc845617083164a018795cd9147d87a5b\", \"gin_unittests\": \"da835c6b0cc2387584a2502883d69159be8feb87\", \"gl_unittests_ozone\": \"dc554a5795cf23bdae9cdf1cc3e300007d537479\", \"google_apis_unittests\": \"3db08fcc649a22baf69f0b916585664a3de2f7f2\", \"gpu_unittests\": \"765328c1170b64793fb58279a0e75b5a96e2b6b2\", \"gwp_asan_unittests\": \"93d9c126489f26e780791b8289d80b8e4c093ffb\", \"interactive_ui_tests\": \"5e0735c2057005f286145134ac29c786e9e138d2\", \"ipc_tests\": \"c4e8b5acfe24c98dd12c22d5f72dcf1daeafdd62\", \"jingle_unittests\": \"5cf29be0cf78ad26edba955831b9035bceb3024e\", \"keyboard_unittests\": \"937f54d812e2b8f2563702f7d291f33cce0a0d51\", \"latency_unittests\": \"e1ec4f454a1b03bbc3a8090274f2e82537aad43b\", \"libjingle_xmpp_unittests\": \"986e5c8acb0de075b35b75956ec2615f56b34bd5\", \"liburlpattern_unittests\": \"599e216958f0e9d992aa68df5d97d5dc9a8a1b30\", \"media_blink_unittests\": \"f36ffef3b298b5b4d21dff11dfb30c2841f74948\", \"media_unittests\": \"e0c3f6d366b55357e1aa4150d4d450aae1fec536\", \"message_center_unittests\": \"579eb9203f4f315704ff2952f14996aeef492e9b\", \"midi_unittests\": \"fc678861fc2df444ee2ad314345fe3ed728d1940\", \"mojo_core_unittests\": \"45408ff7402ab5d1f72750f890d5cb4d82b06683\", \"mojo_unittests\": \"bfad788c44bca3c75b4aeb73ca1530dacc84b36c\", \"nacl_helper_nonsfi_unittests\": \"a409cb327b04420f08b75ad3fcade42b35daf5d3\", \"nacl_loader_unittests\": \"462fa366fd945df040c49f24b93c132f56751792\", \"native_theme_unittests\": \"c5b2eded3d5e2a4e11eb19e76d516a6906b6ef47\", \"net_unittests\": \"2e36d07dc12c1ae2041a24bb37d09143570b1032\", \"ozone_gl_unittests\": \"4e4c1ab14fe801edaab8baf1a7f3aef15c234345\", \"ozone_unittests\": \"fbec965911d821bfeca8a705ecc8e1590bd04aa0\", \"ozone_x11_unittests\": \"61b8c11893e90581581792b077bcd1d259602f92\", \"pdf_unittests\": \"7bb1a67934ce81bb7a279222771eae5e9bfcba29\", \"perfetto_unittests\": \"71e95da7a4049f4376ea19221e596bc19f7aaf2b\", \"ppapi_unittests\": \"e4ad5beb0ed8a0cbd4c0b11b36b289ea67407df7\", \"printing_unittests\": \"bf05ff97c2acce4e142a5b5fe9d948a89d89b6c9\", \"remoting_unittests\": \"b4c14bcb5c6af41ff32be0f40f7f2d8df65503e7\", \"sandbox_linux_unittests\": \"a4e33f85a93a6c6bffb4ba4f3d00d53a1d0a2230\", \"service_manager_unittests\": \"dd7e975542f1cef9ee0ada24804fd26d0c7f623c\", \"services_unittests\": \"00c54d2039b6db3ec1c8ff2973925677b1fa8e6d\", \"shell_dialogs_unittests\": \"d8bfc26fc201f3820f41f77bd1b413ae572cbae7\", \"skia_unittests\": \"e0028a1063330883374f56f801b1c17f155fcb51\", \"snapshot_unittests\": \"e38f4031614f2e81c6b7b6724e0c26a109a39177\", \"sql_unittests\": \"0882ceaea75461fb7d261b58b1f3bd911fa44058\", \"storage_unittests\": \"44f283e90b7dcf84db7e9bdb4b0e0bf7e1a049c2\", \"sync_integration_tests\": \"6e2005f959aea63ed9eee5ee98a3da307d057479\", \"traffic_annotation_auditor_unittests\": \"2a8026b0dc226b81546d01c1def999944eabf62d\", \"ui_base_unittests\": \"ece02b3ca829aaabf8c33e2fb733382455c7f0a0\", \"ui_chromeos_unittests\": \"78bef80f76a86c33efb8e3312ea4726244bbcacf\", \"ui_touch_selection_unittests\": \"8c583fece2bd98e2067b96f172a8f267349210ca\", \"unit_tests\": \"6c32494d151a047acd4d54ce73aa137862b20213\", \"url_unittests\": \"1b408482d4eea489168c3ae9643de40c390e6bfe\", \"usage_time_limit_unittests\": \"15c9c8afb3af9fb205ac7b4fd3296aa6f7b51ec9\", \"views_unittests\": \"3eb9b2e81cb44058069a8bafbcc9c7a23dcde913\", \"viz_unittests\": \"62eb619c784e839f7b5025eeeb0537895c44b359\", \"wm_unittests\": \"d2d94555e59c6296f77bf5d1cf8a67148bca0b50\", \"wtf_unittests\": \"779e954a2d2cddf55dac186b4f434abe1a8e797a\", \"zlib_unittests\": \"2d016191ceb0bb9bca23685549c163dcb65d7806\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_112d55e69d4bf792454bf0bc343f5011325dc51aa46e2a4521a4a1f4a6d30164_v2\", \"builder_151f80801b9f29276c11c5f05dab27ce5673f9c9ba442aaf2cf20aab9215f522_v2\", \"builder_4973d939604d11dcbaec68575e69c80c756ff63e355ab013b29b8bca26434b7c_v2\", \"builder_51829829c31fbaf3768e3309e640f1ed37e0867e138bde8c875431bcab07adc2_v2\", \"builder_8851890e994e29b56fba2042f90feba99073c8e56d0ea67af5ee13ebe9f87cf6_v2\", \"builder_8ed59a4fa98a52009c5b7be7e9c90826b738aab438c85f8db549ef122247493f_v2\", \"builder_b624744545b53f30069a9e0f9d655dd80cb0688eb6f8753c5d4d1f02e2478691_v2\", \"builder_f5610824250c57fc28679fbc8606252c3dbaca731fb7f2a4bebf1e0fc7f48a3a_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-87-beu2\"], \"image\": [\"chrome-xenial-20110100-7e97731f9b6\"], \"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\": [\"5626-39642e9\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}, \"ui\": {\"info\": \"1 Test Suite(s) failed.\\n\\n**browser_tests** failed because of:\\n\\n- ExistingUserControllerActiveDirectoryTest.UserKerberosFilesChangedSignalTriggersFileUpdate\\n\\n- ExistingUserControllerActiveDirectoryTest.ActiveDirectoryOnlineLogin_Success\\n\\n- ExistingUserControllerActiveDirectoryTest.ActiveDirectoryOfflineLogin_Success\\n\\n- PDFTestFiles/PDFExtensionLoadTest.Load/3\\n\\n- ...1 more failure(s) (5 total)...\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1605245498609560",
"status": "COMPLETED",
"status_changed_ts": "1605245499265306",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-dbg/21278",
"builder:linux-chromeos-dbg",
"buildset:commit/git/e03792f4f58ce450894178bc3224e7874e1e006b",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/e03792f4f58ce450894178bc3224e7874e1e006b",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9029201961085361040",
"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/8863771898949528928/+/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:4fd8e6bde5274510",
"user_agent:luci-scheduler"
],
"updated_ts": "1605251325053973",
"url": "https://ci.chromium.org/b/8863771898949528928",
"utcnow_ts": "1605251325920591"
}
}
[07:08:45.987] Invocation finished in 1h38m31.711498789s with status FAILED