[22:00:26.652] New invocation is queued and will start shortly
[22:00:27.817] Starting the invocation (attempt 1)
[22:00:27.855] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[22:00:27.855] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[22:00:27.855] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9021438920575061360",
"parameters_json": "{\"builder_name\":\"linux-chromeos-dbg\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"fd6e4bf7bc4eeaf657dbc548bdcc7b61d07f7934\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-dbg",
"scheduler_invocation_id:9021438920575061360",
"scheduler_job_id:chromium/linux-chromeos-dbg",
"user_agent:luci-scheduler",
"buildset:commit/git/fd6e4bf7bc4eeaf657dbc548bdcc7b61d07f7934",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/fd6e4bf7bc4eeaf657dbc548bdcc7b61d07f7934",
"gitiles_ref:refs/heads/master"
]
}
[22:00:28.484] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1612648827948220",
"id": "8856008858543594384",
"parameters_json": "{\"builder_name\": \"linux-chromeos-dbg\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"fd6e4bf7bc4eeaf657dbc548bdcc7b61d07f7934\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1612648828364856",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-dbg/22512",
"builder:linux-chromeos-dbg",
"buildset:commit/git/fd6e4bf7bc4eeaf657dbc548bdcc7b61d07f7934",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/fd6e4bf7bc4eeaf657dbc548bdcc7b61d07f7934",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021438920575061360",
"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/8856008858543594384/+/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": "1612648828364974",
"url": "https://ci.chromium.org/b/8856008858543594384",
"utcnow_ts": "1612648828476836"
}
}
[22:00:28.484] Task URL: https://ci.chromium.org/b/8856008858543594384
[22:00:28.484] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:2:0) after 1m0s
[22:01:28.534] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:2:0)
[22:01:28.534] Timer tick, asking Buildbucket for the build status
[22:01:28.641] Build 8856008858543594384: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:01:28.641] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:3:0) after 1m0s
[22:02:28.727] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:3:0)
[22:02:28.727] Timer tick, asking Buildbucket for the build status
[22:02:28.916] Build 8856008858543594384: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:02:28.916] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:4:0) after 1m0s
[22:03:28.906] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:4:0)
[22:03:28.907] Timer tick, asking Buildbucket for the build status
[22:03:28.977] Build 8856008858543594384: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:03:28.977] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:5:0) after 1m0s
[22:04:28.995] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:5:0)
[22:04:28.995] Timer tick, asking Buildbucket for the build status
[22:04:29.066] Build 8856008858543594384: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:04:29.066] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:6:0) after 1m0s
[22:05:29.107] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:6:0)
[22:05:29.107] Timer tick, asking Buildbucket for the build status
[22:05:29.500] Build 8856008858543594384: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:05:29.500] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:7:0) after 1m0s
[22:06:29.554] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:7:0)
[22:06:29.554] Timer tick, asking Buildbucket for the build status
[22:06:29.695] Build 8856008858543594384: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:06:29.695] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:8:0) after 1m0s
[22:07:29.710] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:8:0)
[22:07:29.710] Timer tick, asking Buildbucket for the build status
[22:07:29.786] Build 8856008858543594384: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:07:29.786] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:9:0) after 1m0s
[22:08:29.811] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:9:0)
[22:08:29.811] Timer tick, asking Buildbucket for the build status
[22:08:29.872] Build 8856008858543594384: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:08:29.872] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:10:0) after 1m0s
[22:09:29.853] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:10:0)
[22:09:29.853] Timer tick, asking Buildbucket for the build status
[22:09:29.995] Build 8856008858543594384: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:09:29.995] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:11:0) after 1m0s
[22:10:30.002] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:11:0)
[22:10:30.002] Timer tick, asking Buildbucket for the build status
[22:10:30.106] Build 8856008858543594384: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:10:30.106] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:12:0) after 1m0s
[22:11:30.127] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:12:0)
[22:11:30.127] Timer tick, asking Buildbucket for the build status
[22:11:30.207] Build 8856008858543594384: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:11:30.207] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:13:0) after 1m0s
[22:12:30.224] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:13:0)
[22:12:30.224] Timer tick, asking Buildbucket for the build status
[22:12:30.447] Build 8856008858543594384: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:12:30.447] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:14:0) after 1m0s
[22:13:30.463] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:14:0)
[22:13:30.463] Timer tick, asking Buildbucket for the build status
[22:13:30.551] Build 8856008858543594384: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:13:30.551] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:15:0) after 1m0s
[22:14:30.585] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:15:0)
[22:14:30.585] Timer tick, asking Buildbucket for the build status
[22:14:30.683] Build 8856008858543594384: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:14:30.683] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:16:0) after 1m0s
[22:15:30.693] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:16:0)
[22:15:30.693] Timer tick, asking Buildbucket for the build status
[22:15:31.411] Build 8856008858543594384: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:15:31.411] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:17:0) after 1m0s
[22:16:31.460] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:17:0)
[22:16:31.460] Timer tick, asking Buildbucket for the build status
[22:16:31.553] Build 8856008858543594384: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:16:31.553] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:18:0) after 1m0s
[22:17:31.530] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:18:0)
[22:17:31.530] Timer tick, asking Buildbucket for the build status
[22:17:31.795] Build 8856008858543594384: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:17:31.795] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:19:0) after 1m0s
[22:18:31.771] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:19:0)
[22:18:31.771] Timer tick, asking Buildbucket for the build status
[22:18:31.836] Build 8856008858543594384: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:18:31.836] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:20:0) after 1m0s
[22:19:31.856] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:20:0)
[22:19:31.856] Timer tick, asking Buildbucket for the build status
[22:19:32.006] Build 8856008858543594384: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:19:32.006] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:21:0) after 1m0s
[22:20:32.040] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:21:0)
[22:20:32.040] Timer tick, asking Buildbucket for the build status
[22:20:32.290] Build 8856008858543594384: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:20:32.290] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:22:0) after 1m0s
[22:21:32.300] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:22:0)
[22:21:32.300] Timer tick, asking Buildbucket for the build status
[22:21:32.543] Build 8856008858543594384: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:21:32.543] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:23:0) after 1m0s
[22:22:32.562] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:23:0)
[22:22:32.562] Timer tick, asking Buildbucket for the build status
[22:22:32.651] Build 8856008858543594384: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:22:32.651] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:24:0) after 1m0s
[22:23:32.678] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:24:0)
[22:23:32.678] Timer tick, asking Buildbucket for the build status
[22:23:33.134] Build 8856008858543594384: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:23:33.134] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:25:0) after 1m0s
[22:24:33.153] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:25:0)
[22:24:33.153] Timer tick, asking Buildbucket for the build status
[22:24:33.254] Build 8856008858543594384: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:24:33.254] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:26:0) after 1m0s
[22:25:33.328] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:26:0)
[22:25:33.329] Timer tick, asking Buildbucket for the build status
[22:25:33.415] Build 8856008858543594384: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:25:33.415] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:27:0) after 1m0s
[22:26:33.435] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:27:0)
[22:26:33.435] Timer tick, asking Buildbucket for the build status
[22:26:33.530] Build 8856008858543594384: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:26:33.530] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:28:0) after 1m0s
[22:27:33.534] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:28:0)
[22:27:33.534] Timer tick, asking Buildbucket for the build status
[22:27:33.685] Build 8856008858543594384: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:27:33.685] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:29:0) after 1m0s
[22:28:33.712] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:29:0)
[22:28:33.712] Timer tick, asking Buildbucket for the build status
[22:28:33.775] Build 8856008858543594384: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:28:33.775] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:30:0) after 1m0s
[22:29:33.789] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:30:0)
[22:29:33.789] Timer tick, asking Buildbucket for the build status
[22:29:34.208] Build 8856008858543594384: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:29:34.208] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:31:0) after 1m0s
[22:30:34.311] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:31:0)
[22:30:34.311] Timer tick, asking Buildbucket for the build status
[22:30:34.423] Build 8856008858543594384: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:30:34.423] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:32:0) after 1m0s
[22:31:34.466] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:32:0)
[22:31:34.466] Timer tick, asking Buildbucket for the build status
[22:31:34.561] Build 8856008858543594384: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:31:34.562] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:33:0) after 1m0s
[22:32:34.608] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:33:0)
[22:32:34.608] Timer tick, asking Buildbucket for the build status
[22:32:34.676] Build 8856008858543594384: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:32:34.676] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:34:0) after 1m0s
[22:33:34.699] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:34:0)
[22:33:34.699] Timer tick, asking Buildbucket for the build status
[22:33:34.984] Build 8856008858543594384: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:33:34.984] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:35:0) after 1m0s
[22:34:35.035] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:35:0)
[22:34:35.035] Timer tick, asking Buildbucket for the build status
[22:34:35.230] Build 8856008858543594384: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:34:35.230] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:36:0) after 1m0s
[22:35:35.286] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:36:0)
[22:35:35.286] Timer tick, asking Buildbucket for the build status
[22:35:35.414] Build 8856008858543594384: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:35:35.414] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:37:0) after 1m0s
[22:36:35.412] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:37:0)
[22:36:35.412] Timer tick, asking Buildbucket for the build status
[22:36:35.477] Build 8856008858543594384: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:36:35.477] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:38:0) after 1m0s
[22:37:35.591] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:38:0)
[22:37:35.591] Timer tick, asking Buildbucket for the build status
[22:37:35.655] Build 8856008858543594384: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:37:35.655] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:39:0) after 1m0s
[22:38:35.769] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:39:0)
[22:38:35.769] Timer tick, asking Buildbucket for the build status
[22:38:36.009] Build 8856008858543594384: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:38:36.009] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:40:0) after 1m0s
[22:39:36.010] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:40:0)
[22:39:36.010] Timer tick, asking Buildbucket for the build status
[22:39:36.081] Build 8856008858543594384: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:39:36.081] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:41:0) after 1m0s
[22:40:36.103] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:41:0)
[22:40:36.103] Timer tick, asking Buildbucket for the build status
[22:40:36.714] Build 8856008858543594384: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:40:36.714] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:42:0) after 1m0s
[22:41:36.865] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:42:0)
[22:41:36.865] Timer tick, asking Buildbucket for the build status
[22:41:36.932] Build 8856008858543594384: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:41:36.932] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:43:0) after 1m0s
[22:42:36.984] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:43:0)
[22:42:36.984] Timer tick, asking Buildbucket for the build status
[22:42:37.083] Build 8856008858543594384: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:42:37.083] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:44:0) after 1m0s
[22:43:37.140] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:44:0)
[22:43:37.140] Timer tick, asking Buildbucket for the build status
[22:43:37.211] Build 8856008858543594384: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:43:37.211] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:45:0) after 1m0s
[22:44:37.231] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:45:0)
[22:44:37.231] Timer tick, asking Buildbucket for the build status
[22:44:37.495] Build 8856008858543594384: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:44:37.495] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:46:0) after 1m0s
[22:45:37.548] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:46:0)
[22:45:37.548] Timer tick, asking Buildbucket for the build status
[22:45:37.795] Build 8856008858543594384: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:45:37.795] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:47:0) after 1m0s
[22:46:37.807] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:47:0)
[22:46:37.807] Timer tick, asking Buildbucket for the build status
[22:46:37.973] Build 8856008858543594384: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:46:37.973] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:48:0) after 1m0s
[22:47:38.006] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:48:0)
[22:47:38.006] Timer tick, asking Buildbucket for the build status
[22:47:38.073] Build 8856008858543594384: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:47:38.073] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:49:0) after 1m0s
[22:47:48.415] Received PubSub notification, asking Buildbucket for the build status
[22:47:48.541] Build 8856008858543594384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:48:38.059] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:49:0)
[22:48:38.059] Timer tick, asking Buildbucket for the build status
[22:48:38.186] Build 8856008858543594384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:48:38.186] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:51:0) after 1m0s
[22:49:38.212] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:51:0)
[22:49:38.212] Timer tick, asking Buildbucket for the build status
[22:49:38.378] Build 8856008858543594384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:49:38.378] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:52:0) after 1m0s
[22:50:38.389] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:52:0)
[22:50:38.389] Timer tick, asking Buildbucket for the build status
[22:50:38.494] Build 8856008858543594384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:50:38.494] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:53:0) after 1m0s
[22:51:38.514] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:53:0)
[22:51:38.514] Timer tick, asking Buildbucket for the build status
[22:51:38.710] Build 8856008858543594384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:51:38.710] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:54:0) after 1m0s
[22:52:38.728] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:54:0)
[22:52:38.728] Timer tick, asking Buildbucket for the build status
[22:52:38.838] Build 8856008858543594384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:52:38.838] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:55:0) after 1m0s
[22:53:38.855] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:55:0)
[22:53:38.855] Timer tick, asking Buildbucket for the build status
[22:53:39.111] Build 8856008858543594384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:53:39.111] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:56:0) after 1m0s
[22:54:39.162] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:56:0)
[22:54:39.162] Timer tick, asking Buildbucket for the build status
[22:54:39.291] Build 8856008858543594384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:54:39.291] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:57:0) after 1m0s
[22:55:39.392] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:57:0)
[22:55:39.392] Timer tick, asking Buildbucket for the build status
[22:55:39.642] Build 8856008858543594384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:55:39.642] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:58:0) after 1m0s
[22:56:39.698] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:58:0)
[22:56:39.698] Timer tick, asking Buildbucket for the build status
[22:56:39.767] Build 8856008858543594384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:56:39.767] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:59:0) after 1m0s
[22:57:40.255] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:59:0)
[22:57:40.255] Timer tick, asking Buildbucket for the build status
[22:57:40.520] Build 8856008858543594384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:57:40.520] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:60:0) after 1m0s
[22:58:40.668] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:60:0)
[22:58:40.668] Timer tick, asking Buildbucket for the build status
[22:58:40.735] Build 8856008858543594384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:58:40.735] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:61:0) after 1m0s
[22:59:40.834] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:61:0)
[22:59:40.834] Timer tick, asking Buildbucket for the build status
[22:59:41.014] Build 8856008858543594384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:59:41.014] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:62:0) after 1m0s
[23:00:41.125] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:62:0)
[23:00:41.125] Timer tick, asking Buildbucket for the build status
[23:00:41.213] Build 8856008858543594384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:00:41.213] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:63:0) after 1m0s
[23:01:41.394] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:63:0)
[23:01:41.394] Timer tick, asking Buildbucket for the build status
[23:01:41.541] Build 8856008858543594384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:01:41.541] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:64:0) after 1m0s
[23:02:41.657] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:64:0)
[23:02:41.657] Timer tick, asking Buildbucket for the build status
[23:02:42.195] Build 8856008858543594384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:02:42.195] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:65:0) after 1m0s
[23:03:42.305] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:65:0)
[23:03:42.305] Timer tick, asking Buildbucket for the build status
[23:03:42.517] Build 8856008858543594384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:03:42.517] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:66:0) after 1m0s
[23:04:42.631] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:66:0)
[23:04:42.631] Timer tick, asking Buildbucket for the build status
[23:04:42.897] Build 8856008858543594384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:04:42.897] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:67:0) after 1m0s
[23:05:43.015] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:67:0)
[23:05:43.016] Timer tick, asking Buildbucket for the build status
[23:05:43.250] Build 8856008858543594384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:05:43.250] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:68:0) after 1m0s
[23:06:43.271] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:68:0)
[23:06:43.271] Timer tick, asking Buildbucket for the build status
[23:06:43.339] Build 8856008858543594384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:06:43.339] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:69:0) after 1m0s
[23:07:43.391] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:69:0)
[23:07:43.391] Timer tick, asking Buildbucket for the build status
[23:07:43.536] Build 8856008858543594384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:07:43.536] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:70:0) after 1m0s
[23:08:43.559] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:70:0)
[23:08:43.559] Timer tick, asking Buildbucket for the build status
[23:08:43.750] Build 8856008858543594384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:08:43.750] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:71:0) after 1m0s
[23:09:43.793] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:71:0)
[23:09:43.793] Timer tick, asking Buildbucket for the build status
[23:09:43.946] Build 8856008858543594384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:09:43.946] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:72:0) after 1m0s
[23:10:44.080] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:72:0)
[23:10:44.080] Timer tick, asking Buildbucket for the build status
[23:10:44.228] Build 8856008858543594384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:10:44.228] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:73:0) after 1m0s
[23:11:44.226] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:73:0)
[23:11:44.226] Timer tick, asking Buildbucket for the build status
[23:11:44.413] Build 8856008858543594384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:11:44.413] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:74:0) after 1m0s
[23:12:44.582] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:74:0)
[23:12:44.582] Timer tick, asking Buildbucket for the build status
[23:12:44.662] Build 8856008858543594384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:12:44.662] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:75:0) after 1m0s
[23:13:44.863] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:75:0)
[23:13:44.863] Timer tick, asking Buildbucket for the build status
[23:13:44.939] Build 8856008858543594384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:13:44.939] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:76:0) after 1m0s
[23:14:45.051] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:76:0)
[23:14:45.051] Timer tick, asking Buildbucket for the build status
[23:14:45.114] Build 8856008858543594384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:14:45.114] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:77:0) after 1m0s
[23:15:45.172] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:77:0)
[23:15:45.172] Timer tick, asking Buildbucket for the build status
[23:15:45.278] Build 8856008858543594384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:15:45.278] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:78:0) after 1m0s
[23:16:45.313] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:78:0)
[23:16:45.313] Timer tick, asking Buildbucket for the build status
[23:16:45.577] Build 8856008858543594384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:16:45.577] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:79:0) after 1m0s
[23:17:45.822] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:79:0)
[23:17:45.822] Timer tick, asking Buildbucket for the build status
[23:17:45.888] Build 8856008858543594384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:17:45.888] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:80:0) after 1m0s
[23:18:46.182] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:80:0)
[23:18:46.182] Timer tick, asking Buildbucket for the build status
[23:18:46.620] Build 8856008858543594384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:18:46.620] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:81:0) after 1m0s
[23:19:46.795] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:81:0)
[23:19:46.795] Timer tick, asking Buildbucket for the build status
[23:19:47.255] Build 8856008858543594384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:19:47.255] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:82:0) after 1m0s
[23:20:47.319] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:82:0)
[23:20:47.319] Timer tick, asking Buildbucket for the build status
[23:20:47.543] Build 8856008858543594384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:20:47.543] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:83:0) after 1m0s
[23:21:47.597] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:83:0)
[23:21:47.597] Timer tick, asking Buildbucket for the build status
[23:21:47.656] Build 8856008858543594384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:21:47.656] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:84:0) after 1m0s
[23:22:47.675] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:84:0)
[23:22:47.675] Timer tick, asking Buildbucket for the build status
[23:22:47.737] Build 8856008858543594384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:22:47.737] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:85:0) after 1m0s
[23:23:47.797] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:85:0)
[23:23:47.797] Timer tick, asking Buildbucket for the build status
[23:23:47.931] Build 8856008858543594384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:23:47.931] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:86:0) after 1m0s
[23:24:47.988] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:86:0)
[23:24:47.988] Timer tick, asking Buildbucket for the build status
[23:24:48.057] Build 8856008858543594384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:24:48.057] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:87:0) after 1m0s
[23:25:48.096] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:87:0)
[23:25:48.096] Timer tick, asking Buildbucket for the build status
[23:25:48.282] Build 8856008858543594384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:25:48.282] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:88:0) after 1m0s
[23:26:48.277] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:88:0)
[23:26:48.277] Timer tick, asking Buildbucket for the build status
[23:26:48.532] Build 8856008858543594384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:26:48.532] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:89:0) after 1m0s
[23:27:48.584] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:89:0)
[23:27:48.584] Timer tick, asking Buildbucket for the build status
[23:27:48.675] Build 8856008858543594384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:27:48.675] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:90:0) after 1m0s
[23:28:48.699] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:90:0)
[23:28:48.699] Timer tick, asking Buildbucket for the build status
[23:28:48.839] Build 8856008858543594384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:28:48.839] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:91:0) after 1m0s
[23:29:48.891] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:91:0)
[23:29:48.891] Timer tick, asking Buildbucket for the build status
[23:29:49.158] Build 8856008858543594384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:29:49.158] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:92:0) after 1m0s
[23:30:49.296] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:92:0)
[23:30:49.296] Timer tick, asking Buildbucket for the build status
[23:30:49.500] Build 8856008858543594384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:30:49.500] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:93:0) after 1m0s
[23:31:49.527] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:93:0)
[23:31:49.527] Timer tick, asking Buildbucket for the build status
[23:31:49.583] Build 8856008858543594384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:31:49.583] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:94:0) after 1m0s
[23:32:49.595] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:94:0)
[23:32:49.595] Timer tick, asking Buildbucket for the build status
[23:32:49.842] Build 8856008858543594384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:32:49.843] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:95:0) after 1m0s
[23:33:49.863] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:95:0)
[23:33:49.863] Timer tick, asking Buildbucket for the build status
[23:33:50.061] Build 8856008858543594384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:33:50.061] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:96:0) after 1m0s
[23:34:50.122] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:96:0)
[23:34:50.122] Timer tick, asking Buildbucket for the build status
[23:34:50.301] Build 8856008858543594384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:34:50.301] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:97:0) after 1m0s
[23:35:50.278] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:97:0)
[23:35:50.278] Timer tick, asking Buildbucket for the build status
[23:35:50.406] Build 8856008858543594384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:35:50.406] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:98:0) after 1m0s
[23:36:50.456] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:98:0)
[23:36:50.456] Timer tick, asking Buildbucket for the build status
[23:36:50.629] Build 8856008858543594384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:36:50.629] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:99:0) after 1m0s
[23:37:50.635] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:99:0)
[23:37:50.635] Timer tick, asking Buildbucket for the build status
[23:37:50.824] Build 8856008858543594384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:37:50.824] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:100:0) after 1m0s
[23:38:50.878] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:100:0)
[23:38:50.878] Timer tick, asking Buildbucket for the build status
[23:38:51.247] Build 8856008858543594384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:38:51.247] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:101:0) after 1m0s
[23:39:51.268] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:101:0)
[23:39:51.268] Timer tick, asking Buildbucket for the build status
[23:39:51.568] Build 8856008858543594384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:39:51.568] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:102:0) after 1m0s
[23:40:51.622] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:102:0)
[23:40:51.622] Timer tick, asking Buildbucket for the build status
[23:40:52.057] Build 8856008858543594384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:40:52.057] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:103:0) after 1m0s
[23:41:52.088] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:103:0)
[23:41:52.088] Timer tick, asking Buildbucket for the build status
[23:41:52.180] Build 8856008858543594384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:41:52.180] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:104:0) after 1m0s
[23:42:52.207] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:104:0)
[23:42:52.207] Timer tick, asking Buildbucket for the build status
[23:42:52.284] Build 8856008858543594384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:42:52.284] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:105:0) after 1m0s
[23:43:52.369] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:105:0)
[23:43:52.369] Timer tick, asking Buildbucket for the build status
[23:43:52.496] Build 8856008858543594384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:43:52.496] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:106:0) after 1m0s
[23:44:52.529] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:106:0)
[23:44:52.529] Timer tick, asking Buildbucket for the build status
[23:44:52.640] Build 8856008858543594384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:44:52.640] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:107:0) after 1m0s
[23:45:52.665] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:107:0)
[23:45:52.665] Timer tick, asking Buildbucket for the build status
[23:45:52.801] Build 8856008858543594384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:45:52.801] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:108:0) after 1m0s
[23:46:52.873] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:108:0)
[23:46:52.873] Timer tick, asking Buildbucket for the build status
[23:46:52.950] Build 8856008858543594384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:46:52.950] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:109:0) after 1m0s
[23:47:52.958] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:109:0)
[23:47:52.958] Timer tick, asking Buildbucket for the build status
[23:47:53.074] Build 8856008858543594384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:47:53.074] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:110:0) after 1m0s
[23:48:53.117] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:110:0)
[23:48:53.117] Timer tick, asking Buildbucket for the build status
[23:48:53.304] Build 8856008858543594384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:48:53.304] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:111:0) after 1m0s
[23:49:53.311] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:111:0)
[23:49:53.311] Timer tick, asking Buildbucket for the build status
[23:49:53.700] Build 8856008858543594384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:49:53.700] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:112:0) after 1m0s
[23:50:53.760] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:112:0)
[23:50:53.760] Timer tick, asking Buildbucket for the build status
[23:50:53.854] Build 8856008858543594384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:50:53.854] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:113:0) after 1m0s
[23:51:53.876] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:113:0)
[23:51:53.876] Timer tick, asking Buildbucket for the build status
[23:51:54.207] Build 8856008858543594384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:51:54.207] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:114:0) after 1m0s
[23:52:54.231] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:114:0)
[23:52:54.231] Timer tick, asking Buildbucket for the build status
[23:52:54.363] Build 8856008858543594384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:52:54.363] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:115:0) after 1m0s
[23:53:54.345] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:115:0)
[23:53:54.345] Timer tick, asking Buildbucket for the build status
[23:53:54.739] Build 8856008858543594384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:53:54.739] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:116:0) after 1m0s
[23:54:54.849] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:116:0)
[23:54:54.849] Timer tick, asking Buildbucket for the build status
[23:54:54.929] Build 8856008858543594384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:54:54.929] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:117:0) after 1m0s
[23:55:55.071] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:117:0)
[23:55:55.071] Timer tick, asking Buildbucket for the build status
[23:55:55.199] Build 8856008858543594384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:55:55.199] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:118:0) after 1m0s
[23:56:55.293] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:118:0)
[23:56:55.293] Timer tick, asking Buildbucket for the build status
[23:56:55.798] Build 8856008858543594384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:56:55.798] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:119:0) after 1m0s
[23:57:55.819] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:119:0)
[23:57:55.819] Timer tick, asking Buildbucket for the build status
[23:57:55.884] Build 8856008858543594384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:57:55.884] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:120:0) after 1m0s
[23:58:55.919] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:120:0)
[23:58:55.919] Timer tick, asking Buildbucket for the build status
[23:58:55.998] Build 8856008858543594384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:58:55.998] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:121:0) after 1m0s
[23:59:56.073] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:121:0)
[23:59:56.073] Timer tick, asking Buildbucket for the build status
[23:59:56.143] Build 8856008858543594384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:59:56.143] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:122:0) after 1m0s
[00:00:56.222] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:122:0)
[00:00:56.222] Timer tick, asking Buildbucket for the build status
[00:00:56.342] Build 8856008858543594384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:00:56.342] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:123:0) after 1m0s
[00:01:56.383] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:123:0)
[00:01:56.383] Timer tick, asking Buildbucket for the build status
[00:01:56.736] Build 8856008858543594384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:01:56.736] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:124:0) after 1m0s
[00:02:56.812] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:124:0)
[00:02:56.812] Timer tick, asking Buildbucket for the build status
[00:02:57.307] Build 8856008858543594384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:02:57.307] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:125:0) after 1m0s
[00:03:57.439] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:125:0)
[00:03:57.439] Timer tick, asking Buildbucket for the build status
[00:03:57.507] Build 8856008858543594384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:03:57.507] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:126:0) after 1m0s
[00:04:57.714] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:126:0)
[00:04:57.714] Timer tick, asking Buildbucket for the build status
[00:04:57.785] Build 8856008858543594384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:04:57.785] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:127:0) after 1m0s
[00:05:58.068] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:127:0)
[00:05:58.068] Timer tick, asking Buildbucket for the build status
[00:05:58.255] Build 8856008858543594384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:05:58.255] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:128:0) after 1m0s
[00:06:58.320] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:128:0)
[00:06:58.320] Timer tick, asking Buildbucket for the build status
[00:06:58.424] Build 8856008858543594384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:06:58.424] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:129:0) after 1m0s
[00:07:58.703] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:129:0)
[00:07:58.703] Timer tick, asking Buildbucket for the build status
[00:07:59.311] Build 8856008858543594384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:07:59.311] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:130:0) after 1m0s
[00:08:59.446] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:130:0)
[00:08:59.446] Timer tick, asking Buildbucket for the build status
[00:08:59.871] Build 8856008858543594384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:08:59.871] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:131:0) after 1m0s
[00:09:59.896] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:131:0)
[00:09:59.896] Timer tick, asking Buildbucket for the build status
[00:09:59.962] Build 8856008858543594384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:09:59.962] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:132:0) after 1m0s
[00:10:59.968] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:132:0)
[00:10:59.968] Timer tick, asking Buildbucket for the build status
[00:11:00.218] Build 8856008858543594384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:11:00.218] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:133:0) after 1m0s
[00:12:00.288] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:133:0)
[00:12:00.288] Timer tick, asking Buildbucket for the build status
[00:12:00.364] Build 8856008858543594384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:12:00.364] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:134:0) after 1m0s
[00:13:00.511] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:134:0)
[00:13:00.511] Timer tick, asking Buildbucket for the build status
[00:13:00.637] Build 8856008858543594384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:13:00.637] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:135:0) after 1m0s
[00:14:00.689] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:135:0)
[00:14:00.689] Timer tick, asking Buildbucket for the build status
[00:14:01.539] Build 8856008858543594384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:14:01.539] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:136:0) after 1m0s
[00:15:01.562] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:136:0)
[00:15:01.562] Timer tick, asking Buildbucket for the build status
[00:15:01.918] Build 8856008858543594384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:15:01.918] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:137:0) after 1m0s
[00:16:02.001] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:137:0)
[00:16:02.001] Timer tick, asking Buildbucket for the build status
[00:16:02.214] Build 8856008858543594384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:16:02.214] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:138:0) after 1m0s
[00:17:02.240] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:138:0)
[00:17:02.240] Timer tick, asking Buildbucket for the build status
[00:17:02.318] Build 8856008858543594384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:17:02.318] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:139:0) after 1m0s
[00:18:02.372] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:139:0)
[00:18:02.372] Timer tick, asking Buildbucket for the build status
[00:18:02.553] Build 8856008858543594384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:18:02.553] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:140:0) after 1m0s
[00:19:02.623] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:140:0)
[00:19:02.623] Timer tick, asking Buildbucket for the build status
[00:19:02.687] Build 8856008858543594384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:19:02.687] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:141:0) after 1m0s
[00:20:02.799] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:141:0)
[00:20:02.799] Timer tick, asking Buildbucket for the build status
[00:20:02.957] Build 8856008858543594384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:20:02.957] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:142:0) after 1m0s
[00:21:03.018] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:142:0)
[00:21:03.018] Timer tick, asking Buildbucket for the build status
[00:21:03.141] Build 8856008858543594384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:21:03.142] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:143:0) after 1m0s
[00:22:03.117] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:143:0)
[00:22:03.117] Timer tick, asking Buildbucket for the build status
[00:22:03.448] Build 8856008858543594384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:22:03.448] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:144:0) after 1m0s
[00:23:03.518] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:144:0)
[00:23:03.518] Timer tick, asking Buildbucket for the build status
[00:23:04.705] Build 8856008858543594384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:23:04.705] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:145:0) after 1m0s
[00:24:04.729] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:145:0)
[00:24:04.729] Timer tick, asking Buildbucket for the build status
[00:24:04.958] Build 8856008858543594384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:24:04.958] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:146:0) after 1m0s
[00:25:05.046] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:146:0)
[00:25:05.046] Timer tick, asking Buildbucket for the build status
[00:25:05.151] Build 8856008858543594384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:25:05.151] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:147:0) after 1m0s
[00:26:05.136] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:147:0)
[00:26:05.136] Timer tick, asking Buildbucket for the build status
[00:26:05.279] Build 8856008858543594384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:26:05.279] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:148:0) after 1m0s
[00:27:05.461] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:148:0)
[00:27:05.461] Timer tick, asking Buildbucket for the build status
[00:27:05.607] Build 8856008858543594384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:27:05.607] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:149:0) after 1m0s
[00:28:05.701] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:149:0)
[00:28:05.701] Timer tick, asking Buildbucket for the build status
[00:28:06.061] Build 8856008858543594384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:28:06.061] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:150:0) after 1m0s
[00:29:06.158] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:150:0)
[00:29:06.158] Timer tick, asking Buildbucket for the build status
[00:29:06.231] Build 8856008858543594384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:29:06.231] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:151:0) after 1m0s
[00:30:06.286] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:151:0)
[00:30:06.286] Timer tick, asking Buildbucket for the build status
[00:30:06.383] Build 8856008858543594384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:30:06.383] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:152:0) after 1m0s
[00:31:06.791] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:152:0)
[00:31:06.791] Timer tick, asking Buildbucket for the build status
[00:31:06.925] Build 8856008858543594384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:31:06.925] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:153:0) after 1m0s
[00:32:07.047] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:153:0)
[00:32:07.047] Timer tick, asking Buildbucket for the build status
[00:32:07.384] Build 8856008858543594384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:32:07.384] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:154:0) after 1m0s
[00:33:07.417] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:154:0)
[00:33:07.417] Timer tick, asking Buildbucket for the build status
[00:33:07.907] Build 8856008858543594384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:33:07.907] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:155:0) after 1m0s
[00:34:08.013] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:155:0)
[00:34:08.013] Timer tick, asking Buildbucket for the build status
[00:34:08.438] Build 8856008858543594384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:34:08.438] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:156:0) after 1m0s
[00:35:08.518] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:156:0)
[00:35:08.519] Timer tick, asking Buildbucket for the build status
[00:35:08.816] Build 8856008858543594384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:35:08.816] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9021438920575061360:157:0) after 1m0s
[00:35:14.709] Received PubSub notification, asking Buildbucket for the build status
[00:35:15.325] Build 8856008858543594384: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[00:35:15.325] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1612658114145789",
"created_by": "project:chromium",
"created_ts": "1612648827948220",
"id": "8856008858543594384",
"parameters_json": "{\"builder_name\": \"linux-chromeos-dbg\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"fd6e4bf7bc4eeaf657dbc548bdcc7b61d07f7934\"}}",
"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\": \"fd6e4bf7bc4eeaf657dbc548bdcc7b61d07f7934\", \"project\": \"chromium/src\"}], \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-chromium-ci-xenial-us-east1-d-8-31-63xk\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-dbg\", \"buildnumber\": 22512, \"got_angle_revision\": \"d820ec7b3e5f9412d1ceb55d31b3f7dc09e3d16d\", \"got_dawn_revision\": \"2831f0584028114a8896059ce00e7bddaf3bba1f\", \"got_nacl_revision\": \"8db20f20316f13d8886a2732099f5bb880568663\", \"got_revision\": \"fd6e4bf7bc4eeaf657dbc548bdcc7b61d07f7934\", \"got_revision_cp\": \"refs/heads/master@{#851500}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"266614a36dbd5328a9079ea6bbef9026c7bbe3d7\", \"got_v8_revision\": \"711403215714087a74f05b1382b18e4c318be936\", \"got_v8_revision_cp\": \"refs/heads/9.0.137@{#1}\", \"got_webrtc_revision\": \"d42413a4b4636422ba5ea66ba40e7d24fb64f9b1\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33183}\", \"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\": \"fd6e4bf7bc4eeaf657dbc548bdcc7b61d07f7934\", \"swarm_hashes_refs/heads/master(at){#851500}_without_patch\": {\"absl_hardening_tests\": \"4dbe98820477d9d933f6b2360b2a3aabe7b7249a\", \"accessibility_unittests\": \"478ccc06467eac8e61d3f6dfaf461510c8a55dad\", \"angle_unittests\": \"e03fe8289ef93885c7779c057cf8f2451d0f93eb\", \"app_list_unittests\": \"782b1e18c4782d45a37d24a6e3c731dd0680efcd\", \"app_shell_unittests\": \"6ba6dce14f8aeffb54863340e23917f7dc3187ed\", \"ash_unittests\": \"32bb43127d19958f5ef7f5ecbeccca3ace4e1f4c\", \"aura_unittests\": \"ff17aa97e040543050109a1acdd90b21b5831bf2\", \"base_unittests\": \"c9e565893c6af8cd6f51a139bfd51d3888c5546d\", \"base_util_unittests\": \"d85e80e3318d6fd63607cfa98c53fadff2d9cca4\", \"blink_common_unittests\": \"96a4c38530df5477dbee3034903405321cf2782f\", \"blink_fuzzer_unittests\": \"e0e740c33e0a109e051d3118a0c6dff8bc37dffc\", \"blink_heap_unittests\": \"d91b322d45a91dfd076236cbdfa0a870176f0149\", \"blink_platform_unittests\": \"af4cb033b814340dd613e5621772bfb893fbf441\", \"blink_unittests\": \"16799d7e776bbacb8fd7bcd4e08b8b3c5e2c52d0\", \"boringssl_crypto_tests\": \"2b1bb5bc015cd5911d8a7ea1665046f53b4076dc\", \"boringssl_ssl_tests\": \"be89dce7878e4825b3f387277e2834910a2954e2\", \"browser_tests\": \"a6a78876cde4064f19dad20cb66d4b451e75af03\", \"capture_unittests\": \"a150fe2052754a4540bed51e9c2953edebda4110\", \"cast_unittests\": \"bc45f0f81fc11154384c52d28fa16bd1d11fe870\", \"cc_unittests\": \"c87a345a6c2ae64789e31448bd72110b0b7008a7\", \"chrome_app_unittests\": \"f9a68fb0b8cd51323b8fc362dce4b2bbe80d6561\", \"chromedriver_unittests\": \"a288d91c2a2f2d9916957d7a6fa94e00d23193ab\", \"chromeos_components_unittests\": \"568b6f887b31e7f0a6702e8c6659a45cbcada7c7\", \"chromeos_unittests\": \"53fd4d07bf13ada4249c51309428364345b6c2b8\", \"components_browsertests\": \"831b3f83ca58d472c3b5ffbd2467ad46628dc532\", \"components_unittests\": \"93ac9fecc5be067590d60470a6f52aff351cce84\", \"compositor_unittests\": \"897ee86ecdd37a6cbfb966a3a7ef7a0694d36d9c\", \"content_browsertests\": \"5dd548138984acfec275f9f8824dccd0b2ca98fc\", \"content_unittests\": \"058b5143a139436c2701beb31deffc6b3679e41b\", \"crashpad_tests\": \"8780fb7482661e4315a0a6427cf081502164ec89\", \"crypto_unittests\": \"da985ebcfdec43846d73d1514895989f5f41a419\", \"dbus_unittests\": \"b1cb5a1c6a2000c80d4a389a9a9325a65b62565b\", \"device_unittests\": \"eeff1c2837e537fe860497f23ee3123770d6697e\", \"display_unittests\": \"70a268467354279017c055b8cef2efe9d5df97b7\", \"events_unittests\": \"1668a022dbb96d5dc6b8a8bbe095f2bc05f7c38b\", \"exo_unittests\": \"76b764dd9c9687c39f9ef72604956d05112e4dcb\", \"extensions_browsertests\": \"a30ddc94af2af4ac94f557e3d906e90ffe745eaa\", \"extensions_unittests\": \"2894ab89553d3743a80db35c40fad3ee6c7ddd8b\", \"filesystem_service_unittests\": \"c3b9b098933961ff71a56b0450973b2a78045f58\", \"gcm_unit_tests\": \"df0d731a8678cf6d1c71306c4237d65f9751656c\", \"gfx_unittests\": \"53f19500fbbbc3ee7103ba3b72c8eb6f500a30b2\", \"gin_unittests\": \"b5978ea87ab63b5cb0ca53e16d86d9914f58a16f\", \"gl_unittests_ozone\": \"b113c635eecf915e184a7b57a4b2a05e679ecacb\", \"google_apis_unittests\": \"bd88285ba27afeddc496f1d5229a067e8a798795\", \"gpu_unittests\": \"3d1e50951c5b0382faca6f1c897678c39ee903b7\", \"gwp_asan_unittests\": \"8f6b727c2c87f6f14257a19e0e6c7346a80e2d6f\", \"interactive_ui_tests\": \"56724cd2078b50792cd8484bfad06b51d5918bec\", \"ipc_tests\": \"7c716c0d03ede200f83e45289c3e1234c102c4d1\", \"jingle_unittests\": \"9ea8ad9b0f4c6b620c988c71a1c9469953c54f37\", \"keyboard_unittests\": \"f91f81dcf37adc4cda5320436a0d6a07f03e9926\", \"latency_unittests\": \"de3b7658e960d8b78e1f635c8e1de3eb06876d59\", \"libjingle_xmpp_unittests\": \"0834ce4d8f97ee5e133a3e6833929a2fb0d42e50\", \"liburlpattern_unittests\": \"d5d09c167def38b38d7d33c0a383dfd76f3b9cc4\", \"media_blink_unittests\": \"56fb00d24e75befbd02513e39aeac3c0d59e0bd9\", \"media_unittests\": \"b3f7b52a2f803c0cc7c5e3a2b47674f3156d1ac0\", \"message_center_unittests\": \"550b18a602966e10dbd130ad48260174cd9bc1a0\", \"midi_unittests\": \"51afeb4c70e8c8e161bd4edcbdd378900831236a\", \"mojo_core_unittests\": \"a9dee07b20f921a96bb0d0deda1119684257c369\", \"mojo_unittests\": \"61c86b6267b12c999ee7c3882a1ef29569618908\", \"nacl_helper_nonsfi_unittests\": \"012b544237dedf0426cd8908518af0340077dff2\", \"nacl_loader_unittests\": \"6e536bf90e816395e46a06fb9223cd24e80da3af\", \"native_theme_unittests\": \"c7f42b92a02e610af28712382927d4a3ef9f4b79\", \"net_unittests\": \"f10e3a6c9177b3cead606c6728cf9ab4a8bbd014\", \"ozone_gl_unittests\": \"56e20e092f9668353241025edaa1d4b2ceee42c8\", \"ozone_unittests\": \"170488bf4b7457dcb254d389c2ccb54580d03d0e\", \"ozone_x11_unittests\": \"8c9743f474cfaf65e40fc7120a7c7bf193806d50\", \"pdf_unittests\": \"ad350f92c01bc781735da88098013546a35b31af\", \"perfetto_unittests\": \"ed54d644611a338be6c8ad22b95564fd463051b3\", \"ppapi_unittests\": \"61180dd377c5c681fe78ec6872ed1b77e36c1af0\", \"printing_unittests\": \"d7e12abb0be60f0ced9d4616a24e91e86c8726ec\", \"remoting_unittests\": \"b2245b6e968146bd98a508cb8d70014af080db03\", \"sandbox_linux_unittests\": \"02f5deab70f53893cf427eac2a401505a3f3b41d\", \"service_manager_unittests\": \"e5cfb13a0762c6e1276c7ebffc5b641971eb953a\", \"services_unittests\": \"9cd4f34d111cd6b60de3bfb95bf5ba0800926ef6\", \"shell_dialogs_unittests\": \"2efb8365e798b2e065de051ed1ebac6d8cc83769\", \"shell_encryption_unittests\": \"428cf506ac4b25a585437b71210072bfff61e24b\", \"skia_unittests\": \"1f1e9cc32a350f63c4e3c1cdf9a3fe461f8d31f1\", \"snapshot_unittests\": \"1d8e9b78089816421e757c598e17c2c3b9309639\", \"sql_unittests\": \"23c73f129957141031a634def50d420dade4e334\", \"storage_unittests\": \"5673c2c636e876fe3b6571f0c7f7a6503fda562d\", \"sync_integration_tests\": \"1fab606a00f587f88ea266cf21da398d3001a67d\", \"traffic_annotation_auditor_unittests\": \"7d55d817dcea4542eb15803350888651b419abc5\", \"ui_base_unittests\": \"763206e8a2d3d38403d819b7d5e42b2f1f9a8e8b\", \"ui_chromeos_unittests\": \"2d8f2986fa03cb349a754b1c7db2c16d662538cc\", \"ui_touch_selection_unittests\": \"8e9a9bd59fa0a56fbec53500c3ca6b1fe71cc244\", \"unit_tests\": \"d1742550bc52cb5a314e23fdd628abda84f02c81\", \"url_unittests\": \"1a270122a7f5b63ef9a42d819e13339330438c22\", \"usage_time_limit_unittests\": \"93cc20e8b378e5ec0caf8e5872472ad59b2eed45\", \"views_unittests\": \"2decfefb5dac0cceca670cdf334371cb272d97aa\", \"viz_unittests\": \"06a5b9ab71ec0906e4c55d2bfbf4b7552b003460\", \"wm_unittests\": \"44ab041bf13aee0cde5751d3851f31aad51e4f78\", \"wtf_unittests\": \"80aa7d4119af8047253c4220ddab48840642e552\", \"zlib_unittests\": \"cd8321d8df730ee63ef2b68e8d86e45b0e5d0646\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_5165e2de7796d7dcb4f0b4d7ef826fa605d2b1188c14fc8becf427ca962c0c8e_v2\", \"builder_5315cf154f9c1ae6b2621094599bc107171a849e1c0f232ec66a504ee800a364_v2\", \"builder_98a60cf4ba341a05f49601a94ea91d205798f47ee731869ed66ec9c0f2c54ce9_v2\", \"builder_cfdb55a1f65ed072d1fe07f7fc1107a97a5e848a8d1b6b0015401ab521f6b501_v2\", \"builder_e47700e9abf1b62c06bb1feb4592b8e752d42e12f6359b4e93db4847c43379d6_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-xenial-us-east1-d-8-31-63xk\"], \"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\": [\"5676-c76598c\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-d\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1612651667446820",
"status": "COMPLETED",
"status_changed_ts": "1612658114424223",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-dbg/22512",
"builder:linux-chromeos-dbg",
"buildset:commit/git/fd6e4bf7bc4eeaf657dbc548bdcc7b61d07f7934",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/fd6e4bf7bc4eeaf657dbc548bdcc7b61d07f7934",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021438920575061360",
"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/8856008858543594384/+/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:51922dd6f9ba1a10",
"user_agent:luci-scheduler"
],
"updated_ts": "1612658114428806",
"url": "https://ci.chromium.org/b/8856008858543594384",
"utcnow_ts": "1612658115301770"
}
}
[00:35:15.325] Invocation finished in 2h34m48.698125408s with status SUCCEEDED