[05:52:44.482] New invocation is queued and will start shortly
[05:52:45.740] Starting the invocation (attempt 1)
[05:52:45.777] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[05:52:45.777] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[05:52:45.777] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9037354272287546352",
"parameters_json": "{\"builder_name\":\"win-official\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"8067bb7dae6a3f44e6fbf5e889c0cad0e21c9cdc\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-official",
"scheduler_invocation_id:9037354272287546352",
"scheduler_job_id:chromium/win-official",
"user_agent:luci-scheduler",
"buildset:commit/git/8067bb7dae6a3f44e6fbf5e889c0cad0e21c9cdc",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/8067bb7dae6a3f44e6fbf5e889c0cad0e21c9cdc",
"gitiles_ref:refs/heads/master"
]
}
[05:52:46.593] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1597470765862223",
"id": "8871924210173716688",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"8067bb7dae6a3f44e6fbf5e889c0cad0e21c9cdc\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1597470766390220",
"tags": [
"build_address:luci.chromium.ci/win-official/342",
"builder:win-official",
"buildset:commit/git/8067bb7dae6a3f44e6fbf5e889c0cad0e21c9cdc",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/8067bb7dae6a3f44e6fbf5e889c0cad0e21c9cdc",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9037354272287546352",
"scheduler_job_id:chromium/win-official",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8871924210173716688/+/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": "1597470766390400",
"url": "https://ci.chromium.org/b/8871924210173716688",
"utcnow_ts": "1597470766579303"
}
}
[05:52:46.593] Task URL: https://ci.chromium.org/b/8871924210173716688
[05:52:46.593] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:2:0) after 1m0s
[05:53:11.550] Received PubSub notification, asking Buildbucket for the build status
[05:53:11.716] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:53:46.619] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:2:0)
[05:53:46.619] Timer tick, asking Buildbucket for the build status
[05:53:46.726] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:53:46.727] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:4:0) after 1m0s
[05:54:46.728] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:4:0)
[05:54:46.728] Timer tick, asking Buildbucket for the build status
[05:54:46.910] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:54:46.910] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:5:0) after 1m0s
[05:55:46.976] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:5:0)
[05:55:46.976] Timer tick, asking Buildbucket for the build status
[05:55:47.144] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:55:47.144] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:6:0) after 1m0s
[05:56:47.195] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:6:0)
[05:56:47.195] Timer tick, asking Buildbucket for the build status
[05:56:47.312] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:56:47.312] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:7:0) after 1m0s
[05:57:47.404] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:7:0)
[05:57:47.404] Timer tick, asking Buildbucket for the build status
[05:57:49.136] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:57:49.136] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:8:0) after 1m0s
[05:58:49.157] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:8:0)
[05:58:49.157] Timer tick, asking Buildbucket for the build status
[05:58:49.497] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:58:49.497] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:9:0) after 1m0s
[05:59:49.552] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:9:0)
[05:59:49.552] Timer tick, asking Buildbucket for the build status
[05:59:51.413] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:59:51.413] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:10:0) after 1m0s
[06:00:51.471] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:10:0)
[06:00:51.471] Timer tick, asking Buildbucket for the build status
[06:00:51.563] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:00:51.563] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:11:0) after 1m0s
[06:01:51.619] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:11:0)
[06:01:51.619] Timer tick, asking Buildbucket for the build status
[06:01:51.864] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:01:51.864] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:12:0) after 1m0s
[06:02:51.999] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:12:0)
[06:02:51.999] Timer tick, asking Buildbucket for the build status
[06:02:52.240] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:02:52.240] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:13:0) after 1m0s
[06:03:52.288] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:13:0)
[06:03:52.288] Timer tick, asking Buildbucket for the build status
[06:03:52.356] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:03:52.356] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:14:0) after 1m0s
[06:04:52.403] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:14:0)
[06:04:52.403] Timer tick, asking Buildbucket for the build status
[06:04:52.511] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:04:52.511] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:15:0) after 1m0s
[06:05:52.629] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:15:0)
[06:05:52.629] Timer tick, asking Buildbucket for the build status
[06:05:52.727] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:05:52.727] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:16:0) after 1m0s
[06:06:52.781] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:16:0)
[06:06:52.781] Timer tick, asking Buildbucket for the build status
[06:06:52.852] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:06:52.852] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:17:0) after 1m0s
[06:07:52.922] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:17:0)
[06:07:52.922] Timer tick, asking Buildbucket for the build status
[06:07:56.626] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:07:56.626] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:18:0) after 1m0s
[06:08:56.680] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:18:0)
[06:08:56.680] Timer tick, asking Buildbucket for the build status
[06:08:56.886] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:08:56.886] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:19:0) after 1m0s
[06:09:56.912] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:19:0)
[06:09:56.912] Timer tick, asking Buildbucket for the build status
[06:09:57.013] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:09:57.013] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:20:0) after 1m0s
[06:10:57.041] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:20:0)
[06:10:57.041] Timer tick, asking Buildbucket for the build status
[06:10:57.206] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:10:57.206] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:21:0) after 1m0s
[06:11:57.225] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:21:0)
[06:11:57.225] Timer tick, asking Buildbucket for the build status
[06:11:58.819] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:11:58.819] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:22:0) after 1m0s
[06:12:58.827] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:22:0)
[06:12:58.827] Timer tick, asking Buildbucket for the build status
[06:12:58.974] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:12:58.974] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:23:0) after 1m0s
[06:13:59.008] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:23:0)
[06:13:59.008] Timer tick, asking Buildbucket for the build status
[06:13:59.137] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:13:59.137] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:24:0) after 1m0s
[06:14:59.159] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:24:0)
[06:14:59.159] Timer tick, asking Buildbucket for the build status
[06:14:59.522] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:14:59.522] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:25:0) after 1m0s
[06:15:59.621] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:25:0)
[06:15:59.621] Timer tick, asking Buildbucket for the build status
[06:15:59.918] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:15:59.918] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:26:0) after 1m0s
[06:17:00.061] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:26:0)
[06:17:00.061] Timer tick, asking Buildbucket for the build status
[06:17:00.662] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:17:00.662] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:27:0) after 1m0s
[06:18:00.704] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:27:0)
[06:18:00.704] Timer tick, asking Buildbucket for the build status
[06:18:00.815] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:18:00.815] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:28:0) after 1m0s
[06:19:01.823] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:28:0)
[06:19:01.823] Timer tick, asking Buildbucket for the build status
[06:19:02.225] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:19:02.225] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:29:0) after 1m0s
[06:20:02.666] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:29:0)
[06:20:02.666] Timer tick, asking Buildbucket for the build status
[06:20:02.900] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:20:02.900] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:30:0) after 1m0s
[06:21:03.006] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:30:0)
[06:21:03.006] Timer tick, asking Buildbucket for the build status
[06:21:03.152] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:21:03.152] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:31:0) after 1m0s
[06:22:03.314] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:31:0)
[06:22:03.314] Timer tick, asking Buildbucket for the build status
[06:22:03.427] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:22:03.427] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:32:0) after 1m0s
[06:23:03.435] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:32:0)
[06:23:03.435] Timer tick, asking Buildbucket for the build status
[06:23:03.562] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:23:03.562] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:33:0) after 1m0s
[06:24:03.606] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:33:0)
[06:24:03.606] Timer tick, asking Buildbucket for the build status
[06:24:03.685] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:24:03.685] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:34:0) after 1m0s
[06:25:03.709] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:34:0)
[06:25:03.709] Timer tick, asking Buildbucket for the build status
[06:25:04.976] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:25:04.976] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:35:0) after 1m0s
[06:26:04.978] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:35:0)
[06:26:04.978] Timer tick, asking Buildbucket for the build status
[06:26:05.072] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:26:05.072] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:36:0) after 1m0s
[06:27:05.145] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:36:0)
[06:27:05.145] Timer tick, asking Buildbucket for the build status
[06:27:05.326] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:27:05.326] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:37:0) after 1m0s
[06:28:05.348] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:37:0)
[06:28:05.348] Timer tick, asking Buildbucket for the build status
[06:28:05.493] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:28:05.493] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:38:0) after 1m0s
[06:29:05.524] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:38:0)
[06:29:05.524] Timer tick, asking Buildbucket for the build status
[06:29:05.607] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:29:05.607] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:39:0) after 1m0s
[06:30:05.663] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:39:0)
[06:30:05.663] Timer tick, asking Buildbucket for the build status
[06:30:05.812] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:30:05.812] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:40:0) after 1m0s
[06:31:05.806] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:40:0)
[06:31:05.806] Timer tick, asking Buildbucket for the build status
[06:31:05.957] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:31:05.957] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:41:0) after 1m0s
[06:32:06.041] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:41:0)
[06:32:06.041] Timer tick, asking Buildbucket for the build status
[06:32:06.250] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:32:06.250] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:42:0) after 1m0s
[06:33:06.288] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:42:0)
[06:33:06.288] Timer tick, asking Buildbucket for the build status
[06:33:06.416] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:33:06.417] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:43:0) after 1m0s
[06:34:06.444] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:43:0)
[06:34:06.444] Timer tick, asking Buildbucket for the build status
[06:34:06.544] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:34:06.544] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:44:0) after 1m0s
[06:35:06.573] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:44:0)
[06:35:06.573] Timer tick, asking Buildbucket for the build status
[06:35:06.988] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:35:06.988] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:45:0) after 1m0s
[06:36:07.023] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:45:0)
[06:36:07.023] Timer tick, asking Buildbucket for the build status
[06:36:07.219] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:36:07.219] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:46:0) after 1m0s
[06:37:07.199] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:46:0)
[06:37:07.199] Timer tick, asking Buildbucket for the build status
[06:37:07.286] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:37:07.286] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:47:0) after 1m0s
[06:38:07.354] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:47:0)
[06:38:07.354] Timer tick, asking Buildbucket for the build status
[06:38:07.473] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:38:07.473] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:48:0) after 1m0s
[06:39:07.530] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:48:0)
[06:39:07.530] Timer tick, asking Buildbucket for the build status
[06:39:07.611] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:39:07.611] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:49:0) after 1m0s
[06:40:07.685] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:49:0)
[06:40:07.685] Timer tick, asking Buildbucket for the build status
[06:40:07.761] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:40:07.761] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:50:0) after 1m0s
[06:41:07.804] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:50:0)
[06:41:07.804] Timer tick, asking Buildbucket for the build status
[06:41:07.887] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:41:07.887] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:51:0) after 1m0s
[06:42:07.921] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:51:0)
[06:42:07.921] Timer tick, asking Buildbucket for the build status
[06:42:08.014] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:42:08.014] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:52:0) after 1m0s
[06:43:08.096] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:52:0)
[06:43:08.096] Timer tick, asking Buildbucket for the build status
[06:43:08.230] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:43:08.230] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:53:0) after 1m0s
[06:44:08.259] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:53:0)
[06:44:08.259] Timer tick, asking Buildbucket for the build status
[06:44:08.340] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:44:08.340] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:54:0) after 1m0s
[06:45:08.432] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:54:0)
[06:45:08.432] Timer tick, asking Buildbucket for the build status
[06:45:08.551] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:45:08.551] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:55:0) after 1m0s
[06:46:08.604] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:55:0)
[06:46:08.604] Timer tick, asking Buildbucket for the build status
[06:46:08.671] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:46:08.671] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:56:0) after 1m0s
[06:47:08.789] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:56:0)
[06:47:08.789] Timer tick, asking Buildbucket for the build status
[06:47:09.065] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:47:09.065] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:57:0) after 1m0s
[06:48:09.104] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:57:0)
[06:48:09.104] Timer tick, asking Buildbucket for the build status
[06:48:09.263] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:48:09.263] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:58:0) after 1m0s
[06:49:09.346] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:58:0)
[06:49:09.346] Timer tick, asking Buildbucket for the build status
[06:49:09.415] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:49:09.415] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:59:0) after 1m0s
[06:50:09.435] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:59:0)
[06:50:09.435] Timer tick, asking Buildbucket for the build status
[06:50:09.531] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:50:09.531] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:60:0) after 1m0s
[06:51:09.545] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:60:0)
[06:51:09.545] Timer tick, asking Buildbucket for the build status
[06:51:09.650] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:51:09.650] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:61:0) after 1m0s
[06:52:09.757] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:61:0)
[06:52:09.757] Timer tick, asking Buildbucket for the build status
[06:52:11.441] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:52:11.441] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:62:0) after 1m0s
[06:53:11.488] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:62:0)
[06:53:11.488] Timer tick, asking Buildbucket for the build status
[06:53:11.617] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:53:11.617] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:63:0) after 1m0s
[06:54:11.677] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:63:0)
[06:54:11.677] Timer tick, asking Buildbucket for the build status
[06:54:11.928] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:54:11.928] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:64:0) after 1m0s
[06:55:12.066] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:64:0)
[06:55:12.066] Timer tick, asking Buildbucket for the build status
[06:55:12.212] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:55:12.212] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:65:0) after 1m0s
[06:56:12.236] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:65:0)
[06:56:12.236] Timer tick, asking Buildbucket for the build status
[06:56:12.328] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:56:12.328] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:66:0) after 1m0s
[06:57:12.356] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:66:0)
[06:57:12.356] Timer tick, asking Buildbucket for the build status
[06:57:12.716] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:57:12.716] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:67:0) after 1m0s
[06:58:12.865] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:67:0)
[06:58:12.865] Timer tick, asking Buildbucket for the build status
[06:58:13.086] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:58:13.086] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:68:0) after 1m0s
[06:59:13.083] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:68:0)
[06:59:13.083] Timer tick, asking Buildbucket for the build status
[06:59:13.219] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:59:13.219] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:69:0) after 1m0s
[07:00:13.287] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:69:0)
[07:00:13.287] Timer tick, asking Buildbucket for the build status
[07:00:13.503] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:00:13.503] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:70:0) after 1m0s
[07:01:13.535] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:70:0)
[07:01:13.535] Timer tick, asking Buildbucket for the build status
[07:01:13.619] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:01:13.619] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:71:0) after 1m0s
[07:02:13.721] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:71:0)
[07:02:13.721] Timer tick, asking Buildbucket for the build status
[07:02:13.802] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:02:13.802] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:72:0) after 1m0s
[07:03:13.821] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:72:0)
[07:03:13.821] Timer tick, asking Buildbucket for the build status
[07:03:14.341] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:03:14.341] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:73:0) after 1m0s
[07:04:14.369] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:73:0)
[07:04:14.369] Timer tick, asking Buildbucket for the build status
[07:04:14.492] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:04:14.492] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:74:0) after 1m0s
[07:05:14.551] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:74:0)
[07:05:14.551] Timer tick, asking Buildbucket for the build status
[07:05:14.674] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:05:14.674] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:75:0) after 1m0s
[07:06:14.733] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:75:0)
[07:06:14.733] Timer tick, asking Buildbucket for the build status
[07:06:14.800] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:06:14.800] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:76:0) after 1m0s
[07:07:14.898] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:76:0)
[07:07:14.898] Timer tick, asking Buildbucket for the build status
[07:07:14.988] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:07:14.988] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:77:0) after 1m0s
[07:08:15.137] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:77:0)
[07:08:15.137] Timer tick, asking Buildbucket for the build status
[07:08:15.823] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:08:15.823] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:78:0) after 1m0s
[07:09:15.937] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:78:0)
[07:09:15.937] Timer tick, asking Buildbucket for the build status
[07:09:16.069] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:09:16.070] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:79:0) after 1m0s
[07:10:16.103] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:79:0)
[07:10:16.103] Timer tick, asking Buildbucket for the build status
[07:10:16.197] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:10:16.197] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:80:0) after 1m0s
[07:11:16.314] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:80:0)
[07:11:16.314] Timer tick, asking Buildbucket for the build status
[07:11:16.510] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:11:16.510] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:81:0) after 1m0s
[07:12:16.542] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:81:0)
[07:12:16.542] Timer tick, asking Buildbucket for the build status
[07:12:16.660] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:12:16.660] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:82:0) after 1m0s
[07:13:16.723] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:82:0)
[07:13:16.723] Timer tick, asking Buildbucket for the build status
[07:13:16.844] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:13:16.844] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:83:0) after 1m0s
[07:14:16.900] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:83:0)
[07:14:16.900] Timer tick, asking Buildbucket for the build status
[07:14:17.063] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:14:17.063] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:84:0) after 1m0s
[07:15:17.113] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:84:0)
[07:15:17.113] Timer tick, asking Buildbucket for the build status
[07:15:17.404] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:15:17.404] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:85:0) after 1m0s
[07:16:17.439] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:85:0)
[07:16:17.439] Timer tick, asking Buildbucket for the build status
[07:16:17.544] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:16:17.544] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:86:0) after 1m0s
[07:17:17.527] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:86:0)
[07:17:17.527] Timer tick, asking Buildbucket for the build status
[07:17:17.580] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:17:17.580] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:87:0) after 1m0s
[07:18:17.658] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:87:0)
[07:18:17.658] Timer tick, asking Buildbucket for the build status
[07:18:18.123] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:18:18.123] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:88:0) after 1m0s
[07:19:18.321] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:88:0)
[07:19:18.321] Timer tick, asking Buildbucket for the build status
[07:19:18.743] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:19:18.743] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:89:0) after 1m0s
[07:20:18.783] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:89:0)
[07:20:18.783] Timer tick, asking Buildbucket for the build status
[07:20:18.991] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:20:18.991] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:90:0) after 1m0s
[07:21:19.131] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:90:0)
[07:21:19.131] Timer tick, asking Buildbucket for the build status
[07:21:19.218] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:21:19.218] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:91:0) after 1m0s
[07:22:19.326] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:91:0)
[07:22:19.326] Timer tick, asking Buildbucket for the build status
[07:22:19.417] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:22:19.417] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:92:0) after 1m0s
[07:23:20.244] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:92:0)
[07:23:20.244] Timer tick, asking Buildbucket for the build status
[07:23:20.417] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:23:20.417] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:93:0) after 1m0s
[07:24:20.466] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:93:0)
[07:24:20.466] Timer tick, asking Buildbucket for the build status
[07:24:20.571] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:24:20.571] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:94:0) after 1m0s
[07:25:20.653] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:94:0)
[07:25:20.653] Timer tick, asking Buildbucket for the build status
[07:25:22.766] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:25:22.766] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:95:0) after 1m0s
[07:26:22.843] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:95:0)
[07:26:22.843] Timer tick, asking Buildbucket for the build status
[07:26:23.181] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:26:23.181] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:96:0) after 1m0s
[07:27:23.159] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:96:0)
[07:27:23.159] Timer tick, asking Buildbucket for the build status
[07:27:23.344] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:27:23.344] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:97:0) after 1m0s
[07:28:23.405] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:97:0)
[07:28:23.405] Timer tick, asking Buildbucket for the build status
[07:28:23.531] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:28:23.531] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:98:0) after 1m0s
[07:29:23.559] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:98:0)
[07:29:23.559] Timer tick, asking Buildbucket for the build status
[07:29:23.623] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:29:23.623] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:99:0) after 1m0s
[07:30:23.676] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:99:0)
[07:30:23.676] Timer tick, asking Buildbucket for the build status
[07:30:23.915] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:30:23.915] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:100:0) after 1m0s
[07:31:23.920] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:100:0)
[07:31:23.920] Timer tick, asking Buildbucket for the build status
[07:31:25.582] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:31:25.582] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:101:0) after 1m0s
[07:32:25.685] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:101:0)
[07:32:25.685] Timer tick, asking Buildbucket for the build status
[07:32:25.748] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:32:25.748] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:102:0) after 1m0s
[07:33:25.778] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:102:0)
[07:33:25.778] Timer tick, asking Buildbucket for the build status
[07:33:25.902] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:33:25.902] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:103:0) after 1m0s
[07:34:25.928] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:103:0)
[07:34:25.928] Timer tick, asking Buildbucket for the build status
[07:34:26.100] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:34:26.100] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:104:0) after 1m0s
[07:35:26.196] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:104:0)
[07:35:26.196] Timer tick, asking Buildbucket for the build status
[07:35:26.285] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:35:26.285] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:105:0) after 1m0s
[07:36:26.392] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:105:0)
[07:36:26.392] Timer tick, asking Buildbucket for the build status
[07:36:26.503] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:36:26.503] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:106:0) after 1m0s
[07:37:26.596] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:106:0)
[07:37:26.596] Timer tick, asking Buildbucket for the build status
[07:37:26.722] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:37:26.722] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:107:0) after 1m0s
[07:38:26.812] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:107:0)
[07:38:26.812] Timer tick, asking Buildbucket for the build status
[07:38:27.064] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:38:27.064] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:108:0) after 1m0s
[07:39:27.070] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:108:0)
[07:39:27.070] Timer tick, asking Buildbucket for the build status
[07:39:27.164] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:39:27.164] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:109:0) after 1m0s
[07:40:27.257] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:109:0)
[07:40:27.257] Timer tick, asking Buildbucket for the build status
[07:40:27.450] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:40:27.450] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:110:0) after 1m0s
[07:41:27.513] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:110:0)
[07:41:27.513] Timer tick, asking Buildbucket for the build status
[07:41:27.655] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:41:27.655] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:111:0) after 1m0s
[07:42:27.682] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:111:0)
[07:42:27.682] Timer tick, asking Buildbucket for the build status
[07:42:27.982] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:42:27.982] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:112:0) after 1m0s
[07:43:28.046] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:112:0)
[07:43:28.046] Timer tick, asking Buildbucket for the build status
[07:43:28.234] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:43:28.234] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:113:0) after 1m0s
[07:44:28.256] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:113:0)
[07:44:28.256] Timer tick, asking Buildbucket for the build status
[07:44:28.347] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:44:28.347] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:114:0) after 1m0s
[07:45:28.392] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:114:0)
[07:45:28.392] Timer tick, asking Buildbucket for the build status
[07:45:28.551] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:45:28.551] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:115:0) after 1m0s
[07:46:28.577] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:115:0)
[07:46:28.577] Timer tick, asking Buildbucket for the build status
[07:46:28.667] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:46:28.667] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:116:0) after 1m0s
[07:47:28.691] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:116:0)
[07:47:28.691] Timer tick, asking Buildbucket for the build status
[07:47:28.766] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:47:28.766] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:117:0) after 1m0s
[07:48:28.802] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:117:0)
[07:48:28.802] Timer tick, asking Buildbucket for the build status
[07:48:28.984] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:48:28.985] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:118:0) after 1m0s
[07:49:29.043] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:118:0)
[07:49:29.043] Timer tick, asking Buildbucket for the build status
[07:49:29.129] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:49:29.129] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:119:0) after 1m0s
[07:50:29.166] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:119:0)
[07:50:29.166] Timer tick, asking Buildbucket for the build status
[07:50:29.246] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:50:29.246] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:120:0) after 1m0s
[07:51:29.271] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:120:0)
[07:51:29.271] Timer tick, asking Buildbucket for the build status
[07:51:29.347] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:51:29.347] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:121:0) after 1m0s
[07:52:29.380] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:121:0)
[07:52:29.380] Timer tick, asking Buildbucket for the build status
[07:52:29.460] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:52:29.460] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:122:0) after 1m0s
[07:53:29.514] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:122:0)
[07:53:29.514] Timer tick, asking Buildbucket for the build status
[07:53:30.381] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:53:30.381] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:123:0) after 1m0s
[07:54:30.430] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:123:0)
[07:54:30.430] Timer tick, asking Buildbucket for the build status
[07:54:30.495] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:54:30.495] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:124:0) after 1m0s
[07:55:30.525] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:124:0)
[07:55:30.525] Timer tick, asking Buildbucket for the build status
[07:55:30.881] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:55:30.881] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:125:0) after 1m0s
[07:56:30.911] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:125:0)
[07:56:30.911] Timer tick, asking Buildbucket for the build status
[07:56:31.109] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:56:31.109] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:126:0) after 1m0s
[07:57:31.134] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:126:0)
[07:57:31.134] Timer tick, asking Buildbucket for the build status
[07:57:31.240] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:57:31.240] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:127:0) after 1m0s
[07:58:31.333] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:127:0)
[07:58:31.333] Timer tick, asking Buildbucket for the build status
[07:58:31.472] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:58:31.472] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:128:0) after 1m0s
[07:59:31.550] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:128:0)
[07:59:31.550] Timer tick, asking Buildbucket for the build status
[07:59:31.926] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:59:31.926] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:129:0) after 1m0s
[08:00:31.959] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:129:0)
[08:00:31.959] Timer tick, asking Buildbucket for the build status
[08:00:32.046] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:00:32.046] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:130:0) after 1m0s
[08:01:32.169] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:130:0)
[08:01:32.169] Timer tick, asking Buildbucket for the build status
[08:01:32.266] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:01:32.266] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:131:0) after 1m0s
[08:02:32.304] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:131:0)
[08:02:32.304] Timer tick, asking Buildbucket for the build status
[08:02:32.391] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:02:32.391] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:132:0) after 1m0s
[08:03:32.417] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:132:0)
[08:03:32.417] Timer tick, asking Buildbucket for the build status
[08:03:32.657] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:03:32.657] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:133:0) after 1m0s
[08:04:32.688] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:133:0)
[08:04:32.688] Timer tick, asking Buildbucket for the build status
[08:04:35.255] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:04:35.255] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:134:0) after 1m0s
[08:05:35.299] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:134:0)
[08:05:35.299] Timer tick, asking Buildbucket for the build status
[08:05:35.384] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:05:35.384] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:135:0) after 1m0s
[08:06:35.432] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:135:0)
[08:06:35.432] Timer tick, asking Buildbucket for the build status
[08:06:35.512] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:06:35.512] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:136:0) after 1m0s
[08:07:35.556] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:136:0)
[08:07:35.556] Timer tick, asking Buildbucket for the build status
[08:07:35.684] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:07:35.684] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:137:0) after 1m0s
[08:08:35.721] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:137:0)
[08:08:35.721] Timer tick, asking Buildbucket for the build status
[08:08:35.902] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:08:35.902] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:138:0) after 1m0s
[08:09:35.931] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:138:0)
[08:09:35.931] Timer tick, asking Buildbucket for the build status
[08:09:36.057] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:09:36.057] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:139:0) after 1m0s
[08:10:36.102] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:139:0)
[08:10:36.102] Timer tick, asking Buildbucket for the build status
[08:10:36.180] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:10:36.180] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:140:0) after 1m0s
[08:11:36.232] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:140:0)
[08:11:36.232] Timer tick, asking Buildbucket for the build status
[08:11:36.296] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:11:36.296] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:141:0) after 1m0s
[08:12:36.415] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:141:0)
[08:12:36.415] Timer tick, asking Buildbucket for the build status
[08:12:36.538] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:12:36.538] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:142:0) after 1m0s
[08:13:36.634] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:142:0)
[08:13:36.634] Timer tick, asking Buildbucket for the build status
[08:13:36.758] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:13:36.758] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:143:0) after 1m0s
[08:14:36.913] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:143:0)
[08:14:36.913] Timer tick, asking Buildbucket for the build status
[08:14:37.057] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:14:37.057] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:144:0) after 1m0s
[08:15:37.118] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:144:0)
[08:15:37.118] Timer tick, asking Buildbucket for the build status
[08:15:37.307] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:15:37.307] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:145:0) after 1m0s
[08:16:37.384] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:145:0)
[08:16:37.384] Timer tick, asking Buildbucket for the build status
[08:16:37.517] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:16:37.517] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:146:0) after 1m0s
[08:17:37.587] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:146:0)
[08:17:37.587] Timer tick, asking Buildbucket for the build status
[08:17:37.683] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:17:37.683] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:147:0) after 1m0s
[08:18:37.733] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:147:0)
[08:18:37.733] Timer tick, asking Buildbucket for the build status
[08:18:37.896] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:18:37.896] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:148:0) after 1m0s
[08:19:37.979] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:148:0)
[08:19:37.979] Timer tick, asking Buildbucket for the build status
[08:19:38.063] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:19:38.063] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:149:0) after 1m0s
[08:20:38.139] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:149:0)
[08:20:38.139] Timer tick, asking Buildbucket for the build status
[08:20:38.924] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:20:38.924] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:150:0) after 1m0s
[08:21:39.138] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:150:0)
[08:21:39.138] Timer tick, asking Buildbucket for the build status
[08:21:39.213] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:21:39.213] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:151:0) after 1m0s
[08:22:39.302] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:151:0)
[08:22:39.302] Timer tick, asking Buildbucket for the build status
[08:22:39.497] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:22:39.497] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:152:0) after 1m0s
[08:23:39.573] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:152:0)
[08:23:39.573] Timer tick, asking Buildbucket for the build status
[08:23:40.192] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:23:40.192] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:153:0) after 1m0s
[08:24:40.319] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:153:0)
[08:24:40.319] Timer tick, asking Buildbucket for the build status
[08:24:40.392] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:24:40.392] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:154:0) after 1m0s
[08:25:40.471] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:154:0)
[08:25:40.471] Timer tick, asking Buildbucket for the build status
[08:25:40.602] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:25:40.602] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:155:0) after 1m0s
[08:26:40.637] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:155:0)
[08:26:40.637] Timer tick, asking Buildbucket for the build status
[08:26:40.736] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:26:40.736] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:156:0) after 1m0s
[08:27:40.792] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:156:0)
[08:27:40.792] Timer tick, asking Buildbucket for the build status
[08:27:40.894] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:27:40.894] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:157:0) after 1m0s
[08:28:40.927] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:157:0)
[08:28:40.927] Timer tick, asking Buildbucket for the build status
[08:28:41.088] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:28:41.088] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:158:0) after 1m0s
[08:29:41.090] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:158:0)
[08:29:41.090] Timer tick, asking Buildbucket for the build status
[08:29:41.286] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:29:41.286] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:159:0) after 1m0s
[08:30:41.313] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:159:0)
[08:30:41.313] Timer tick, asking Buildbucket for the build status
[08:30:41.397] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:30:41.397] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:160:0) after 1m0s
[08:31:41.434] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:160:0)
[08:31:41.434] Timer tick, asking Buildbucket for the build status
[08:31:41.677] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:31:41.677] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:161:0) after 1m0s
[08:32:41.755] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:161:0)
[08:32:41.755] Timer tick, asking Buildbucket for the build status
[08:32:41.843] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:32:41.843] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:162:0) after 1m0s
[08:33:41.917] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:162:0)
[08:33:41.917] Timer tick, asking Buildbucket for the build status
[08:33:42.203] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:33:42.203] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:163:0) after 1m0s
[08:34:42.221] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:163:0)
[08:34:42.221] Timer tick, asking Buildbucket for the build status
[08:34:42.300] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:34:42.300] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:164:0) after 1m0s
[08:35:42.434] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:164:0)
[08:35:42.434] Timer tick, asking Buildbucket for the build status
[08:35:42.532] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:35:42.533] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:165:0) after 1m0s
[08:36:42.528] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:165:0)
[08:36:42.528] Timer tick, asking Buildbucket for the build status
[08:36:42.887] Build 8871924210173716688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:36:42.887] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037354272287546352:166:0) after 1m0s
[08:37:31.464] Received PubSub notification, asking Buildbucket for the build status
[08:37:31.526] Build 8871924210173716688: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[08:37:31.526] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1597480650469484",
"created_by": "project:chromium",
"created_ts": "1597470765862223",
"id": "8871924210173716688",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"8067bb7dae6a3f44e6fbf5e889c0cad0e21c9cdc\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/chromium_tests\": {\"bucketed_triggers\": true}, \"$build/goma\": {\"enable_ats\": true, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\"}, \"$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-8-yk3i\", \"branch\": \"refs/heads/master\", \"buildername\": \"win-official\", \"buildnumber\": 342, \"got_angle_revision\": \"9ae6d7f2ee9e12b707483dc9cadd7c3529595016\", \"got_dawn_revision\": \"f580096468450bfa697ea9e118e891962ff6f796\", \"got_nacl_revision\": \"3008f9e6de122325d8f9dbf02f7cdd51fa1ec306\", \"got_revision\": \"8067bb7dae6a3f44e6fbf5e889c0cad0e21c9cdc\", \"got_revision_cp\": \"refs/heads/master@{#798426}\", \"got_swarming_client_revision\": \"4c095d04179dc725a300085ae21fe3b79900d072\", \"got_swiftshader_revision\": \"abe07b9438553f741e776dedb59a2d4b502a9983\", \"got_v8_revision\": \"7ffb9c58d2c45acb088b604fa0a1938317c98957\", \"got_v8_revision_cp\": \"refs/heads/8.6.382@{#1}\", \"got_webrtc_revision\": \"383f2cfca4a1ebd9626134101fe70d5a716b2d92\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#31921}\", \"mastername\": \"chromium\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"8067bb7dae6a3f44e6fbf5e889c0cad0e21c9cdc\"}, \"swarming\": {\"bot_dimensions\": {\"builder\": [\"win-archive-dbg\", \"win-archive-rel\", \"win-official\", \"win32-archive-dbg\", \"win32-archive-rel\", \"win32-official\"], \"caches\": [\"builder_9b3ebe5fd1c5be87380c40d216747346a59b28cf26de183f0e8e2b32e944d3c8_v2\", \"git\", \"goma_v2\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"win-dbg-8-yk3i\"], \"image\": [\"chrome-win10-1703-20081200-b8de48ff3cf\"], \"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\": [\"5488-fc78d20\"], \"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": "1597470790927188",
"status": "COMPLETED",
"status_changed_ts": "1597480650841258",
"tags": [
"build_address:luci.chromium.ci/win-official/342",
"builder:win-official",
"buildset:commit/git/8067bb7dae6a3f44e6fbf5e889c0cad0e21c9cdc",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/8067bb7dae6a3f44e6fbf5e889c0cad0e21c9cdc",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9037354272287546352",
"scheduler_job_id:chromium/win-official",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8871924210173716688/+/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:4e097f00272ab310",
"user_agent:luci-scheduler"
],
"updated_ts": "1597480650910499",
"url": "https://ci.chromium.org/b/8871924210173716688",
"utcnow_ts": "1597480651518754"
}
}
[08:37:31.526] Invocation finished in 2h44m47.062980116s with status SUCCEEDED