[10:25:16.594] New invocation is queued and will start shortly
[10:25:17.806] Starting the invocation (attempt 1)
[10:25:17.877] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[10:25:17.878] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[10:25:17.878] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9031448323035798896",
"parameters_json": "{\"builder_name\":\"win32-official\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"6a201596755da73a4570e8bf6f1af63ea444e236\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win32-official",
"scheduler_invocation_id:9031448323035798896",
"scheduler_job_id:chromium/win32-official",
"user_agent:luci-scheduler",
"buildset:commit/git/6a201596755da73a4570e8bf6f1af63ea444e236",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/6a201596755da73a4570e8bf6f1af63ea444e236",
"gitiles_ref:refs/heads/master"
]
}
[10:25:18.487] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"created_by": "project:chromium",
"created_ts": "1603103117967756",
"id": "8866018260932901744",
"parameters_json": "{\"builder_name\": \"win32-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"6a201596755da73a4570e8bf6f1af63ea444e236\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1603103118365111",
"tags": [
"build_address:luci.chromium.ci/win32-official/770",
"builder:win32-official",
"buildset:commit/git/6a201596755da73a4570e8bf6f1af63ea444e236",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/6a201596755da73a4570e8bf6f1af63ea444e236",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9031448323035798896",
"scheduler_job_id:chromium/win32-official",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8866018260932901744/+/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": "1603103118365226",
"url": "https://ci.chromium.org/b/8866018260932901744",
"utcnow_ts": "1603103118475774"
}
}
[10:25:18.487] Task URL: https://ci.chromium.org/b/8866018260932901744
[10:25:18.487] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:2:0) after 1m0s
[10:26:03.431] Received PubSub notification, asking Buildbucket for the build status
[10:26:03.583] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:26:18.564] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:2:0)
[10:26:18.565] Timer tick, asking Buildbucket for the build status
[10:26:18.632] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:26:18.632] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:4:0) after 1m0s
[10:27:18.720] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:4:0)
[10:27:18.720] Timer tick, asking Buildbucket for the build status
[10:27:18.811] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:27:18.811] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:5:0) after 1m0s
[10:28:18.908] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:5:0)
[10:28:18.908] Timer tick, asking Buildbucket for the build status
[10:28:19.005] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:28:19.005] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:6:0) after 1m0s
[10:29:19.159] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:6:0)
[10:29:19.159] Timer tick, asking Buildbucket for the build status
[10:29:19.267] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:29:19.267] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:7:0) after 1m0s
[10:30:19.651] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:7:0)
[10:30:19.651] Timer tick, asking Buildbucket for the build status
[10:30:19.772] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:30:19.772] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:8:0) after 1m0s
[10:31:20.077] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:8:0)
[10:31:20.077] Timer tick, asking Buildbucket for the build status
[10:31:20.336] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:31:20.336] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:9:0) after 1m0s
[10:32:20.474] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:9:0)
[10:32:20.474] Timer tick, asking Buildbucket for the build status
[10:32:20.550] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:32:20.550] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:10:0) after 1m0s
[10:33:20.628] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:10:0)
[10:33:20.628] Timer tick, asking Buildbucket for the build status
[10:33:20.719] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:33:20.719] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:11:0) after 1m0s
[10:34:20.800] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:11:0)
[10:34:20.800] Timer tick, asking Buildbucket for the build status
[10:34:20.874] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:34:20.874] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:12:0) after 1m0s
[10:35:20.962] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:12:0)
[10:35:20.962] Timer tick, asking Buildbucket for the build status
[10:35:21.060] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:35:21.060] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:13:0) after 1m0s
[10:36:21.105] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:13:0)
[10:36:21.105] Timer tick, asking Buildbucket for the build status
[10:36:21.192] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:36:21.192] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:14:0) after 1m0s
[10:37:21.272] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:14:0)
[10:37:21.272] Timer tick, asking Buildbucket for the build status
[10:37:21.350] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:37:21.350] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:15:0) after 1m0s
[10:38:21.410] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:15:0)
[10:38:21.410] Timer tick, asking Buildbucket for the build status
[10:38:21.495] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:38:21.495] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:16:0) after 1m0s
[10:39:21.531] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:16:0)
[10:39:21.531] Timer tick, asking Buildbucket for the build status
[10:39:21.587] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:39:21.587] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:17:0) after 1m0s
[10:40:21.607] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:17:0)
[10:40:21.607] Timer tick, asking Buildbucket for the build status
[10:40:21.873] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:40:21.873] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:18:0) after 1m0s
[10:41:21.928] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:18:0)
[10:41:21.928] Timer tick, asking Buildbucket for the build status
[10:41:22.065] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:41:22.066] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:19:0) after 1m0s
[10:42:22.104] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:19:0)
[10:42:22.104] Timer tick, asking Buildbucket for the build status
[10:42:22.223] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:42:22.223] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:20:0) after 1m0s
[10:43:22.237] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:20:0)
[10:43:22.237] Timer tick, asking Buildbucket for the build status
[10:43:22.319] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:43:22.319] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:21:0) after 1m0s
[10:44:22.364] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:21:0)
[10:44:22.364] Timer tick, asking Buildbucket for the build status
[10:44:22.503] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:44:22.503] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:22:0) after 1m0s
[10:45:22.709] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:22:0)
[10:45:22.709] Timer tick, asking Buildbucket for the build status
[10:45:22.921] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:45:22.921] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:23:0) after 1m0s
[10:46:23.009] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:23:0)
[10:46:23.009] Timer tick, asking Buildbucket for the build status
[10:46:23.231] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:46:23.231] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:24:0) after 1m0s
[10:47:23.244] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:24:0)
[10:47:23.244] Timer tick, asking Buildbucket for the build status
[10:47:23.363] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:47:23.363] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:25:0) after 1m0s
[10:48:23.417] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:25:0)
[10:48:23.417] Timer tick, asking Buildbucket for the build status
[10:48:23.483] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:48:23.483] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:26:0) after 1m0s
[10:49:23.594] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:26:0)
[10:49:23.594] Timer tick, asking Buildbucket for the build status
[10:49:23.718] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:49:23.718] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:27:0) after 1m0s
[10:50:23.738] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:27:0)
[10:50:23.738] Timer tick, asking Buildbucket for the build status
[10:50:24.014] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:50:24.014] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:28:0) after 1m0s
[10:51:24.043] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:28:0)
[10:51:24.043] Timer tick, asking Buildbucket for the build status
[10:51:24.784] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:51:24.784] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:29:0) after 1m0s
[10:52:24.812] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:29:0)
[10:52:24.812] Timer tick, asking Buildbucket for the build status
[10:52:25.116] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:52:25.116] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:30:0) after 1m0s
[10:53:25.114] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:30:0)
[10:53:25.114] Timer tick, asking Buildbucket for the build status
[10:53:25.412] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:53:25.412] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:31:0) after 1m0s
[10:54:25.443] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:31:0)
[10:54:25.443] Timer tick, asking Buildbucket for the build status
[10:54:25.528] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:54:25.528] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:32:0) after 1m0s
[10:55:25.551] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:32:0)
[10:55:25.551] Timer tick, asking Buildbucket for the build status
[10:55:25.681] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:55:25.681] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:33:0) after 1m0s
[10:56:25.711] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:33:0)
[10:56:25.711] Timer tick, asking Buildbucket for the build status
[10:56:25.776] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:56:25.776] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:34:0) after 1m0s
[10:57:25.874] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:34:0)
[10:57:25.874] Timer tick, asking Buildbucket for the build status
[10:57:25.942] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:57:25.942] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:35:0) after 1m0s
[10:58:25.964] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:35:0)
[10:58:25.964] Timer tick, asking Buildbucket for the build status
[10:58:26.094] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:58:26.094] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:36:0) after 1m0s
[10:59:26.117] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:36:0)
[10:59:26.117] Timer tick, asking Buildbucket for the build status
[10:59:26.231] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:59:26.231] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:37:0) after 1m0s
[11:00:26.233] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:37:0)
[11:00:26.233] Timer tick, asking Buildbucket for the build status
[11:00:26.373] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:00:26.373] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:38:0) after 1m0s
[11:01:26.429] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:38:0)
[11:01:26.429] Timer tick, asking Buildbucket for the build status
[11:01:26.532] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:01:26.532] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:39:0) after 1m0s
[11:02:26.566] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:39:0)
[11:02:26.566] Timer tick, asking Buildbucket for the build status
[11:02:26.644] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:02:26.644] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:40:0) after 1m0s
[11:03:26.668] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:40:0)
[11:03:26.668] Timer tick, asking Buildbucket for the build status
[11:03:26.828] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:03:26.828] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:41:0) after 1m0s
[11:04:26.898] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:41:0)
[11:04:26.898] Timer tick, asking Buildbucket for the build status
[11:04:26.992] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:04:26.992] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:42:0) after 1m0s
[11:05:27.017] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:42:0)
[11:05:27.017] Timer tick, asking Buildbucket for the build status
[11:05:27.130] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:05:27.130] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:43:0) after 1m0s
[11:06:27.229] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:43:0)
[11:06:27.229] Timer tick, asking Buildbucket for the build status
[11:06:27.298] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:06:27.298] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:44:0) after 1m0s
[11:07:27.319] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:44:0)
[11:07:27.319] Timer tick, asking Buildbucket for the build status
[11:07:27.703] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:07:27.703] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:45:0) after 1m0s
[11:08:27.691] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:45:0)
[11:08:27.691] Timer tick, asking Buildbucket for the build status
[11:08:27.757] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:08:27.757] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:46:0) after 1m0s
[11:09:27.795] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:46:0)
[11:09:27.795] Timer tick, asking Buildbucket for the build status
[11:09:27.864] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:09:27.864] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:47:0) after 1m0s
[11:10:27.898] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:47:0)
[11:10:27.898] Timer tick, asking Buildbucket for the build status
[11:10:28.021] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:10:28.021] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:48:0) after 1m0s
[11:11:28.067] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:48:0)
[11:11:28.067] Timer tick, asking Buildbucket for the build status
[11:11:28.237] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:11:28.237] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:49:0) after 1m0s
[11:12:28.301] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:49:0)
[11:12:28.301] Timer tick, asking Buildbucket for the build status
[11:12:28.415] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:12:28.415] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:50:0) after 1m0s
[11:13:28.476] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:50:0)
[11:13:28.476] Timer tick, asking Buildbucket for the build status
[11:13:28.540] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:13:28.540] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:51:0) after 1m0s
[11:14:28.641] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:51:0)
[11:14:28.641] Timer tick, asking Buildbucket for the build status
[11:14:28.845] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:14:28.845] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:52:0) after 1m0s
[11:15:29.184] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:52:0)
[11:15:29.184] Timer tick, asking Buildbucket for the build status
[11:15:29.280] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:15:29.280] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:53:0) after 1m0s
[11:16:29.398] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:53:0)
[11:16:29.398] Timer tick, asking Buildbucket for the build status
[11:16:29.465] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:16:29.465] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:54:0) after 1m0s
[11:17:29.524] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:54:0)
[11:17:29.524] Timer tick, asking Buildbucket for the build status
[11:17:29.631] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:17:29.631] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:55:0) after 1m0s
[11:18:29.644] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:55:0)
[11:18:29.644] Timer tick, asking Buildbucket for the build status
[11:18:29.713] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:18:29.713] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:56:0) after 1m0s
[11:19:29.739] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:56:0)
[11:19:29.739] Timer tick, asking Buildbucket for the build status
[11:19:29.808] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:19:29.808] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:57:0) after 1m0s
[11:20:29.871] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:57:0)
[11:20:29.871] Timer tick, asking Buildbucket for the build status
[11:20:29.983] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:20:29.983] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:58:0) after 1m0s
[11:21:30.031] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:58:0)
[11:21:30.031] Timer tick, asking Buildbucket for the build status
[11:21:30.105] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:21:30.105] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:59:0) after 1m0s
[11:22:30.138] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:59:0)
[11:22:30.138] Timer tick, asking Buildbucket for the build status
[11:22:30.205] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:22:30.205] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:60:0) after 1m0s
[11:23:30.226] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:60:0)
[11:23:30.226] Timer tick, asking Buildbucket for the build status
[11:23:30.281] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:23:30.281] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:61:0) after 1m0s
[11:24:30.317] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:61:0)
[11:24:30.317] Timer tick, asking Buildbucket for the build status
[11:24:30.370] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:24:30.370] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:62:0) after 1m0s
[11:25:30.394] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:62:0)
[11:25:30.394] Timer tick, asking Buildbucket for the build status
[11:25:30.518] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:25:30.518] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:63:0) after 1m0s
[11:26:30.624] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:63:0)
[11:26:30.624] Timer tick, asking Buildbucket for the build status
[11:26:30.686] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:26:30.686] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:64:0) after 1m0s
[11:27:30.819] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:64:0)
[11:27:30.819] Timer tick, asking Buildbucket for the build status
[11:27:30.872] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:27:30.872] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:65:0) after 1m0s
[11:28:31.018] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:65:0)
[11:28:31.018] Timer tick, asking Buildbucket for the build status
[11:28:31.092] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:28:31.092] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:66:0) after 1m0s
[11:29:31.132] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:66:0)
[11:29:31.132] Timer tick, asking Buildbucket for the build status
[11:29:31.216] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:29:31.216] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:67:0) after 1m0s
[11:30:31.274] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:67:0)
[11:30:31.274] Timer tick, asking Buildbucket for the build status
[11:30:31.397] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:30:31.397] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:68:0) after 1m0s
[11:31:31.458] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:68:0)
[11:31:31.458] Timer tick, asking Buildbucket for the build status
[11:31:31.525] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:31:31.525] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:69:0) after 1m0s
[11:32:31.658] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:69:0)
[11:32:31.658] Timer tick, asking Buildbucket for the build status
[11:32:31.720] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:32:31.720] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:70:0) after 1m0s
[11:33:31.919] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:70:0)
[11:33:31.919] Timer tick, asking Buildbucket for the build status
[11:33:32.291] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:33:32.291] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:71:0) after 1m0s
[11:34:32.388] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:71:0)
[11:34:32.389] Timer tick, asking Buildbucket for the build status
[11:34:32.446] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:34:32.446] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:72:0) after 1m0s
[11:35:32.471] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:72:0)
[11:35:32.471] Timer tick, asking Buildbucket for the build status
[11:35:32.830] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:35:32.830] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:73:0) after 1m0s
[11:36:32.913] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:73:0)
[11:36:32.913] Timer tick, asking Buildbucket for the build status
[11:36:33.009] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:36:33.009] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:74:0) after 1m0s
[11:37:33.084] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:74:0)
[11:37:33.084] Timer tick, asking Buildbucket for the build status
[11:37:33.146] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:37:33.146] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:75:0) after 1m0s
[11:38:33.215] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:75:0)
[11:38:33.215] Timer tick, asking Buildbucket for the build status
[11:38:33.447] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:38:33.447] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:76:0) after 1m0s
[11:39:33.504] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:76:0)
[11:39:33.504] Timer tick, asking Buildbucket for the build status
[11:39:33.574] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:39:33.575] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:77:0) after 1m0s
[11:40:33.556] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:77:0)
[11:40:33.556] Timer tick, asking Buildbucket for the build status
[11:40:33.881] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:40:33.881] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:78:0) after 1m0s
[11:41:33.910] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:78:0)
[11:41:33.910] Timer tick, asking Buildbucket for the build status
[11:41:33.962] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:41:33.962] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:79:0) after 1m0s
[11:42:34.087] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:79:0)
[11:42:34.087] Timer tick, asking Buildbucket for the build status
[11:42:34.172] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:42:34.172] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:80:0) after 1m0s
[11:43:34.197] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:80:0)
[11:43:34.197] Timer tick, asking Buildbucket for the build status
[11:43:34.318] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:43:34.318] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:81:0) after 1m0s
[11:44:34.358] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:81:0)
[11:44:34.358] Timer tick, asking Buildbucket for the build status
[11:44:34.436] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:44:34.436] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:82:0) after 1m0s
[11:45:34.484] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:82:0)
[11:45:34.484] Timer tick, asking Buildbucket for the build status
[11:45:34.591] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:45:34.591] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:83:0) after 1m0s
[11:46:34.642] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:83:0)
[11:46:34.642] Timer tick, asking Buildbucket for the build status
[11:46:34.713] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:46:34.713] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:84:0) after 1m0s
[11:47:34.792] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:84:0)
[11:47:34.792] Timer tick, asking Buildbucket for the build status
[11:47:34.862] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:47:34.862] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:85:0) after 1m0s
[11:48:34.887] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:85:0)
[11:48:34.887] Timer tick, asking Buildbucket for the build status
[11:48:35.229] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:48:35.229] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:86:0) after 1m0s
[11:49:35.269] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:86:0)
[11:49:35.269] Timer tick, asking Buildbucket for the build status
[11:49:35.338] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:49:35.338] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:87:0) after 1m0s
[11:50:35.418] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:87:0)
[11:50:35.418] Timer tick, asking Buildbucket for the build status
[11:50:35.522] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:50:35.522] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:88:0) after 1m0s
[11:51:35.613] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:88:0)
[11:51:35.613] Timer tick, asking Buildbucket for the build status
[11:51:35.695] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:51:35.695] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:89:0) after 1m0s
[11:52:35.870] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:89:0)
[11:52:35.870] Timer tick, asking Buildbucket for the build status
[11:52:35.953] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:52:35.953] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:90:0) after 1m0s
[11:53:36.076] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:90:0)
[11:53:36.076] Timer tick, asking Buildbucket for the build status
[11:53:36.229] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:53:36.229] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:91:0) after 1m0s
[11:54:37.016] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:91:0)
[11:54:37.016] Timer tick, asking Buildbucket for the build status
[11:54:37.353] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:54:37.353] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:92:0) after 1m0s
[11:55:39.874] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:92:0)
[11:55:39.874] Timer tick, asking Buildbucket for the build status
[11:55:39.973] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:55:39.973] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:93:0) after 1m0s
[11:56:40.161] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:93:0)
[11:56:40.161] Timer tick, asking Buildbucket for the build status
[11:56:40.227] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:56:40.227] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:94:0) after 1m0s
[11:57:40.528] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:94:0)
[11:57:40.528] Timer tick, asking Buildbucket for the build status
[11:57:40.597] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:57:40.597] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:95:0) after 1m0s
[11:58:40.831] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:95:0)
[11:58:40.831] Timer tick, asking Buildbucket for the build status
[11:58:41.098] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:58:41.098] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:96:0) after 1m0s
[11:59:41.282] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:96:0)
[11:59:41.282] Timer tick, asking Buildbucket for the build status
[11:59:41.346] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:59:41.346] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:97:0) after 1m0s
[12:00:41.503] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:97:0)
[12:00:41.504] Timer tick, asking Buildbucket for the build status
[12:00:41.628] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:00:41.628] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:98:0) after 1m0s
[12:01:41.768] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:98:0)
[12:01:41.769] Timer tick, asking Buildbucket for the build status
[12:01:41.897] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:01:41.897] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:99:0) after 1m0s
[12:02:42.004] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:99:0)
[12:02:42.004] Timer tick, asking Buildbucket for the build status
[12:02:42.068] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:02:42.068] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:100:0) after 1m0s
[12:03:42.156] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:100:0)
[12:03:42.156] Timer tick, asking Buildbucket for the build status
[12:03:42.354] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:03:42.354] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:101:0) after 1m0s
[12:04:44.698] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:101:0)
[12:04:44.698] Timer tick, asking Buildbucket for the build status
[12:04:44.775] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:04:44.775] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:102:0) after 1m0s
[12:05:45.183] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:102:0)
[12:05:45.183] Timer tick, asking Buildbucket for the build status
[12:05:45.291] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:05:45.291] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:103:0) after 1m0s
[12:06:45.376] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:103:0)
[12:06:45.376] Timer tick, asking Buildbucket for the build status
[12:06:45.586] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:06:45.586] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:104:0) after 1m0s
[12:07:45.779] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:104:0)
[12:07:45.779] Timer tick, asking Buildbucket for the build status
[12:07:45.978] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:07:45.978] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:105:0) after 1m0s
[12:08:46.130] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:105:0)
[12:08:46.130] Timer tick, asking Buildbucket for the build status
[12:08:46.294] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:08:46.294] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:106:0) after 1m0s
[12:09:46.418] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:106:0)
[12:09:46.418] Timer tick, asking Buildbucket for the build status
[12:09:46.489] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:09:46.489] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:107:0) after 1m0s
[12:10:46.585] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:107:0)
[12:10:46.585] Timer tick, asking Buildbucket for the build status
[12:10:46.982] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:10:46.982] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:108:0) after 1m0s
[12:11:47.229] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:108:0)
[12:11:47.229] Timer tick, asking Buildbucket for the build status
[12:11:47.304] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:11:47.304] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:109:0) after 1m0s
[12:12:47.415] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:109:0)
[12:12:47.416] Timer tick, asking Buildbucket for the build status
[12:12:47.493] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:12:47.493] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:110:0) after 1m0s
[12:13:47.608] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:110:0)
[12:13:47.608] Timer tick, asking Buildbucket for the build status
[12:13:47.951] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:13:47.951] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:111:0) after 1m0s
[12:14:48.008] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:111:0)
[12:14:48.008] Timer tick, asking Buildbucket for the build status
[12:14:48.093] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:14:48.093] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:112:0) after 1m0s
[12:15:48.213] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:112:0)
[12:15:48.213] Timer tick, asking Buildbucket for the build status
[12:15:48.607] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:15:48.607] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:113:0) after 1m0s
[12:16:48.806] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:113:0)
[12:16:48.806] Timer tick, asking Buildbucket for the build status
[12:16:48.886] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:16:48.886] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:114:0) after 1m0s
[12:17:48.969] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:114:0)
[12:17:48.969] Timer tick, asking Buildbucket for the build status
[12:17:49.107] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:17:49.107] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:115:0) after 1m0s
[12:18:49.183] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:115:0)
[12:18:49.183] Timer tick, asking Buildbucket for the build status
[12:18:49.408] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:18:49.408] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:116:0) after 1m0s
[12:19:49.440] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:116:0)
[12:19:49.440] Timer tick, asking Buildbucket for the build status
[12:19:49.596] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:19:49.596] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:117:0) after 1m0s
[12:20:49.627] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:117:0)
[12:20:49.627] Timer tick, asking Buildbucket for the build status
[12:20:50.156] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:20:50.156] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:118:0) after 1m0s
[12:21:50.302] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:118:0)
[12:21:50.302] Timer tick, asking Buildbucket for the build status
[12:21:50.374] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:21:50.374] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:119:0) after 1m0s
[12:22:50.585] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:119:0)
[12:22:50.585] Timer tick, asking Buildbucket for the build status
[12:22:50.641] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:22:50.641] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:120:0) after 1m0s
[12:23:50.721] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:120:0)
[12:23:50.721] Timer tick, asking Buildbucket for the build status
[12:23:50.780] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:23:50.780] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:121:0) after 1m0s
[12:24:50.860] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:121:0)
[12:24:50.860] Timer tick, asking Buildbucket for the build status
[12:24:50.944] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:24:50.944] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:122:0) after 1m0s
[12:25:51.069] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:122:0)
[12:25:51.069] Timer tick, asking Buildbucket for the build status
[12:25:51.211] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:25:51.211] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:123:0) after 1m0s
[12:26:51.440] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:123:0)
[12:26:51.440] Timer tick, asking Buildbucket for the build status
[12:26:51.508] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:26:51.508] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:124:0) after 1m0s
[12:27:51.716] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:124:0)
[12:27:51.716] Timer tick, asking Buildbucket for the build status
[12:27:51.826] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:27:51.826] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:125:0) after 1m0s
[12:28:51.880] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:125:0)
[12:28:51.880] Timer tick, asking Buildbucket for the build status
[12:28:51.953] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:28:51.953] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:126:0) after 1m0s
[12:29:51.994] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:126:0)
[12:29:51.994] Timer tick, asking Buildbucket for the build status
[12:29:52.132] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:29:52.132] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:127:0) after 1m0s
[12:30:52.121] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:127:0)
[12:30:52.121] Timer tick, asking Buildbucket for the build status
[12:30:52.246] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:30:52.246] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:128:0) after 1m0s
[12:31:52.290] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:128:0)
[12:31:52.290] Timer tick, asking Buildbucket for the build status
[12:31:52.352] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:31:52.352] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:129:0) after 1m0s
[12:32:52.414] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:129:0)
[12:32:52.414] Timer tick, asking Buildbucket for the build status
[12:32:52.517] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:32:52.517] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:130:0) after 1m0s
[12:33:52.622] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:130:0)
[12:33:52.622] Timer tick, asking Buildbucket for the build status
[12:33:52.697] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:33:52.697] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:131:0) after 1m0s
[12:34:52.779] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:131:0)
[12:34:52.779] Timer tick, asking Buildbucket for the build status
[12:34:52.980] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:34:52.980] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:132:0) after 1m0s
[12:35:53.049] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:132:0)
[12:35:53.049] Timer tick, asking Buildbucket for the build status
[12:35:53.173] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:35:53.173] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:133:0) after 1m0s
[12:36:53.239] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:133:0)
[12:36:53.239] Timer tick, asking Buildbucket for the build status
[12:36:53.306] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:36:53.306] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:134:0) after 1m0s
[12:37:53.376] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:134:0)
[12:37:53.376] Timer tick, asking Buildbucket for the build status
[12:37:53.436] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:37:53.436] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:135:0) after 1m0s
[12:38:53.521] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:135:0)
[12:38:53.521] Timer tick, asking Buildbucket for the build status
[12:38:53.722] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:38:53.722] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:136:0) after 1m0s
[12:39:53.711] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:136:0)
[12:39:53.711] Timer tick, asking Buildbucket for the build status
[12:39:53.772] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:39:53.772] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:137:0) after 1m0s
[12:40:53.829] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:137:0)
[12:40:53.829] Timer tick, asking Buildbucket for the build status
[12:40:53.937] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:40:53.937] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:138:0) after 1m0s
[12:41:53.955] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:138:0)
[12:41:53.955] Timer tick, asking Buildbucket for the build status
[12:41:54.063] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:41:54.063] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:139:0) after 1m0s
[12:42:54.038] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:139:0)
[12:42:54.038] Timer tick, asking Buildbucket for the build status
[12:42:54.113] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:42:54.113] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:140:0) after 1m0s
[12:43:54.113] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:140:0)
[12:43:54.113] Timer tick, asking Buildbucket for the build status
[12:43:54.191] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:43:54.191] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:141:0) after 1m0s
[12:44:54.271] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:141:0)
[12:44:54.271] Timer tick, asking Buildbucket for the build status
[12:44:54.373] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:44:54.373] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:142:0) after 1m0s
[12:45:54.604] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:142:0)
[12:45:54.604] Timer tick, asking Buildbucket for the build status
[12:45:54.720] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:45:54.720] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:143:0) after 1m0s
[12:46:54.846] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:143:0)
[12:46:54.846] Timer tick, asking Buildbucket for the build status
[12:46:54.944] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:46:54.944] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:144:0) after 1m0s
[12:47:55.090] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:144:0)
[12:47:55.090] Timer tick, asking Buildbucket for the build status
[12:47:55.163] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:47:55.163] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:145:0) after 1m0s
[12:48:55.259] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:145:0)
[12:48:55.259] Timer tick, asking Buildbucket for the build status
[12:48:55.321] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:48:55.321] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:146:0) after 1m0s
[12:49:55.378] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:146:0)
[12:49:55.378] Timer tick, asking Buildbucket for the build status
[12:49:55.447] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:49:55.447] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:147:0) after 1m0s
[12:50:55.513] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:147:0)
[12:50:55.513] Timer tick, asking Buildbucket for the build status
[12:50:55.682] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:50:55.682] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:148:0) after 1m0s
[12:51:55.899] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:148:0)
[12:51:55.899] Timer tick, asking Buildbucket for the build status
[12:51:55.958] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:51:55.958] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:149:0) after 1m0s
[12:52:56.282] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:149:0)
[12:52:56.282] Timer tick, asking Buildbucket for the build status
[12:52:56.373] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:52:56.373] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:150:0) after 1m0s
[12:53:56.592] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:150:0)
[12:53:56.592] Timer tick, asking Buildbucket for the build status
[12:53:56.671] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:53:56.671] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:151:0) after 1m0s
[12:54:56.878] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:151:0)
[12:54:56.878] Timer tick, asking Buildbucket for the build status
[12:54:56.941] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:54:56.941] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:152:0) after 1m0s
[12:55:57.034] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:152:0)
[12:55:57.034] Timer tick, asking Buildbucket for the build status
[12:55:57.150] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:55:57.150] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:153:0) after 1m0s
[12:56:57.215] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:153:0)
[12:56:57.215] Timer tick, asking Buildbucket for the build status
[12:56:57.284] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:56:57.284] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:154:0) after 1m0s
[12:57:57.346] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:154:0)
[12:57:57.346] Timer tick, asking Buildbucket for the build status
[12:57:57.419] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:57:57.419] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:155:0) after 1m0s
[12:58:57.484] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:155:0)
[12:58:57.484] Timer tick, asking Buildbucket for the build status
[12:58:57.544] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:58:57.544] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:156:0) after 1m0s
[12:59:57.596] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:156:0)
[12:59:57.596] Timer tick, asking Buildbucket for the build status
[12:59:57.653] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:59:57.653] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:157:0) after 1m0s
[13:00:57.699] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:157:0)
[13:00:57.699] Timer tick, asking Buildbucket for the build status
[13:00:57.815] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:00:57.815] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:158:0) after 1m0s
[13:01:57.882] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:158:0)
[13:01:57.882] Timer tick, asking Buildbucket for the build status
[13:01:57.949] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:01:57.949] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:159:0) after 1m0s
[13:02:57.946] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:159:0)
[13:02:57.946] Timer tick, asking Buildbucket for the build status
[13:02:58.014] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:02:58.014] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:160:0) after 1m0s
[13:03:58.782] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:160:0)
[13:03:58.782] Timer tick, asking Buildbucket for the build status
[13:03:58.896] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:03:58.896] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:161:0) after 1m0s
[13:04:59.145] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:161:0)
[13:04:59.145] Timer tick, asking Buildbucket for the build status
[13:04:59.217] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:04:59.217] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:162:0) after 1m0s
[13:05:59.786] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:162:0)
[13:05:59.786] Timer tick, asking Buildbucket for the build status
[13:05:59.955] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:05:59.955] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:163:0) after 1m0s
[13:06:59.983] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:163:0)
[13:06:59.983] Timer tick, asking Buildbucket for the build status
[13:07:00.212] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:07:00.212] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:164:0) after 1m0s
[13:08:00.248] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:164:0)
[13:08:00.248] Timer tick, asking Buildbucket for the build status
[13:08:00.312] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:08:00.313] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:165:0) after 1m0s
[13:09:00.384] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:165:0)
[13:09:00.384] Timer tick, asking Buildbucket for the build status
[13:09:00.455] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:09:00.455] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:166:0) after 1m0s
[13:10:00.479] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:166:0)
[13:10:00.480] Timer tick, asking Buildbucket for the build status
[13:10:00.537] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:10:00.537] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:167:0) after 1m0s
[13:11:02.234] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:167:0)
[13:11:02.234] Timer tick, asking Buildbucket for the build status
[13:11:02.362] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:11:02.363] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:168:0) after 1m0s
[13:12:02.386] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:168:0)
[13:12:02.386] Timer tick, asking Buildbucket for the build status
[13:12:02.700] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:12:02.700] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:169:0) after 1m0s
[13:13:02.992] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:169:0)
[13:13:02.992] Timer tick, asking Buildbucket for the build status
[13:13:03.054] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:13:03.054] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:170:0) after 1m0s
[13:14:03.183] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:170:0)
[13:14:03.183] Timer tick, asking Buildbucket for the build status
[13:14:03.249] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:14:03.249] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:171:0) after 1m0s
[13:15:03.529] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:171:0)
[13:15:03.529] Timer tick, asking Buildbucket for the build status
[13:15:03.876] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:15:03.876] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:172:0) after 1m0s
[13:16:03.991] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:172:0)
[13:16:03.991] Timer tick, asking Buildbucket for the build status
[13:16:04.300] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:16:04.300] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:173:0) after 1m0s
[13:17:04.395] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:173:0)
[13:17:04.395] Timer tick, asking Buildbucket for the build status
[13:17:04.619] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:17:04.619] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:174:0) after 1m0s
[13:18:04.766] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:174:0)
[13:18:04.766] Timer tick, asking Buildbucket for the build status
[13:18:05.805] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:18:05.805] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:175:0) after 1m0s
[13:19:05.917] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:175:0)
[13:19:05.917] Timer tick, asking Buildbucket for the build status
[13:19:05.981] Build 8866018260932901744: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:19:05.981] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031448323035798896:176:0) after 1m0s
[13:20:02.764] Received PubSub notification, asking Buildbucket for the build status
[13:20:02.837] Build 8866018260932901744: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[13:20:02.837] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"completed_ts": "1603113601913827",
"created_by": "project:chromium",
"created_ts": "1603103117967756",
"id": "8866018260932901744",
"parameters_json": "{\"builder_name\": \"win32-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"6a201596755da73a4570e8bf6f1af63ea444e236\"}}",
"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\": \"6a201596755da73a4570e8bf6f1af63ea444e236\", \"project\": \"chromium/src\"}], \"$recipe_engine/path\": {\"cache_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\cache\", \"temp_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\tmp\\\\rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"win-dbg-4-9azj\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium\", \"buildername\": \"win32-official\", \"buildnumber\": 770, \"got_angle_revision\": \"c69ef8bf34ca3d7c1e8a4e187280e5b06e21e605\", \"got_dawn_revision\": \"875091308b04ba87e29c929ed707f27889c660ea\", \"got_nacl_revision\": \"0fabd6dc4222dfae664e55a84326e0c904f5b62c\", \"got_revision\": \"6a201596755da73a4570e8bf6f1af63ea444e236\", \"got_revision_cp\": \"refs/heads/master@{#818411}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"df17a76102dfabb3f1bd6e51449cece9f77b45e3\", \"got_v8_revision\": \"93ed90e664430291b53e3ee3f26d4dbc0e3c7804\", \"got_v8_revision_cp\": \"refs/heads/8.8.121@{#1}\", \"got_webrtc_revision\": \"1f7eab68c0ce267b935f9ea3544d3105d7fd19dc\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32431}\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"6a201596755da73a4570e8bf6f1af63ea444e236\"}, \"swarming\": {\"bot_dimensions\": {\"builder\": [\"win-archive-dbg\", \"win-archive-rel\", \"win-official\", \"win32-archive-dbg\", \"win32-archive-rel\", \"win32-official\"], \"caches\": [\"builder_f6b49936a2965b3cdad8f63ce13313d0c4b82119a9acfffbfe199e91c26d0c88_v2\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"win-dbg-4-9azj\"], \"image\": [\"chrome-win10-1703-20092300-22ba5320847\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"e2-standard-32\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"5601-97d7e7d\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1603103162172401",
"status": "COMPLETED",
"status_changed_ts": "1603113602293027",
"tags": [
"build_address:luci.chromium.ci/win32-official/770",
"builder:win32-official",
"buildset:commit/git/6a201596755da73a4570e8bf6f1af63ea444e236",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/6a201596755da73a4570e8bf6f1af63ea444e236",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9031448323035798896",
"scheduler_job_id:chromium/win32-official",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8866018260932901744/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:os:Windows",
"swarming_tag:recipe_name:chromium",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:4f5935dd5c86f510",
"user_agent:luci-scheduler"
],
"updated_ts": "1603113602297159",
"url": "https://ci.chromium.org/b/8866018260932901744",
"utcnow_ts": "1603113602827676"
}
}
[13:20:02.837] Invocation finished in 2h54m46.25839952s with status SUCCEEDED