[16:59:24.550] New invocation is queued and will start shortly
[16:59:26.011] Starting the invocation (attempt 1)
[16:59:26.050] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[16:59:26.053] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[16:59:26.053] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9022998008481770240",
"parameters_json": "{\"builder_name\":\"win-official\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"8d79f7cb8caa7060348ff5c7466f496ab8e83cea\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-official",
"scheduler_invocation_id:9022998008481770240",
"scheduler_job_id:chromium/win-official",
"user_agent:luci-scheduler",
"buildset:commit/git/8d79f7cb8caa7060348ff5c7466f496ab8e83cea",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/8d79f7cb8caa7060348ff5c7466f496ab8e83cea",
"gitiles_ref:refs/heads/master"
]
}
[16:59:27.032] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1611161966198874",
"id": "8857567946090001088",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"8d79f7cb8caa7060348ff5c7466f496ab8e83cea\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1611161966769227",
"tags": [
"build_address:luci.chromium.ci/win-official/1423",
"builder:win-official",
"buildset:commit/git/8d79f7cb8caa7060348ff5c7466f496ab8e83cea",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/8d79f7cb8caa7060348ff5c7466f496ab8e83cea",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022998008481770240",
"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/8857567946090001088/+/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": "1611161966769344",
"url": "https://ci.chromium.org/b/8857567946090001088",
"utcnow_ts": "1611161966995573"
}
}
[16:59:27.032] Task URL: https://ci.chromium.org/b/8857567946090001088
[16:59:27.032] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:2:0) after 1m0s
[17:00:27.165] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:2:0)
[17:00:27.165] Timer tick, asking Buildbucket for the build status
[17:00:27.272] Build 8857567946090001088: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:00:27.272] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:3:0) after 1m0s
[17:01:27.781] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:3:0)
[17:01:27.781] Timer tick, asking Buildbucket for the build status
[17:01:27.870] Build 8857567946090001088: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:01:27.870] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:4:0) after 1m0s
[17:02:28.163] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:4:0)
[17:02:28.163] Timer tick, asking Buildbucket for the build status
[17:02:28.233] Build 8857567946090001088: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:02:28.233] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:5:0) after 1m0s
[17:03:06.713] Received PubSub notification, asking Buildbucket for the build status
[17:03:07.216] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:03:28.279] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:5:0)
[17:03:28.279] Timer tick, asking Buildbucket for the build status
[17:03:29.417] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:03:29.417] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:7:0) after 1m0s
[17:04:29.837] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:7:0)
[17:04:29.837] Timer tick, asking Buildbucket for the build status
[17:04:30.215] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:04:30.215] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:8:0) after 1m0s
[17:05:30.465] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:8:0)
[17:05:30.465] Timer tick, asking Buildbucket for the build status
[17:05:30.549] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:05:30.549] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:9:0) after 1m0s
[17:06:30.623] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:9:0)
[17:06:30.623] Timer tick, asking Buildbucket for the build status
[17:06:30.694] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:06:30.694] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:10:0) after 1m0s
[17:07:30.732] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:10:0)
[17:07:30.732] Timer tick, asking Buildbucket for the build status
[17:07:30.906] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:07:30.907] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:11:0) after 1m0s
[17:08:31.022] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:11:0)
[17:08:31.022] Timer tick, asking Buildbucket for the build status
[17:08:31.119] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:08:31.119] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:12:0) after 1m0s
[17:09:31.146] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:12:0)
[17:09:31.146] Timer tick, asking Buildbucket for the build status
[17:09:31.266] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:09:31.266] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:13:0) after 1m0s
[17:10:31.342] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:13:0)
[17:10:31.342] Timer tick, asking Buildbucket for the build status
[17:10:31.451] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:10:31.451] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:14:0) after 1m0s
[17:11:31.485] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:14:0)
[17:11:31.485] Timer tick, asking Buildbucket for the build status
[17:11:31.574] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:11:31.574] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:15:0) after 1m0s
[17:12:31.673] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:15:0)
[17:12:31.673] Timer tick, asking Buildbucket for the build status
[17:12:31.856] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:12:31.856] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:16:0) after 1m0s
[17:13:31.891] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:16:0)
[17:13:31.891] Timer tick, asking Buildbucket for the build status
[17:13:31.973] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:13:31.974] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:17:0) after 1m0s
[17:14:31.990] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:17:0)
[17:14:31.990] Timer tick, asking Buildbucket for the build status
[17:14:32.309] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:14:32.309] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:18:0) after 1m0s
[17:15:32.382] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:18:0)
[17:15:32.382] Timer tick, asking Buildbucket for the build status
[17:15:32.464] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:15:32.464] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:19:0) after 1m0s
[17:16:32.475] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:19:0)
[17:16:32.475] Timer tick, asking Buildbucket for the build status
[17:16:32.846] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:16:32.846] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:20:0) after 1m0s
[17:17:32.873] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:20:0)
[17:17:32.873] Timer tick, asking Buildbucket for the build status
[17:17:33.066] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:17:33.066] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:21:0) after 1m0s
[17:18:33.116] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:21:0)
[17:18:33.116] Timer tick, asking Buildbucket for the build status
[17:18:33.242] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:18:33.242] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:22:0) after 1m0s
[17:19:33.380] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:22:0)
[17:19:33.380] Timer tick, asking Buildbucket for the build status
[17:19:33.662] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:19:33.662] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:23:0) after 1m0s
[17:20:33.958] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:23:0)
[17:20:33.958] Timer tick, asking Buildbucket for the build status
[17:20:34.169] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:20:34.169] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:24:0) after 1m0s
[17:21:34.322] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:24:0)
[17:21:34.322] Timer tick, asking Buildbucket for the build status
[17:21:34.447] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:21:34.447] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:25:0) after 1m0s
[17:22:34.585] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:25:0)
[17:22:34.585] Timer tick, asking Buildbucket for the build status
[17:22:34.660] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:22:34.660] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:26:0) after 1m0s
[17:23:34.799] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:26:0)
[17:23:34.799] Timer tick, asking Buildbucket for the build status
[17:23:34.946] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:23:34.946] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:27:0) after 1m0s
[17:24:34.983] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:27:0)
[17:24:34.983] Timer tick, asking Buildbucket for the build status
[17:24:35.341] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:24:35.341] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:28:0) after 1m0s
[17:25:35.561] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:28:0)
[17:25:35.561] Timer tick, asking Buildbucket for the build status
[17:25:35.725] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:25:35.725] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:29:0) after 1m0s
[17:26:35.785] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:29:0)
[17:26:35.785] Timer tick, asking Buildbucket for the build status
[17:26:35.883] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:26:35.883] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:30:0) after 1m0s
[17:27:35.991] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:30:0)
[17:27:35.991] Timer tick, asking Buildbucket for the build status
[17:27:36.195] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:27:36.195] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:31:0) after 1m0s
[17:28:36.274] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:31:0)
[17:28:36.274] Timer tick, asking Buildbucket for the build status
[17:28:36.761] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:28:36.761] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:32:0) after 1m0s
[17:29:36.783] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:32:0)
[17:29:36.783] Timer tick, asking Buildbucket for the build status
[17:29:37.348] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:29:37.348] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:33:0) after 1m0s
[17:30:37.430] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:33:0)
[17:30:37.430] Timer tick, asking Buildbucket for the build status
[17:30:37.663] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:30:37.663] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:34:0) after 1m0s
[17:31:37.720] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:34:0)
[17:31:37.720] Timer tick, asking Buildbucket for the build status
[17:31:37.798] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:31:37.798] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:35:0) after 1m0s
[17:32:37.883] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:35:0)
[17:32:37.883] Timer tick, asking Buildbucket for the build status
[17:32:37.957] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:32:37.957] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:36:0) after 1m0s
[17:33:38.000] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:36:0)
[17:33:38.000] Timer tick, asking Buildbucket for the build status
[17:33:38.059] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:33:38.059] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:37:0) after 1m0s
[17:34:38.103] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:37:0)
[17:34:38.103] Timer tick, asking Buildbucket for the build status
[17:34:38.350] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:34:38.350] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:38:0) after 1m0s
[17:35:38.396] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:38:0)
[17:35:38.396] Timer tick, asking Buildbucket for the build status
[17:35:38.722] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:35:38.722] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:39:0) after 1m0s
[17:36:38.744] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:39:0)
[17:36:38.744] Timer tick, asking Buildbucket for the build status
[17:36:38.848] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:36:38.848] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:40:0) after 1m0s
[17:37:39.687] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:40:0)
[17:37:39.687] Timer tick, asking Buildbucket for the build status
[17:37:40.119] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:37:40.119] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:41:0) after 1m0s
[17:38:40.241] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:41:0)
[17:38:40.241] Timer tick, asking Buildbucket for the build status
[17:38:40.319] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:38:40.319] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:42:0) after 1m0s
[17:39:41.182] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:42:0)
[17:39:41.182] Timer tick, asking Buildbucket for the build status
[17:39:41.350] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:39:41.350] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:43:0) after 1m0s
[17:40:41.584] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:43:0)
[17:40:41.584] Timer tick, asking Buildbucket for the build status
[17:40:41.647] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:40:41.647] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:44:0) after 1m0s
[17:41:41.864] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:44:0)
[17:41:41.864] Timer tick, asking Buildbucket for the build status
[17:41:41.920] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:41:41.920] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:45:0) after 1m0s
[17:42:42.106] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:45:0)
[17:42:42.106] Timer tick, asking Buildbucket for the build status
[17:42:42.172] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:42:42.172] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:46:0) after 1m0s
[17:43:42.295] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:46:0)
[17:43:42.295] Timer tick, asking Buildbucket for the build status
[17:43:42.763] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:43:42.763] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:47:0) after 1m0s
[17:44:42.871] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:47:0)
[17:44:42.871] Timer tick, asking Buildbucket for the build status
[17:44:42.976] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:44:42.976] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:48:0) after 1m0s
[17:45:43.151] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:48:0)
[17:45:43.151] Timer tick, asking Buildbucket for the build status
[17:45:43.218] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:45:43.218] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:49:0) after 1m0s
[17:46:43.391] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:49:0)
[17:46:43.391] Timer tick, asking Buildbucket for the build status
[17:46:43.484] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:46:43.484] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:50:0) after 1m0s
[17:47:43.643] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:50:0)
[17:47:43.643] Timer tick, asking Buildbucket for the build status
[17:47:43.739] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:47:43.739] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:51:0) after 1m0s
[17:48:43.814] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:51:0)
[17:48:43.814] Timer tick, asking Buildbucket for the build status
[17:48:43.935] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:48:43.935] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:52:0) after 1m0s
[17:49:44.018] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:52:0)
[17:49:44.018] Timer tick, asking Buildbucket for the build status
[17:49:44.192] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:49:44.192] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:53:0) after 1m0s
[17:50:44.261] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:53:0)
[17:50:44.261] Timer tick, asking Buildbucket for the build status
[17:50:44.525] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:50:44.525] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:54:0) after 1m0s
[17:51:44.665] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:54:0)
[17:51:44.665] Timer tick, asking Buildbucket for the build status
[17:51:44.738] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:51:44.738] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:55:0) after 1m0s
[17:52:44.762] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:55:0)
[17:52:44.762] Timer tick, asking Buildbucket for the build status
[17:52:46.607] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:52:46.607] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:56:0) after 1m0s
[17:53:46.674] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:56:0)
[17:53:46.674] Timer tick, asking Buildbucket for the build status
[17:53:46.841] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:53:46.841] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:57:0) after 1m0s
[17:54:46.838] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:57:0)
[17:54:46.838] Timer tick, asking Buildbucket for the build status
[17:54:47.119] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:54:47.119] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:58:0) after 1m0s
[17:55:47.151] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:58:0)
[17:55:47.151] Timer tick, asking Buildbucket for the build status
[17:55:47.486] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:55:47.486] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:59:0) after 1m0s
[17:56:47.543] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:59:0)
[17:56:47.543] Timer tick, asking Buildbucket for the build status
[17:56:47.640] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:56:47.640] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:60:0) after 1m0s
[17:57:47.699] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:60:0)
[17:57:47.699] Timer tick, asking Buildbucket for the build status
[17:57:47.826] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:57:47.826] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:61:0) after 1m0s
[17:58:47.857] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:61:0)
[17:58:47.857] Timer tick, asking Buildbucket for the build status
[17:58:47.913] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:58:47.913] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:62:0) after 1m0s
[17:59:47.981] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:62:0)
[17:59:47.981] Timer tick, asking Buildbucket for the build status
[17:59:48.652] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:59:48.652] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:63:0) after 1m0s
[18:00:48.699] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:63:0)
[18:00:48.699] Timer tick, asking Buildbucket for the build status
[18:00:48.816] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:00:48.816] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:64:0) after 1m0s
[18:01:48.833] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:64:0)
[18:01:48.833] Timer tick, asking Buildbucket for the build status
[18:01:48.902] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:01:48.902] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:65:0) after 1m0s
[18:02:48.955] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:65:0)
[18:02:48.955] Timer tick, asking Buildbucket for the build status
[18:02:49.038] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:02:49.038] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:66:0) after 1m0s
[18:03:49.098] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:66:0)
[18:03:49.098] Timer tick, asking Buildbucket for the build status
[18:03:49.196] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:03:49.196] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:67:0) after 1m0s
[18:04:49.296] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:67:0)
[18:04:49.296] Timer tick, asking Buildbucket for the build status
[18:04:49.925] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:04:49.925] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:68:0) after 1m0s
[18:05:49.983] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:68:0)
[18:05:49.983] Timer tick, asking Buildbucket for the build status
[18:05:50.045] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:05:50.045] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:69:0) after 1m0s
[18:06:50.069] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:69:0)
[18:06:50.069] Timer tick, asking Buildbucket for the build status
[18:06:50.319] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:06:50.319] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:70:0) after 1m0s
[18:07:50.327] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:70:0)
[18:07:50.327] Timer tick, asking Buildbucket for the build status
[18:07:50.397] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:07:50.397] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:71:0) after 1m0s
[18:08:50.467] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:71:0)
[18:08:50.467] Timer tick, asking Buildbucket for the build status
[18:08:50.557] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:08:50.557] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:72:0) after 1m0s
[18:09:50.663] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:72:0)
[18:09:50.663] Timer tick, asking Buildbucket for the build status
[18:09:50.775] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:09:50.775] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:73:0) after 1m0s
[18:10:50.832] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:73:0)
[18:10:50.832] Timer tick, asking Buildbucket for the build status
[18:10:51.110] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:10:51.110] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:74:0) after 1m0s
[18:11:51.114] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:74:0)
[18:11:51.114] Timer tick, asking Buildbucket for the build status
[18:11:51.186] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:11:51.186] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:75:0) after 1m0s
[18:12:51.236] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:75:0)
[18:12:51.236] Timer tick, asking Buildbucket for the build status
[18:12:51.313] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:12:51.313] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:76:0) after 1m0s
[18:13:51.484] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:76:0)
[18:13:51.484] Timer tick, asking Buildbucket for the build status
[18:13:51.555] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:13:51.555] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:77:0) after 1m0s
[18:14:51.586] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:77:0)
[18:14:51.586] Timer tick, asking Buildbucket for the build status
[18:14:51.730] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:14:51.730] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:78:0) after 1m0s
[18:15:51.767] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:78:0)
[18:15:51.767] Timer tick, asking Buildbucket for the build status
[18:15:51.831] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:15:51.832] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:79:0) after 1m0s
[18:16:51.889] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:79:0)
[18:16:51.889] Timer tick, asking Buildbucket for the build status
[18:16:52.350] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:16:52.351] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:80:0) after 1m0s
[18:17:52.447] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:80:0)
[18:17:52.447] Timer tick, asking Buildbucket for the build status
[18:17:52.532] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:17:52.532] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:81:0) after 1m0s
[18:18:52.565] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:81:0)
[18:18:52.565] Timer tick, asking Buildbucket for the build status
[18:18:52.634] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:18:52.634] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:82:0) after 1m0s
[18:19:52.673] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:82:0)
[18:19:52.673] Timer tick, asking Buildbucket for the build status
[18:19:52.840] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:19:52.840] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:83:0) after 1m0s
[18:20:52.937] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:83:0)
[18:20:52.937] Timer tick, asking Buildbucket for the build status
[18:20:53.094] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:20:53.094] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:84:0) after 1m0s
[18:21:53.254] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:84:0)
[18:21:53.254] Timer tick, asking Buildbucket for the build status
[18:21:53.511] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:21:53.511] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:85:0) after 1m0s
[18:22:53.569] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:85:0)
[18:22:53.569] Timer tick, asking Buildbucket for the build status
[18:22:53.657] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:22:53.657] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:86:0) after 1m0s
[18:23:53.720] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:86:0)
[18:23:53.720] Timer tick, asking Buildbucket for the build status
[18:23:54.064] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:23:54.064] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:87:0) after 1m0s
[18:24:54.150] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:87:0)
[18:24:54.150] Timer tick, asking Buildbucket for the build status
[18:24:54.493] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:24:54.493] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:88:0) after 1m0s
[18:25:54.551] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:88:0)
[18:25:54.551] Timer tick, asking Buildbucket for the build status
[18:25:54.611] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:25:54.611] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:89:0) after 1m0s
[18:26:54.667] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:89:0)
[18:26:54.667] Timer tick, asking Buildbucket for the build status
[18:26:54.775] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:26:54.775] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:90:0) after 1m0s
[18:27:54.811] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:90:0)
[18:27:54.811] Timer tick, asking Buildbucket for the build status
[18:27:55.205] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:27:55.205] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:91:0) after 1m0s
[18:28:55.267] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:91:0)
[18:28:55.267] Timer tick, asking Buildbucket for the build status
[18:28:55.360] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:28:55.360] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:92:0) after 1m0s
[18:29:55.483] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:92:0)
[18:29:55.483] Timer tick, asking Buildbucket for the build status
[18:29:55.580] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:29:55.580] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:93:0) after 1m0s
[18:30:55.636] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:93:0)
[18:30:55.636] Timer tick, asking Buildbucket for the build status
[18:30:55.700] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:30:55.700] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:94:0) after 1m0s
[18:31:55.818] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:94:0)
[18:31:55.818] Timer tick, asking Buildbucket for the build status
[18:31:55.918] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:31:55.918] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:95:0) after 1m0s
[18:32:55.971] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:95:0)
[18:32:55.971] Timer tick, asking Buildbucket for the build status
[18:32:56.033] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:32:56.033] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:96:0) after 1m0s
[18:33:56.120] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:96:0)
[18:33:56.120] Timer tick, asking Buildbucket for the build status
[18:33:56.326] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:33:56.326] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:97:0) after 1m0s
[18:34:56.347] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:97:0)
[18:34:56.347] Timer tick, asking Buildbucket for the build status
[18:34:56.807] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:34:56.807] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:98:0) after 1m0s
[18:35:56.879] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:98:0)
[18:35:56.879] Timer tick, asking Buildbucket for the build status
[18:35:56.950] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:35:56.950] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:99:0) after 1m0s
[18:36:57.096] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:99:0)
[18:36:57.096] Timer tick, asking Buildbucket for the build status
[18:36:57.359] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:36:57.359] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:100:0) after 1m0s
[18:37:57.528] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:100:0)
[18:37:57.528] Timer tick, asking Buildbucket for the build status
[18:37:57.701] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:37:57.701] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:101:0) after 1m0s
[18:38:57.855] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:101:0)
[18:38:57.855] Timer tick, asking Buildbucket for the build status
[18:38:58.170] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:38:58.170] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:102:0) after 1m0s
[18:39:58.246] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:102:0)
[18:39:58.246] Timer tick, asking Buildbucket for the build status
[18:39:58.393] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:39:58.394] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:103:0) after 1m0s
[18:40:58.436] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:103:0)
[18:40:58.436] Timer tick, asking Buildbucket for the build status
[18:40:58.507] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:40:58.507] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:104:0) after 1m0s
[18:41:58.621] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:104:0)
[18:41:58.621] Timer tick, asking Buildbucket for the build status
[18:41:58.707] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:41:58.707] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:105:0) after 1m0s
[18:42:58.759] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:105:0)
[18:42:58.759] Timer tick, asking Buildbucket for the build status
[18:42:58.817] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:42:58.817] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:106:0) after 1m0s
[18:43:58.864] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:106:0)
[18:43:58.865] Timer tick, asking Buildbucket for the build status
[18:43:58.944] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:43:58.944] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:107:0) after 1m0s
[18:44:58.931] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:107:0)
[18:44:58.931] Timer tick, asking Buildbucket for the build status
[18:44:59.071] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:44:59.071] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:108:0) after 1m0s
[18:45:59.171] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:108:0)
[18:45:59.171] Timer tick, asking Buildbucket for the build status
[18:45:59.246] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:45:59.246] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:109:0) after 1m0s
[18:46:59.282] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:109:0)
[18:46:59.282] Timer tick, asking Buildbucket for the build status
[18:46:59.536] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:46:59.536] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:110:0) after 1m0s
[18:47:59.605] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:110:0)
[18:47:59.605] Timer tick, asking Buildbucket for the build status
[18:47:59.666] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:47:59.666] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:111:0) after 1m0s
[18:48:59.793] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:111:0)
[18:48:59.793] Timer tick, asking Buildbucket for the build status
[18:49:00.065] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:49:00.065] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:112:0) after 1m0s
[18:50:00.107] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:112:0)
[18:50:00.107] Timer tick, asking Buildbucket for the build status
[18:50:00.237] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:50:00.237] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:113:0) after 1m0s
[18:51:00.587] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:113:0)
[18:51:00.587] Timer tick, asking Buildbucket for the build status
[18:51:00.663] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:51:00.663] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:114:0) after 1m0s
[18:52:00.690] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:114:0)
[18:52:00.690] Timer tick, asking Buildbucket for the build status
[18:52:00.767] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:52:00.767] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:115:0) after 1m0s
[18:53:00.804] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:115:0)
[18:53:00.804] Timer tick, asking Buildbucket for the build status
[18:53:00.903] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:53:00.903] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:116:0) after 1m0s
[18:54:00.971] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:116:0)
[18:54:00.971] Timer tick, asking Buildbucket for the build status
[18:54:01.142] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:54:01.142] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:117:0) after 1m0s
[18:55:01.152] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:117:0)
[18:55:01.152] Timer tick, asking Buildbucket for the build status
[18:55:01.511] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:55:01.511] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:118:0) after 1m0s
[18:56:01.573] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:118:0)
[18:56:01.573] Timer tick, asking Buildbucket for the build status
[18:56:01.700] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:56:01.700] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:119:0) after 1m0s
[18:57:01.784] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:119:0)
[18:57:01.784] Timer tick, asking Buildbucket for the build status
[18:57:01.983] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:57:01.983] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:120:0) after 1m0s
[18:58:02.008] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:120:0)
[18:58:02.008] Timer tick, asking Buildbucket for the build status
[18:58:02.471] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:58:02.471] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:121:0) after 1m0s
[18:59:02.673] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:121:0)
[18:59:02.673] Timer tick, asking Buildbucket for the build status
[18:59:02.855] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:59:02.855] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:122:0) after 1m0s
[19:00:03.031] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:122:0)
[19:00:03.031] Timer tick, asking Buildbucket for the build status
[19:00:03.306] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:00:03.307] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:123:0) after 1m0s
[19:01:03.291] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:123:0)
[19:01:03.291] Timer tick, asking Buildbucket for the build status
[19:01:03.355] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:01:03.355] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:124:0) after 1m0s
[19:02:03.452] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:124:0)
[19:02:03.452] Timer tick, asking Buildbucket for the build status
[19:02:03.774] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:02:03.774] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:125:0) after 1m0s
[19:03:03.801] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:125:0)
[19:03:03.801] Timer tick, asking Buildbucket for the build status
[19:03:04.013] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:03:04.013] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:126:0) after 1m0s
[19:04:04.077] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:126:0)
[19:04:04.077] Timer tick, asking Buildbucket for the build status
[19:04:04.154] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:04:04.154] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:127:0) after 1m0s
[19:05:04.207] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:127:0)
[19:05:04.207] Timer tick, asking Buildbucket for the build status
[19:05:05.024] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:05:05.024] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:128:0) after 1m0s
[19:06:05.104] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:128:0)
[19:06:05.104] Timer tick, asking Buildbucket for the build status
[19:06:05.431] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:06:05.432] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:129:0) after 1m0s
[19:07:05.450] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:129:0)
[19:07:05.450] Timer tick, asking Buildbucket for the build status
[19:07:06.185] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:07:06.185] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:130:0) after 1m0s
[19:08:06.247] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:130:0)
[19:08:06.247] Timer tick, asking Buildbucket for the build status
[19:08:06.361] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:08:06.361] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:131:0) after 1m0s
[19:09:06.389] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:131:0)
[19:09:06.389] Timer tick, asking Buildbucket for the build status
[19:09:06.611] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:09:06.611] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:132:0) after 1m0s
[19:10:06.618] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:132:0)
[19:10:06.618] Timer tick, asking Buildbucket for the build status
[19:10:07.060] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:10:07.060] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:133:0) after 1m0s
[19:11:07.125] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:133:0)
[19:11:07.125] Timer tick, asking Buildbucket for the build status
[19:11:07.195] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:11:07.195] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:134:0) after 1m0s
[19:12:07.281] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:134:0)
[19:12:07.281] Timer tick, asking Buildbucket for the build status
[19:12:07.926] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:12:07.926] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:135:0) after 1m0s
[19:13:07.960] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:135:0)
[19:13:07.960] Timer tick, asking Buildbucket for the build status
[19:13:08.043] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:13:08.043] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:136:0) after 1m0s
[19:14:08.182] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:136:0)
[19:14:08.182] Timer tick, asking Buildbucket for the build status
[19:14:08.255] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:14:08.255] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:137:0) after 1m0s
[19:15:08.442] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:137:0)
[19:15:08.442] Timer tick, asking Buildbucket for the build status
[19:15:08.719] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:15:08.719] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:138:0) after 1m0s
[19:16:09.006] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:138:0)
[19:16:09.006] Timer tick, asking Buildbucket for the build status
[19:16:09.098] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:16:09.098] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:139:0) after 1m0s
[19:17:09.300] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:139:0)
[19:17:09.300] Timer tick, asking Buildbucket for the build status
[19:17:09.376] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:17:09.376] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:140:0) after 1m0s
[19:18:09.387] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:140:0)
[19:18:09.387] Timer tick, asking Buildbucket for the build status
[19:18:09.496] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:18:09.496] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:141:0) after 1m0s
[19:19:09.637] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:141:0)
[19:19:09.637] Timer tick, asking Buildbucket for the build status
[19:19:09.799] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:19:09.799] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:142:0) after 1m0s
[19:20:09.825] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:142:0)
[19:20:09.825] Timer tick, asking Buildbucket for the build status
[19:20:10.008] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:20:10.008] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:143:0) after 1m0s
[19:21:10.018] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:143:0)
[19:21:10.018] Timer tick, asking Buildbucket for the build status
[19:21:10.099] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:21:10.099] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:144:0) after 1m0s
[19:22:10.193] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:144:0)
[19:22:10.193] Timer tick, asking Buildbucket for the build status
[19:22:10.337] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:22:10.337] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:145:0) after 1m0s
[19:23:10.340] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:145:0)
[19:23:10.340] Timer tick, asking Buildbucket for the build status
[19:23:10.416] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:23:10.416] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:146:0) after 1m0s
[19:24:10.442] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:146:0)
[19:24:10.442] Timer tick, asking Buildbucket for the build status
[19:24:10.533] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:24:10.533] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:147:0) after 1m0s
[19:25:10.595] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:147:0)
[19:25:10.595] Timer tick, asking Buildbucket for the build status
[19:25:10.983] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:25:10.983] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:148:0) after 1m0s
[19:26:11.041] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:148:0)
[19:26:11.041] Timer tick, asking Buildbucket for the build status
[19:26:11.133] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:26:11.133] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:149:0) after 1m0s
[19:27:11.139] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:149:0)
[19:27:11.139] Timer tick, asking Buildbucket for the build status
[19:27:11.217] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:27:11.217] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:150:0) after 1m0s
[19:28:11.371] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:150:0)
[19:28:11.371] Timer tick, asking Buildbucket for the build status
[19:28:11.666] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:28:11.666] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:151:0) after 1m0s
[19:29:11.766] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:151:0)
[19:29:11.766] Timer tick, asking Buildbucket for the build status
[19:29:12.281] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:29:12.281] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:152:0) after 1m0s
[19:30:12.346] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:152:0)
[19:30:12.346] Timer tick, asking Buildbucket for the build status
[19:30:12.723] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:30:12.724] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:153:0) after 1m0s
[19:31:12.886] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:153:0)
[19:31:12.886] Timer tick, asking Buildbucket for the build status
[19:31:13.023] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:31:13.023] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:154:0) after 1m0s
[19:32:13.076] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:154:0)
[19:32:13.076] Timer tick, asking Buildbucket for the build status
[19:32:13.456] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:32:13.456] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:155:0) after 1m0s
[19:33:13.534] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:155:0)
[19:33:13.534] Timer tick, asking Buildbucket for the build status
[19:33:13.599] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:33:13.599] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:156:0) after 1m0s
[19:34:13.696] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:156:0)
[19:34:13.696] Timer tick, asking Buildbucket for the build status
[19:34:13.890] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:34:13.890] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:157:0) after 1m0s
[19:35:13.975] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:157:0)
[19:35:13.975] Timer tick, asking Buildbucket for the build status
[19:35:14.092] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:35:14.092] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:158:0) after 1m0s
[19:36:14.144] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:158:0)
[19:36:14.144] Timer tick, asking Buildbucket for the build status
[19:36:14.265] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:36:14.265] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:159:0) after 1m0s
[19:37:14.299] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:159:0)
[19:37:14.299] Timer tick, asking Buildbucket for the build status
[19:37:14.498] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:37:14.498] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:160:0) after 1m0s
[19:38:14.572] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:160:0)
[19:38:14.572] Timer tick, asking Buildbucket for the build status
[19:38:14.684] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:38:14.684] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:161:0) after 1m0s
[19:39:14.729] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:161:0)
[19:39:14.729] Timer tick, asking Buildbucket for the build status
[19:39:15.971] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:39:15.971] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:162:0) after 1m0s
[19:40:16.097] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:162:0)
[19:40:16.097] Timer tick, asking Buildbucket for the build status
[19:40:16.219] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:40:16.219] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:163:0) after 1m0s
[19:41:16.369] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:163:0)
[19:41:16.369] Timer tick, asking Buildbucket for the build status
[19:41:16.471] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:41:16.471] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:164:0) after 1m0s
[19:42:16.549] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:164:0)
[19:42:16.549] Timer tick, asking Buildbucket for the build status
[19:42:16.677] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:42:16.677] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:165:0) after 1m0s
[19:43:16.742] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:165:0)
[19:43:16.742] Timer tick, asking Buildbucket for the build status
[19:43:16.926] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:43:16.926] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:166:0) after 1m0s
[19:44:17.035] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:166:0)
[19:44:17.035] Timer tick, asking Buildbucket for the build status
[19:44:17.182] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:44:17.182] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:167:0) after 1m0s
[19:45:17.275] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:167:0)
[19:45:17.275] Timer tick, asking Buildbucket for the build status
[19:45:17.427] Build 8857567946090001088: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:45:17.427] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022998008481770240:168:0) after 1m0s
[19:45:47.173] Received PubSub notification, asking Buildbucket for the build status
[19:45:47.252] Build 8857567946090001088: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[19:45:47.252] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1611171946536302",
"created_by": "project:chromium",
"created_ts": "1611161966198874",
"id": "8857567946090001088",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"8d79f7cb8caa7060348ff5c7466f496ab8e83cea\"}}",
"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\": \"8d79f7cb8caa7060348ff5c7466f496ab8e83cea\", \"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-8-fqoc\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium\", \"buildername\": \"win-official\", \"buildnumber\": 1423, \"got_angle_revision\": \"186fe99034047745c4a626935b3ac2cce557f8f3\", \"got_dawn_revision\": \"4b316994ad01ab6b87032c5fd9b53c6d18956d8d\", \"got_nacl_revision\": \"cb31feffa500401c95c6c44d88b7c358236bca36\", \"got_revision\": \"8d79f7cb8caa7060348ff5c7466f496ab8e83cea\", \"got_revision_cp\": \"refs/heads/master@{#845179}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"22be66d4071284ffeeef424adc068e9e400835ab\", \"got_v8_revision\": \"710f03fa2d60f0b6471efefb246e2b3b6595cfcd\", \"got_v8_revision_cp\": \"refs/heads/9.0.21@{#1}\", \"got_webrtc_revision\": \"6dcbcead3da45604b84aef9d9496689fef5ff8f5\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33041}\", \"mirrored_builders\": [\"tryserver.chromium:win-official\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"8d79f7cb8caa7060348ff5c7466f496ab8e83cea\"}, \"swarming\": {\"bot_dimensions\": {\"builder\": [\"win-archive-dbg\", \"win-archive-rel\", \"win-official\", \"win32-archive-dbg\", \"win32-archive-rel\", \"win32-official\"], \"caches\": [\"builder_9b3ebe5fd1c5be87380c40d216747346a59b28cf26de183f0e8e2b32e944d3c8_v2\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"win-dbg-8-fqoc\"], \"image\": [\"chrome-win10-1703-20111600-a937a23cefb\"], \"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\": [\"5666-679c9bd\"], \"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": "1611162185132916",
"status": "COMPLETED",
"status_changed_ts": "1611171946904141",
"tags": [
"build_address:luci.chromium.ci/win-official/1423",
"builder:win-official",
"buildset:commit/git/8d79f7cb8caa7060348ff5c7466f496ab8e83cea",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/8d79f7cb8caa7060348ff5c7466f496ab8e83cea",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022998008481770240",
"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/8857567946090001088/+/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:51398e21ffbf0010",
"user_agent:luci-scheduler"
],
"updated_ts": "1611171946909317",
"url": "https://ci.chromium.org/b/8857567946090001088",
"utcnow_ts": "1611171947241838"
}
}
[19:45:47.252] Invocation finished in 2h46m22.717850188s with status SUCCEEDED