[14:13:14.943] New invocation is queued and will start shortly
[14:13:16.132] Starting the invocation (attempt 1)
[14:13:16.225] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[14:13:16.231] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[14:13:16.231] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9022646074504952832",
"parameters_json": "{\"builder_name\":\"linux-chromeos-dbg\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"a2183c1f1052de2e81d6772c5a81e1f4669a50d0\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-dbg",
"scheduler_invocation_id:9022646074504952832",
"scheduler_job_id:chromium/linux-chromeos-dbg",
"user_agent:luci-scheduler",
"buildset:commit/git/a2183c1f1052de2e81d6772c5a81e1f4669a50d0",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a2183c1f1052de2e81d6772c5a81e1f4669a50d0",
"gitiles_ref:refs/heads/master"
]
}
[14:13:17.728] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1611497596347760",
"id": "8857216012371227552",
"parameters_json": "{\"builder_name\": \"linux-chromeos-dbg\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"a2183c1f1052de2e81d6772c5a81e1f4669a50d0\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1611497597437719",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-dbg/22324",
"builder:linux-chromeos-dbg",
"buildset:commit/git/a2183c1f1052de2e81d6772c5a81e1f4669a50d0",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a2183c1f1052de2e81d6772c5a81e1f4669a50d0",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022646074504952832",
"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/8857216012371227552/+/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": "1611497597437859",
"url": "https://ci.chromium.org/b/8857216012371227552",
"utcnow_ts": "1611497597711796"
}
}
[14:13:17.728] Task URL: https://ci.chromium.org/b/8857216012371227552
[14:13:17.728] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:2:0) after 1m0s
[14:14:17.743] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:2:0)
[14:14:17.743] Timer tick, asking Buildbucket for the build status
[14:14:17.971] Build 8857216012371227552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:14:17.971] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:3:0) after 1m0s
[14:15:17.970] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:3:0)
[14:15:17.970] Timer tick, asking Buildbucket for the build status
[14:15:18.037] Build 8857216012371227552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:15:18.037] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:4:0) after 1m0s
[14:16:18.058] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:4:0)
[14:16:18.058] Timer tick, asking Buildbucket for the build status
[14:16:18.234] Build 8857216012371227552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:16:18.234] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:5:0) after 1m0s
[14:17:18.261] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:5:0)
[14:17:18.261] Timer tick, asking Buildbucket for the build status
[14:17:18.336] Build 8857216012371227552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:17:18.336] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:6:0) after 1m0s
[14:18:18.354] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:6:0)
[14:18:18.354] Timer tick, asking Buildbucket for the build status
[14:18:18.461] Build 8857216012371227552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:18:18.461] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:7:0) after 1m0s
[14:19:18.522] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:7:0)
[14:19:18.522] Timer tick, asking Buildbucket for the build status
[14:19:18.593] Build 8857216012371227552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:19:18.593] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:8:0) after 1m0s
[14:20:18.608] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:8:0)
[14:20:18.608] Timer tick, asking Buildbucket for the build status
[14:20:18.681] Build 8857216012371227552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:20:18.681] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:9:0) after 1m0s
[14:21:18.786] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:9:0)
[14:21:18.786] Timer tick, asking Buildbucket for the build status
[14:21:18.874] Build 8857216012371227552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:21:18.874] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:10:0) after 1m0s
[14:22:18.893] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:10:0)
[14:22:18.893] Timer tick, asking Buildbucket for the build status
[14:22:19.024] Build 8857216012371227552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:22:19.024] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:11:0) after 1m0s
[14:23:19.088] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:11:0)
[14:23:19.088] Timer tick, asking Buildbucket for the build status
[14:23:19.320] Build 8857216012371227552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:23:19.320] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:12:0) after 1m0s
[14:24:19.338] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:12:0)
[14:24:19.338] Timer tick, asking Buildbucket for the build status
[14:24:19.417] Build 8857216012371227552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:24:19.417] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:13:0) after 1m0s
[14:25:19.545] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:13:0)
[14:25:19.545] Timer tick, asking Buildbucket for the build status
[14:25:19.601] Build 8857216012371227552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:25:19.601] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:14:0) after 1m0s
[14:26:19.691] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:14:0)
[14:26:19.691] Timer tick, asking Buildbucket for the build status
[14:26:19.758] Build 8857216012371227552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:26:19.758] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:15:0) after 1m0s
[14:27:19.845] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:15:0)
[14:27:19.845] Timer tick, asking Buildbucket for the build status
[14:27:19.910] Build 8857216012371227552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:27:19.910] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:16:0) after 1m0s
[14:28:19.979] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:16:0)
[14:28:19.979] Timer tick, asking Buildbucket for the build status
[14:28:20.068] Build 8857216012371227552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:28:20.068] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:17:0) after 1m0s
[14:29:20.087] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:17:0)
[14:29:20.087] Timer tick, asking Buildbucket for the build status
[14:29:20.151] Build 8857216012371227552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:29:20.151] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:18:0) after 1m0s
[14:30:20.191] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:18:0)
[14:30:20.191] Timer tick, asking Buildbucket for the build status
[14:30:20.275] Build 8857216012371227552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:30:20.275] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:19:0) after 1m0s
[14:31:20.323] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:19:0)
[14:31:20.323] Timer tick, asking Buildbucket for the build status
[14:31:20.560] Build 8857216012371227552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:31:20.560] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:20:0) after 1m0s
[14:32:20.615] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:20:0)
[14:32:20.615] Timer tick, asking Buildbucket for the build status
[14:32:20.797] Build 8857216012371227552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:32:20.797] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:21:0) after 1m0s
[14:33:20.974] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:21:0)
[14:33:20.974] Timer tick, asking Buildbucket for the build status
[14:33:21.327] Build 8857216012371227552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:33:21.327] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:22:0) after 1m0s
[14:34:21.362] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:22:0)
[14:34:21.362] Timer tick, asking Buildbucket for the build status
[14:34:21.506] Build 8857216012371227552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:34:21.506] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:23:0) after 1m0s
[14:35:21.550] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:23:0)
[14:35:21.550] Timer tick, asking Buildbucket for the build status
[14:35:21.629] Build 8857216012371227552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:35:21.629] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:24:0) after 1m0s
[14:36:21.712] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:24:0)
[14:36:21.712] Timer tick, asking Buildbucket for the build status
[14:36:21.810] Build 8857216012371227552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:36:21.810] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:25:0) after 1m0s
[14:37:21.872] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:25:0)
[14:37:21.872] Timer tick, asking Buildbucket for the build status
[14:37:21.946] Build 8857216012371227552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:37:21.946] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:26:0) after 1m0s
[14:38:21.980] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:26:0)
[14:38:21.980] Timer tick, asking Buildbucket for the build status
[14:38:22.075] Build 8857216012371227552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:38:22.075] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:27:0) after 1m0s
[14:39:22.127] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:27:0)
[14:39:22.127] Timer tick, asking Buildbucket for the build status
[14:39:22.211] Build 8857216012371227552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:39:22.211] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:28:0) after 1m0s
[14:40:22.263] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:28:0)
[14:40:22.263] Timer tick, asking Buildbucket for the build status
[14:40:22.352] Build 8857216012371227552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:40:22.352] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:29:0) after 1m0s
[14:41:22.446] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:29:0)
[14:41:22.446] Timer tick, asking Buildbucket for the build status
[14:41:22.518] Build 8857216012371227552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:41:22.518] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:30:0) after 1m0s
[14:42:23.446] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:30:0)
[14:42:23.446] Timer tick, asking Buildbucket for the build status
[14:42:23.508] Build 8857216012371227552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:42:23.508] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:31:0) after 1m0s
[14:43:26.704] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:31:0)
[14:43:26.704] Timer tick, asking Buildbucket for the build status
[14:43:26.815] Build 8857216012371227552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:43:26.815] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:32:0) after 1m0s
[14:44:26.818] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:32:0)
[14:44:26.818] Timer tick, asking Buildbucket for the build status
[14:44:26.885] Build 8857216012371227552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:44:26.885] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:33:0) after 1m0s
[14:45:26.878] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:33:0)
[14:45:26.878] Timer tick, asking Buildbucket for the build status
[14:45:26.974] Build 8857216012371227552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:45:26.974] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:34:0) after 1m0s
[14:46:26.996] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:34:0)
[14:46:26.996] Timer tick, asking Buildbucket for the build status
[14:46:27.076] Build 8857216012371227552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:46:27.076] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:35:0) after 1m0s
[14:47:27.111] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:35:0)
[14:47:27.111] Timer tick, asking Buildbucket for the build status
[14:47:27.176] Build 8857216012371227552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:47:27.176] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:36:0) after 1m0s
[14:48:27.196] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:36:0)
[14:48:27.196] Timer tick, asking Buildbucket for the build status
[14:48:27.306] Build 8857216012371227552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:48:27.306] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:37:0) after 1m0s
[14:49:27.371] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:37:0)
[14:49:27.371] Timer tick, asking Buildbucket for the build status
[14:49:27.433] Build 8857216012371227552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:49:27.433] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:38:0) after 1m0s
[14:50:27.514] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:38:0)
[14:50:27.514] Timer tick, asking Buildbucket for the build status
[14:50:27.608] Build 8857216012371227552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:50:27.608] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:39:0) after 1m0s
[14:51:27.652] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:39:0)
[14:51:27.652] Timer tick, asking Buildbucket for the build status
[14:51:27.718] Build 8857216012371227552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:51:27.718] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:40:0) after 1m0s
[14:52:27.800] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:40:0)
[14:52:27.800] Timer tick, asking Buildbucket for the build status
[14:52:27.889] Build 8857216012371227552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:52:27.889] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:41:0) after 1m0s
[14:53:27.948] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:41:0)
[14:53:27.948] Timer tick, asking Buildbucket for the build status
[14:53:28.064] Build 8857216012371227552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:53:28.064] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:42:0) after 1m0s
[14:54:28.055] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:42:0)
[14:54:28.055] Timer tick, asking Buildbucket for the build status
[14:54:28.131] Build 8857216012371227552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:54:28.132] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:43:0) after 1m0s
[14:55:28.149] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:43:0)
[14:55:28.149] Timer tick, asking Buildbucket for the build status
[14:55:28.392] Build 8857216012371227552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:55:28.392] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:44:0) after 1m0s
[14:56:28.417] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:44:0)
[14:56:28.417] Timer tick, asking Buildbucket for the build status
[14:56:28.593] Build 8857216012371227552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:56:28.593] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:45:0) after 1m0s
[14:57:28.677] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:45:0)
[14:57:28.677] Timer tick, asking Buildbucket for the build status
[14:57:29.133] Build 8857216012371227552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:57:29.133] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:46:0) after 1m0s
[14:58:29.193] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:46:0)
[14:58:29.193] Timer tick, asking Buildbucket for the build status
[14:58:29.302] Build 8857216012371227552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:58:29.302] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:47:0) after 1m0s
[14:59:29.354] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:47:0)
[14:59:29.354] Timer tick, asking Buildbucket for the build status
[14:59:29.699] Build 8857216012371227552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:59:29.699] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:48:0) after 1m0s
[15:00:29.813] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:48:0)
[15:00:29.813] Timer tick, asking Buildbucket for the build status
[15:00:30.324] Build 8857216012371227552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:00:30.325] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:49:0) after 1m0s
[15:01:30.351] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:49:0)
[15:01:30.351] Timer tick, asking Buildbucket for the build status
[15:01:30.423] Build 8857216012371227552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:01:30.423] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:50:0) after 1m0s
[15:02:30.450] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:50:0)
[15:02:30.450] Timer tick, asking Buildbucket for the build status
[15:02:30.573] Build 8857216012371227552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:02:30.573] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:51:0) after 1m0s
[15:03:30.633] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:51:0)
[15:03:30.633] Timer tick, asking Buildbucket for the build status
[15:03:31.187] Build 8857216012371227552: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:03:31.187] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:52:0) after 1m0s
[15:04:09.300] Received PubSub notification, asking Buildbucket for the build status
[15:04:09.917] Build 8857216012371227552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:04:31.212] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:52:0)
[15:04:31.212] Timer tick, asking Buildbucket for the build status
[15:04:31.283] Build 8857216012371227552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:04:31.283] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:54:0) after 1m0s
[15:05:31.300] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:54:0)
[15:05:31.300] Timer tick, asking Buildbucket for the build status
[15:05:31.376] Build 8857216012371227552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:05:31.376] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:55:0) after 1m0s
[15:06:31.484] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:55:0)
[15:06:31.484] Timer tick, asking Buildbucket for the build status
[15:06:31.548] Build 8857216012371227552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:06:31.548] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:56:0) after 1m0s
[15:07:31.628] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:56:0)
[15:07:31.628] Timer tick, asking Buildbucket for the build status
[15:07:32.026] Build 8857216012371227552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:07:32.026] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:57:0) after 1m0s
[15:08:32.054] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:57:0)
[15:08:32.054] Timer tick, asking Buildbucket for the build status
[15:08:32.163] Build 8857216012371227552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:08:32.163] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:58:0) after 1m0s
[15:09:32.281] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:58:0)
[15:09:32.281] Timer tick, asking Buildbucket for the build status
[15:09:32.519] Build 8857216012371227552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:09:32.519] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:59:0) after 1m0s
[15:10:32.715] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:59:0)
[15:10:32.715] Timer tick, asking Buildbucket for the build status
[15:10:32.767] Build 8857216012371227552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:10:32.767] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:60:0) after 1m0s
[15:11:32.903] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:60:0)
[15:11:32.903] Timer tick, asking Buildbucket for the build status
[15:11:32.965] Build 8857216012371227552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:11:32.965] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:61:0) after 1m0s
[15:12:33.053] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:61:0)
[15:12:33.053] Timer tick, asking Buildbucket for the build status
[15:12:33.154] Build 8857216012371227552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:12:33.154] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:62:0) after 1m0s
[15:13:34.036] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:62:0)
[15:13:34.036] Timer tick, asking Buildbucket for the build status
[15:13:34.141] Build 8857216012371227552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:13:34.141] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:63:0) after 1m0s
[15:14:34.164] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:63:0)
[15:14:34.164] Timer tick, asking Buildbucket for the build status
[15:14:34.223] Build 8857216012371227552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:14:34.223] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:64:0) after 1m0s
[15:15:34.333] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:64:0)
[15:15:34.333] Timer tick, asking Buildbucket for the build status
[15:15:34.557] Build 8857216012371227552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:15:34.557] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:65:0) after 1m0s
[15:16:34.579] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:65:0)
[15:16:34.579] Timer tick, asking Buildbucket for the build status
[15:16:34.781] Build 8857216012371227552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:16:34.781] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:66:0) after 1m0s
[15:17:34.786] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:66:0)
[15:17:34.786] Timer tick, asking Buildbucket for the build status
[15:17:34.903] Build 8857216012371227552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:17:34.903] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:67:0) after 1m0s
[15:18:34.948] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:67:0)
[15:18:34.948] Timer tick, asking Buildbucket for the build status
[15:18:35.068] Build 8857216012371227552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:18:35.068] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:68:0) after 1m0s
[15:19:35.124] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:68:0)
[15:19:35.124] Timer tick, asking Buildbucket for the build status
[15:19:35.429] Build 8857216012371227552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:19:35.429] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:69:0) after 1m0s
[15:20:35.439] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:69:0)
[15:20:35.439] Timer tick, asking Buildbucket for the build status
[15:20:35.757] Build 8857216012371227552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:20:35.757] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:70:0) after 1m0s
[15:21:35.768] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:70:0)
[15:21:35.768] Timer tick, asking Buildbucket for the build status
[15:21:35.911] Build 8857216012371227552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:21:35.911] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:71:0) after 1m0s
[15:22:36.055] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:71:0)
[15:22:36.055] Timer tick, asking Buildbucket for the build status
[15:22:36.135] Build 8857216012371227552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:22:36.135] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:72:0) after 1m0s
[15:23:36.126] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:72:0)
[15:23:36.126] Timer tick, asking Buildbucket for the build status
[15:23:36.596] Build 8857216012371227552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:23:36.596] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:73:0) after 1m0s
[15:24:36.747] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:73:0)
[15:24:36.747] Timer tick, asking Buildbucket for the build status
[15:24:36.817] Build 8857216012371227552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:24:36.817] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:74:0) after 1m0s
[15:25:36.928] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:74:0)
[15:25:36.928] Timer tick, asking Buildbucket for the build status
[15:25:37.005] Build 8857216012371227552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:25:37.005] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:75:0) after 1m0s
[15:26:37.073] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:75:0)
[15:26:37.073] Timer tick, asking Buildbucket for the build status
[15:26:37.132] Build 8857216012371227552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:26:37.132] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:76:0) after 1m0s
[15:27:37.226] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:76:0)
[15:27:37.226] Timer tick, asking Buildbucket for the build status
[15:27:37.289] Build 8857216012371227552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:27:37.289] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:77:0) after 1m0s
[15:28:37.422] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:77:0)
[15:28:37.422] Timer tick, asking Buildbucket for the build status
[15:28:37.573] Build 8857216012371227552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:28:37.573] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:78:0) after 1m0s
[15:29:37.692] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:78:0)
[15:29:37.692] Timer tick, asking Buildbucket for the build status
[15:29:38.037] Build 8857216012371227552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:29:38.037] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:79:0) after 1m0s
[15:30:38.154] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:79:0)
[15:30:38.154] Timer tick, asking Buildbucket for the build status
[15:30:38.225] Build 8857216012371227552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:30:38.225] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:80:0) after 1m0s
[15:31:38.305] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:80:0)
[15:31:38.305] Timer tick, asking Buildbucket for the build status
[15:31:38.434] Build 8857216012371227552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:31:38.434] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:81:0) after 1m0s
[15:32:38.541] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:81:0)
[15:32:38.541] Timer tick, asking Buildbucket for the build status
[15:32:38.609] Build 8857216012371227552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:32:38.609] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:82:0) after 1m0s
[15:33:38.700] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:82:0)
[15:33:38.700] Timer tick, asking Buildbucket for the build status
[15:33:38.792] Build 8857216012371227552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:33:38.792] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:83:0) after 1m0s
[15:34:38.794] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:83:0)
[15:34:38.794] Timer tick, asking Buildbucket for the build status
[15:34:38.851] Build 8857216012371227552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:34:38.851] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:84:0) after 1m0s
[15:35:39.079] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:84:0)
[15:35:39.079] Timer tick, asking Buildbucket for the build status
[15:35:39.135] Build 8857216012371227552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:35:39.135] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:85:0) after 1m0s
[15:36:39.189] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:85:0)
[15:36:39.189] Timer tick, asking Buildbucket for the build status
[15:36:39.252] Build 8857216012371227552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:36:39.252] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:86:0) after 1m0s
[15:37:39.292] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:86:0)
[15:37:39.292] Timer tick, asking Buildbucket for the build status
[15:37:39.736] Build 8857216012371227552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:37:39.736] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:87:0) after 1m0s
[15:38:39.824] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:87:0)
[15:38:39.824] Timer tick, asking Buildbucket for the build status
[15:38:39.903] Build 8857216012371227552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:38:39.903] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:88:0) after 1m0s
[15:39:39.922] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:88:0)
[15:39:39.922] Timer tick, asking Buildbucket for the build status
[15:39:40.171] Build 8857216012371227552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:39:40.171] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:89:0) after 1m0s
[15:40:40.324] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:89:0)
[15:40:40.324] Timer tick, asking Buildbucket for the build status
[15:40:40.401] Build 8857216012371227552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:40:40.401] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:90:0) after 1m0s
[15:41:40.543] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:90:0)
[15:41:40.544] Timer tick, asking Buildbucket for the build status
[15:41:40.798] Build 8857216012371227552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:41:40.798] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:91:0) after 1m0s
[15:42:40.856] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:91:0)
[15:42:40.856] Timer tick, asking Buildbucket for the build status
[15:42:40.941] Build 8857216012371227552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:42:40.941] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:92:0) after 1m0s
[15:43:40.974] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:92:0)
[15:43:40.974] Timer tick, asking Buildbucket for the build status
[15:43:41.075] Build 8857216012371227552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:43:41.075] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:93:0) after 1m0s
[15:44:41.178] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:93:0)
[15:44:41.178] Timer tick, asking Buildbucket for the build status
[15:44:41.232] Build 8857216012371227552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:44:41.232] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:94:0) after 1m0s
[15:45:41.258] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:94:0)
[15:45:41.258] Timer tick, asking Buildbucket for the build status
[15:45:41.324] Build 8857216012371227552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:45:41.324] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:95:0) after 1m0s
[15:46:41.383] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:95:0)
[15:46:41.383] Timer tick, asking Buildbucket for the build status
[15:46:41.461] Build 8857216012371227552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:46:41.461] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:96:0) after 1m0s
[15:47:41.546] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:96:0)
[15:47:41.546] Timer tick, asking Buildbucket for the build status
[15:47:41.606] Build 8857216012371227552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:47:41.606] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:97:0) after 1m0s
[15:48:41.627] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:97:0)
[15:48:41.627] Timer tick, asking Buildbucket for the build status
[15:48:41.927] Build 8857216012371227552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:48:41.927] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:98:0) after 1m0s
[15:49:41.951] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:98:0)
[15:49:41.951] Timer tick, asking Buildbucket for the build status
[15:49:42.069] Build 8857216012371227552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:49:42.069] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:99:0) after 1m0s
[15:50:42.421] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:99:0)
[15:50:42.421] Timer tick, asking Buildbucket for the build status
[15:50:42.599] Build 8857216012371227552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:50:42.600] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:100:0) after 1m0s
[15:51:42.750] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:100:0)
[15:51:42.750] Timer tick, asking Buildbucket for the build status
[15:51:43.102] Build 8857216012371227552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:51:43.102] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:101:0) after 1m0s
[15:52:43.258] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:101:0)
[15:52:43.258] Timer tick, asking Buildbucket for the build status
[15:52:43.525] Build 8857216012371227552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:52:43.525] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:102:0) after 1m0s
[15:53:43.528] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:102:0)
[15:53:43.528] Timer tick, asking Buildbucket for the build status
[15:53:43.632] Build 8857216012371227552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:53:43.632] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:103:0) after 1m0s
[15:54:43.743] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:103:0)
[15:54:43.743] Timer tick, asking Buildbucket for the build status
[15:54:44.838] Build 8857216012371227552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:54:44.838] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:104:0) after 1m0s
[15:55:44.905] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:104:0)
[15:55:44.905] Timer tick, asking Buildbucket for the build status
[15:55:44.980] Build 8857216012371227552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:55:44.980] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:105:0) after 1m0s
[15:56:44.989] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:105:0)
[15:56:44.989] Timer tick, asking Buildbucket for the build status
[15:56:45.088] Build 8857216012371227552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:56:45.088] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:106:0) after 1m0s
[15:57:45.187] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:106:0)
[15:57:45.187] Timer tick, asking Buildbucket for the build status
[15:57:45.333] Build 8857216012371227552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:57:45.333] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:107:0) after 1m0s
[15:58:45.424] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:107:0)
[15:58:45.424] Timer tick, asking Buildbucket for the build status
[15:58:45.895] Build 8857216012371227552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:58:45.895] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:108:0) after 1m0s
[15:59:45.994] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:108:0)
[15:59:45.994] Timer tick, asking Buildbucket for the build status
[15:59:46.486] Build 8857216012371227552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:59:46.486] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:109:0) after 1m0s
[16:00:46.558] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:109:0)
[16:00:46.558] Timer tick, asking Buildbucket for the build status
[16:00:46.746] Build 8857216012371227552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:00:46.746] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:110:0) after 1m0s
[16:01:46.768] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:110:0)
[16:01:46.768] Timer tick, asking Buildbucket for the build status
[16:01:46.895] Build 8857216012371227552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:01:46.895] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:111:0) after 1m0s
[16:02:46.921] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:111:0)
[16:02:46.921] Timer tick, asking Buildbucket for the build status
[16:02:47.032] Build 8857216012371227552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:02:47.033] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:112:0) after 1m0s
[16:03:47.069] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:112:0)
[16:03:47.069] Timer tick, asking Buildbucket for the build status
[16:03:47.229] Build 8857216012371227552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:03:47.229] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:113:0) after 1m0s
[16:04:47.249] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:113:0)
[16:04:47.249] Timer tick, asking Buildbucket for the build status
[16:04:47.324] Build 8857216012371227552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:04:47.324] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:114:0) after 1m0s
[16:05:47.387] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:114:0)
[16:05:47.387] Timer tick, asking Buildbucket for the build status
[16:05:47.557] Build 8857216012371227552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:05:47.557] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:115:0) after 1m0s
[16:06:47.568] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:115:0)
[16:06:47.568] Timer tick, asking Buildbucket for the build status
[16:06:47.644] Build 8857216012371227552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:06:47.644] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:116:0) after 1m0s
[16:07:47.794] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:116:0)
[16:07:47.794] Timer tick, asking Buildbucket for the build status
[16:07:47.906] Build 8857216012371227552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:07:47.906] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:117:0) after 1m0s
[16:08:48.097] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:117:0)
[16:08:48.097] Timer tick, asking Buildbucket for the build status
[16:08:48.754] Build 8857216012371227552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:08:48.754] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:118:0) after 1m0s
[16:09:48.776] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:118:0)
[16:09:48.776] Timer tick, asking Buildbucket for the build status
[16:09:48.845] Build 8857216012371227552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:09:48.845] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:119:0) after 1m0s
[16:10:48.903] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:119:0)
[16:10:48.903] Timer tick, asking Buildbucket for the build status
[16:10:48.971] Build 8857216012371227552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:10:48.971] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:120:0) after 1m0s
[16:11:48.994] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:120:0)
[16:11:48.994] Timer tick, asking Buildbucket for the build status
[16:11:49.090] Build 8857216012371227552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:11:49.090] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:121:0) after 1m0s
[16:12:49.109] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:121:0)
[16:12:49.109] Timer tick, asking Buildbucket for the build status
[16:12:49.235] Build 8857216012371227552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:12:49.235] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:122:0) after 1m0s
[16:13:49.293] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:122:0)
[16:13:49.293] Timer tick, asking Buildbucket for the build status
[16:13:49.527] Build 8857216012371227552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:13:49.527] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:123:0) after 1m0s
[16:14:49.640] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:123:0)
[16:14:49.640] Timer tick, asking Buildbucket for the build status
[16:14:49.798] Build 8857216012371227552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:14:49.798] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:124:0) after 1m0s
[16:15:49.892] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:124:0)
[16:15:49.892] Timer tick, asking Buildbucket for the build status
[16:15:50.004] Build 8857216012371227552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:15:50.004] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:125:0) after 1m0s
[16:16:50.047] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:125:0)
[16:16:50.047] Timer tick, asking Buildbucket for the build status
[16:16:50.163] Build 8857216012371227552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:16:50.163] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:126:0) after 1m0s
[16:17:50.254] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:126:0)
[16:17:50.254] Timer tick, asking Buildbucket for the build status
[16:17:50.317] Build 8857216012371227552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:17:50.317] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:127:0) after 1m0s
[16:18:50.430] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:127:0)
[16:18:50.430] Timer tick, asking Buildbucket for the build status
[16:18:50.837] Build 8857216012371227552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:18:50.837] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:128:0) after 1m0s
[16:19:50.933] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:128:0)
[16:19:50.933] Timer tick, asking Buildbucket for the build status
[16:19:51.047] Build 8857216012371227552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:19:51.047] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:129:0) after 1m0s
[16:20:51.122] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:129:0)
[16:20:51.122] Timer tick, asking Buildbucket for the build status
[16:20:51.586] Build 8857216012371227552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:20:51.586] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:130:0) after 1m0s
[16:21:51.638] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:130:0)
[16:21:51.638] Timer tick, asking Buildbucket for the build status
[16:21:51.784] Build 8857216012371227552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:21:51.784] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:131:0) after 1m0s
[16:22:51.842] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:131:0)
[16:22:51.842] Timer tick, asking Buildbucket for the build status
[16:22:51.913] Build 8857216012371227552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:22:51.913] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:132:0) after 1m0s
[16:23:51.971] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:132:0)
[16:23:51.971] Timer tick, asking Buildbucket for the build status
[16:23:52.251] Build 8857216012371227552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:23:52.251] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:133:0) after 1m0s
[16:24:52.318] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:133:0)
[16:24:52.319] Timer tick, asking Buildbucket for the build status
[16:24:52.475] Build 8857216012371227552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:24:52.475] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:134:0) after 1m0s
[16:25:52.498] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:134:0)
[16:25:52.498] Timer tick, asking Buildbucket for the build status
[16:25:52.591] Build 8857216012371227552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:25:52.591] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:135:0) after 1m0s
[16:26:53.201] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:135:0)
[16:26:53.201] Timer tick, asking Buildbucket for the build status
[16:26:53.448] Build 8857216012371227552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:26:53.449] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:136:0) after 1m0s
[16:27:53.489] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:136:0)
[16:27:53.489] Timer tick, asking Buildbucket for the build status
[16:27:53.553] Build 8857216012371227552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:27:53.553] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:137:0) after 1m0s
[16:28:53.645] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:137:0)
[16:28:53.645] Timer tick, asking Buildbucket for the build status
[16:28:53.763] Build 8857216012371227552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:28:53.763] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:138:0) after 1m0s
[16:29:53.809] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:138:0)
[16:29:53.809] Timer tick, asking Buildbucket for the build status
[16:29:53.932] Build 8857216012371227552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:29:53.932] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:139:0) after 1m0s
[16:30:54.102] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:139:0)
[16:30:54.102] Timer tick, asking Buildbucket for the build status
[16:30:54.251] Build 8857216012371227552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:30:54.251] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:140:0) after 1m0s
[16:31:54.383] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:140:0)
[16:31:54.383] Timer tick, asking Buildbucket for the build status
[16:31:54.460] Build 8857216012371227552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:31:54.460] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:141:0) after 1m0s
[16:32:54.553] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:141:0)
[16:32:54.553] Timer tick, asking Buildbucket for the build status
[16:32:54.813] Build 8857216012371227552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:32:54.813] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:142:0) after 1m0s
[16:33:54.818] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:142:0)
[16:33:54.818] Timer tick, asking Buildbucket for the build status
[16:33:54.938] Build 8857216012371227552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:33:54.938] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:143:0) after 1m0s
[16:34:55.094] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:143:0)
[16:34:55.094] Timer tick, asking Buildbucket for the build status
[16:34:55.538] Build 8857216012371227552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:34:55.538] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:144:0) after 1m0s
[16:35:55.636] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:144:0)
[16:35:55.636] Timer tick, asking Buildbucket for the build status
[16:35:55.758] Build 8857216012371227552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:35:55.759] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:145:0) after 1m0s
[16:36:55.759] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:145:0)
[16:36:55.759] Timer tick, asking Buildbucket for the build status
[16:36:56.030] Build 8857216012371227552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:36:56.030] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:146:0) after 1m0s
[16:37:56.059] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:146:0)
[16:37:56.059] Timer tick, asking Buildbucket for the build status
[16:37:56.145] Build 8857216012371227552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:37:56.145] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:147:0) after 1m0s
[16:38:56.276] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:147:0)
[16:38:56.276] Timer tick, asking Buildbucket for the build status
[16:38:56.400] Build 8857216012371227552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:38:56.400] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:148:0) after 1m0s
[16:39:56.549] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:148:0)
[16:39:56.549] Timer tick, asking Buildbucket for the build status
[16:39:56.622] Build 8857216012371227552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:39:56.622] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:149:0) after 1m0s
[16:40:56.724] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:149:0)
[16:40:56.724] Timer tick, asking Buildbucket for the build status
[16:40:56.810] Build 8857216012371227552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:40:56.810] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:150:0) after 1m0s
[16:41:56.846] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:150:0)
[16:41:56.846] Timer tick, asking Buildbucket for the build status
[16:41:56.914] Build 8857216012371227552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:41:56.914] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:151:0) after 1m0s
[16:42:57.014] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:151:0)
[16:42:57.014] Timer tick, asking Buildbucket for the build status
[16:42:57.181] Build 8857216012371227552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:42:57.181] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:152:0) after 1m0s
[16:43:57.251] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:152:0)
[16:43:57.251] Timer tick, asking Buildbucket for the build status
[16:43:57.374] Build 8857216012371227552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:43:57.374] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:153:0) after 1m0s
[16:44:57.776] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:153:0)
[16:44:57.776] Timer tick, asking Buildbucket for the build status
[16:44:58.132] Build 8857216012371227552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:44:58.132] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:154:0) after 1m0s
[16:45:58.153] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:154:0)
[16:45:58.153] Timer tick, asking Buildbucket for the build status
[16:45:58.903] Build 8857216012371227552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:45:58.903] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:155:0) after 1m0s
[16:46:58.940] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:155:0)
[16:46:58.940] Timer tick, asking Buildbucket for the build status
[16:46:59.167] Build 8857216012371227552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:46:59.167] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:156:0) after 1m0s
[16:47:59.191] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:156:0)
[16:47:59.191] Timer tick, asking Buildbucket for the build status
[16:47:59.267] Build 8857216012371227552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:47:59.267] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:157:0) after 1m0s
[16:48:59.310] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:157:0)
[16:48:59.310] Timer tick, asking Buildbucket for the build status
[16:48:59.410] Build 8857216012371227552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:48:59.410] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:158:0) after 1m0s
[16:49:59.437] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:158:0)
[16:49:59.437] Timer tick, asking Buildbucket for the build status
[16:49:59.514] Build 8857216012371227552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:49:59.514] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022646074504952832:159:0) after 1m0s
[16:50:02.155] Received PubSub notification, asking Buildbucket for the build status
[16:50:02.247] Build 8857216012371227552: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[16:50:02.247] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1611506998644841",
"created_by": "project:chromium",
"created_ts": "1611497596347760",
"id": "8857216012371227552",
"parameters_json": "{\"builder_name\": \"linux-chromeos-dbg\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"a2183c1f1052de2e81d6772c5a81e1f4669a50d0\"}}",
"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\": \"a2183c1f1052de2e81d6772c5a81e1f4669a50d0\", \"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-60-gnhu\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-dbg\", \"buildnumber\": 22324, \"got_angle_revision\": \"3c3b82abcf0a5c18b4d97ae49726045ce6d3b832\", \"got_dawn_revision\": \"f1f8394de04ea9532de763dfb2f968fc9610683b\", \"got_nacl_revision\": \"cb31feffa500401c95c6c44d88b7c358236bca36\", \"got_revision\": \"a2183c1f1052de2e81d6772c5a81e1f4669a50d0\", \"got_revision_cp\": \"refs/heads/master@{#846580}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"413f7cb17c77c95e4cc3ba141f3c0f049ac2ecb2\", \"got_v8_revision\": \"f2b2f28304b332e59226ee6c0688307ed9982884\", \"got_v8_revision_cp\": \"refs/heads/9.0.57@{#1}\", \"got_webrtc_revision\": \"90776cb0ef72d437983c8d29e5a7f2ac264c107d\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33064}\", \"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\": \"a2183c1f1052de2e81d6772c5a81e1f4669a50d0\", \"swarm_hashes_refs/heads/master(at){#846580}_without_patch\": {\"absl_hardening_tests\": \"a3bd4b078a9f594562a4f2c6252dbdce7dbaf521\", \"accessibility_unittests\": \"f6221dc04ceea61f2962214e6b2298086b54b641\", \"angle_unittests\": \"b115aa65c3d018216f882663c23552155eaa230a\", \"app_list_unittests\": \"2fd9f3f4f23db0761cd0905d5a396dea9b823d69\", \"app_shell_unittests\": \"a85ae347d80de698c26eea07506b7b5c44e3c818\", \"ash_unittests\": \"655b74bcdc5effaeca6ec40c5f7ced1fab02cb8e\", \"aura_unittests\": \"c30c14e03e0de8dc82776e87b68d5476b30aba19\", \"base_unittests\": \"5d89cf795c3193c3368e1bab3719cfe8f5928140\", \"base_util_unittests\": \"12dae3edfad4eb2a751d11c33684e3f9711ddca6\", \"blink_common_unittests\": \"f110e2bbdcdc40727789d3ac2d3d9332cb308fc3\", \"blink_fuzzer_unittests\": \"e443c3826560255284ba2a7e38be334e462d3dba\", \"blink_heap_unittests\": \"c779ea88dee7d76d4d8d4a135e69b71aedfaf711\", \"blink_platform_unittests\": \"2fade806ecbb1552b04914451bb00bb618eedfcf\", \"blink_unittests\": \"4f2bc42ba40d7a6ea91e3b8ade7f9ff974fcc2a1\", \"boringssl_crypto_tests\": \"d18498d5161a89821f60049f177d17a189f1ac1c\", \"boringssl_ssl_tests\": \"203b25c35171c125cfbc18c61936df84ba20b908\", \"browser_tests\": \"204cfc2f2779a637fe34fa64c77d1a11e49cccb1\", \"capture_unittests\": \"ffab0c22b8ea1d4aa58c9124a3fca9aaa31cc25c\", \"cast_unittests\": \"4a425b12848363725ed6c1109be31d689956ff24\", \"cc_unittests\": \"ae34d27e33de2bb9ebcbf644e2ef4f164592fb02\", \"chrome_app_unittests\": \"2f4c6064a71ec31d5a0019ad45eaa996af4044ed\", \"chromedriver_unittests\": \"b1ae4a68be742d13c90131f248359dea3b7a9ee4\", \"chromeos_components_unittests\": \"c491cdd73c758c1da66940f57ca129e55e230282\", \"chromeos_unittests\": \"ab336089b5b9281db3f7b728f91775a56ced35cc\", \"components_browsertests\": \"e6298fabc603e65f1968e0aeacb9afc0f3a6b0c7\", \"components_unittests\": \"29b5f989b2b20fa50ca029fdbb4114a80f72116d\", \"compositor_unittests\": \"52bef0053447bed8c88e8918e346c962f7e699a6\", \"content_browsertests\": \"35db711dcf1f2936d8f43ee114ab220fe3bbc3ea\", \"content_unittests\": \"763d23fae19b0a4677ad60d7956a7449ae1b085d\", \"crashpad_tests\": \"d0420808fbffdfeaec058facb56adb0daebf6a3f\", \"crypto_unittests\": \"23e9e54a086c15aaea103dc19c18ac00db0795cb\", \"dbus_unittests\": \"cf80aaaa41d01df8af2b134e3cebe1d3d0d980e8\", \"device_unittests\": \"82353f1fbcb862601f1448e71aaf6ce94aba112b\", \"display_unittests\": \"1fe791f83ef4ada65023dc7894f01d0b962089b0\", \"events_unittests\": \"a40c290ce407e172f5cddc7ad9fa951ce229c391\", \"exo_unittests\": \"7c0b2a566d227bd49474f660f58996c5a959f4a1\", \"extensions_browsertests\": \"e9566cdf28d7e6b0ebf7708a1b8c74a38f294fb9\", \"extensions_unittests\": \"21f4b32e1e93dc7a819119b448d0b5f8ab687868\", \"filesystem_service_unittests\": \"b80a984bfcfa470cfc45932e3bc7de9b284172d9\", \"gcm_unit_tests\": \"952aa40590aac1d136c4c8bc77761c8a375f158a\", \"gfx_unittests\": \"ffc254b8a688e021aac1a28da364b23a8151bb97\", \"gin_unittests\": \"c22309d9e0f76160b07aeaa4877cc90b9f9c4869\", \"gl_unittests_ozone\": \"756cdb9d6883361eb42cf11eabd9e9279240c491\", \"google_apis_unittests\": \"4f92722e654379f14019fa3e61a5ca46f6194110\", \"gpu_unittests\": \"a634bcadff50a2bec662f4590cab56deb4c11409\", \"gwp_asan_unittests\": \"33e5463c96ba9d12d5050976a9bde30f38bf0c2f\", \"interactive_ui_tests\": \"c693ba7f09c2aa2c417cd42337538a4ba2685f42\", \"ipc_tests\": \"caace76828c07d0cfa8ce7c06a9f6265b9d49331\", \"jingle_unittests\": \"63b959acc9be3c9922b2959d6ec398eac642d8b4\", \"keyboard_unittests\": \"c9514cc95540010999172873ffff75528cf898b1\", \"latency_unittests\": \"cabb90f00b6fb4455c7485105c5d605bc5b5215f\", \"libjingle_xmpp_unittests\": \"2d44f5307d33f186cea3c89c81ee41955c2fc47a\", \"liburlpattern_unittests\": \"4dcc14172e32b2470af3c1ef92fe8b928f005a83\", \"media_blink_unittests\": \"916cafc177fc8291acfbaadd91a6f4e0eee6affc\", \"media_unittests\": \"19ac068e5b5dd189bf5e5360588e78a9276a2f4b\", \"message_center_unittests\": \"73fcec02f7b58f87f5658eb3af007766f40799bd\", \"midi_unittests\": \"59d9d04e01f4d13e9818b0e35df99fc1206e73ab\", \"mojo_core_unittests\": \"2d6068938942d1043c61d8a2c1068497a12419ff\", \"mojo_unittests\": \"d3eb3db8f98bf06639d7a7214501f716e2f728ea\", \"nacl_helper_nonsfi_unittests\": \"f3c7be4dfb040885b8dedaf44399517a302df467\", \"nacl_loader_unittests\": \"400df7ab1196b1e73445568137c38f8db92f0894\", \"native_theme_unittests\": \"5eb595b2e87b604d9e99ffe488db032ed762b4df\", \"net_unittests\": \"f47422dde97533466d11614ce253206571a433b5\", \"ozone_gl_unittests\": \"570c7376936befc035eb2cec451634e2748406d8\", \"ozone_unittests\": \"aebc027e04f22f051747bde76931f2cf02597c21\", \"ozone_x11_unittests\": \"38599bda73c02e03a41bf7343ab0a43e0b092d05\", \"pdf_unittests\": \"fdd2468233591fbc862630c5aa140df7ed05ac63\", \"perfetto_unittests\": \"51f87dd4922912657977133b98ecff9edb228492\", \"ppapi_unittests\": \"7e121d24ba9d5bd2d1945abe664f9d4d16f3fb13\", \"printing_unittests\": \"34745c6067640ea848719339ccbb3e5b86e0a2ac\", \"remoting_unittests\": \"5ccb522e31dff7bf79778c871e6cb17c3f7e089e\", \"sandbox_linux_unittests\": \"87fddbcf7184b9e45062242efdf5a1d185f7d271\", \"service_manager_unittests\": \"f97ea74b4fb3807b652a8a38ff80136e73cae716\", \"services_unittests\": \"ee55a204f35b354db40017ab7d7f895a6316d1a3\", \"shell_dialogs_unittests\": \"20035cf15ebb6d2ab7b76117d9b78db663c34032\", \"shell_encryption_unittests\": \"97f72235aaeab1f242ae8785ba54f60e6f683d86\", \"skia_unittests\": \"8075dccc5f28e98a03392015b229cc86d8111220\", \"snapshot_unittests\": \"e80b8e37dfa34d3630dd085d4e767ff26b0f881a\", \"sql_unittests\": \"e9b8134b70cb790b78419755f5741a9f15c1e5fa\", \"storage_unittests\": \"ddad95443e5e454f8938efa116cebb794b77d990\", \"sync_integration_tests\": \"a952cdf0e11c1f1480242e319ffacf42dc5033da\", \"traffic_annotation_auditor_unittests\": \"b1583a5cd46900bdd6eb56d6dd3a70e1bbff888d\", \"ui_base_unittests\": \"8e2cf022e6b916b0e95564d9c475a440165eaf86\", \"ui_chromeos_unittests\": \"f2e1ec50cbbab614d0dc1f70521626e875ef60d0\", \"ui_touch_selection_unittests\": \"5b857cd1b30fb1152b587fa42a4f1666a4dd8e20\", \"unit_tests\": \"6451b91c6952395243115463176f4f8dfe9e51aa\", \"url_unittests\": \"57d1481c20b737ba4027526b833052f0c84c3aa9\", \"usage_time_limit_unittests\": \"ac2a9500c9568098f93af80e23a5a29ad935706c\", \"views_unittests\": \"293b2e893843d58a99b858fd0f01d62bb1b19a08\", \"viz_unittests\": \"352c9a7e0ad05e928db18e28f351c1db09a208b8\", \"wm_unittests\": \"541ce637a9a45b8b488cebf94180b9dd05c39220\", \"wtf_unittests\": \"53d7311a3abb72a43db1571fdfda5ec5f27abf37\", \"zlib_unittests\": \"3a4b441cf295279b23fd9b4be9dec5a39012eb65\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_1bdcc2c1d38386cf72a342843f986ec12bfab33633905ef2709b97cea999d0f5_v2\", \"builder_349e20fa0346c22afd9d85eafc166958d65f8d3eb174d2113a3f89c2e822dead_v2\", \"builder_3ee79eab7726d87d4f9bf92f4b58b9506b0a7a0d17f466f0c3d67bd50a4697d1_v2\", \"builder_5ae174071c5d6730648bf4e0d5db68d27bfbde0d9c5b879a557aa1bc8e90c36b_v2\", \"builder_7d75f6999c07e42a7f7fde2313383264fbd8ed871e2008bde33b278973c1f4c8_v2\", \"builder_a6d905fb3e765cf980c6458181cc20495ddb667b30aa758edddbed3b46166dd6_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-60-gnhu\"], \"image\": [\"chrome-xenial-21011100-9488e34e558\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"e2-standard-8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16\", \"Ubuntu-16.04\", \"Ubuntu-16.04.6\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.12\"], \"server_version\": [\"5671-1d9dea8\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1611500647721082",
"status": "COMPLETED",
"status_changed_ts": "1611506999161153",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-dbg/22324",
"builder:linux-chromeos-dbg",
"buildset:commit/git/a2183c1f1052de2e81d6772c5a81e1f4669a50d0",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a2183c1f1052de2e81d6772c5a81e1f4669a50d0",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022646074504952832",
"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/8857216012371227552/+/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:514d8f73b940a810",
"user_agent:luci-scheduler"
],
"updated_ts": "1611506999251889",
"url": "https://ci.chromium.org/b/8857216012371227552",
"utcnow_ts": "1611507002239023"
}
}
[16:50:02.247] Invocation finished in 2h36m47.319143557s with status SUCCEEDED