[20:08:14.997] New invocation is queued and will start shortly
[20:08:16.142] Starting the invocation (attempt 1)
[20:08:16.213] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[20:08:16.213] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[20:08:16.214] Buildbucket request:
{
"bucket": "luci.emscripten-releases.ci",
"client_operation_id": "9079156246826083952",
"parameters_json": "{\"builder_name\":\"win\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/emscripten-releases\",\"revision\":\"1a230712c1a11aa523cc8e078abbc2277c6a8ece\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win",
"scheduler_invocation_id:9079156246826083952",
"scheduler_job_id:emscripten-releases/win",
"user_agent:luci-scheduler",
"buildset:commit/git/1a230712c1a11aa523cc8e078abbc2277c6a8ece",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/1a230712c1a11aa523cc8e078abbc2277c6a8ece",
"gitiles_ref:refs/heads/master"
]
}
[20:08:17.170] Buildbucket response:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary_preference": "AUTO",
"created_by": "project:emscripten-releases",
"created_ts": "1557605296353689",
"id": "8913726184729535312",
"parameters_json": "{\"builder_name\": \"win\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"1a230712c1a11aa523cc8e078abbc2277c6a8ece\"}}",
"project": "emscripten-releases",
"result_details_json": "{\"properties\": {}}",
"service_account": "emscripten-releases-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1557605296803624",
"tags": [
"builder:win",
"buildset:commit/git/1a230712c1a11aa523cc8e078abbc2277c6a8ece",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/1a230712c1a11aa523cc8e078abbc2277c6a8ece",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9079156246826083952",
"scheduler_job_id:emscripten-releases/win",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/emscripten-releases/buildbucket/cr-buildbucket.appspot.com/8913726184729535312/+/annotations",
"swarming_tag:luci_project:emscripten-releases",
"swarming_tag:recipe_name:emscripten_releases",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:",
"user_agent:luci-scheduler"
],
"updated_ts": "1557605296803765",
"url": "https://ci.chromium.org/b/8913726184729535312",
"utcnow_ts": "1557605297157174"
}
}
[20:08:17.170] Task URL: https://ci.chromium.org/b/8913726184729535312
[20:08:17.170] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:2:0) after 1m0s
[20:09:17.191] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:2:0)
[20:09:17.191] Timer tick, asking Buildbucket for the build status
[20:09:17.297] Build 8913726184729535312: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:09:17.297] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:3:0) after 1m0s
[20:10:17.322] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:3:0)
[20:10:17.322] Timer tick, asking Buildbucket for the build status
[20:10:17.558] Build 8913726184729535312: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:10:17.558] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:4:0) after 1m0s
[20:11:09.739] Received PubSub notification, asking Buildbucket for the build status
[20:11:09.801] Build 8913726184729535312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:11:17.586] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:4:0)
[20:11:17.586] Timer tick, asking Buildbucket for the build status
[20:11:17.828] Build 8913726184729535312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:11:17.828] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:6:0) after 1m0s
[20:12:17.826] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:6:0)
[20:12:17.826] Timer tick, asking Buildbucket for the build status
[20:12:17.914] Build 8913726184729535312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:12:17.914] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:7:0) after 1m0s
[20:13:17.896] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:7:0)
[20:13:17.896] Timer tick, asking Buildbucket for the build status
[20:13:17.983] Build 8913726184729535312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:13:17.983] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:8:0) after 1m0s
[20:14:17.984] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:8:0)
[20:14:17.984] Timer tick, asking Buildbucket for the build status
[20:14:18.245] Build 8913726184729535312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:14:18.245] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:9:0) after 1m0s
[20:15:18.269] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:9:0)
[20:15:18.269] Timer tick, asking Buildbucket for the build status
[20:15:18.417] Build 8913726184729535312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:15:18.417] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:10:0) after 1m0s
[20:16:18.431] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:10:0)
[20:16:18.431] Timer tick, asking Buildbucket for the build status
[20:16:18.506] Build 8913726184729535312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:16:18.506] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:11:0) after 1m0s
[20:17:18.528] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:11:0)
[20:17:18.528] Timer tick, asking Buildbucket for the build status
[20:17:18.573] Build 8913726184729535312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:17:18.573] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:12:0) after 1m0s
[20:18:18.611] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:12:0)
[20:18:18.611] Timer tick, asking Buildbucket for the build status
[20:18:18.653] Build 8913726184729535312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:18:18.653] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:13:0) after 1m0s
[20:19:18.680] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:13:0)
[20:19:18.680] Timer tick, asking Buildbucket for the build status
[20:19:18.800] Build 8913726184729535312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:19:18.800] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:14:0) after 1m0s
[20:20:18.835] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:14:0)
[20:20:18.835] Timer tick, asking Buildbucket for the build status
[20:20:18.987] Build 8913726184729535312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:20:18.988] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:15:0) after 1m0s
[20:21:18.989] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:15:0)
[20:21:18.989] Timer tick, asking Buildbucket for the build status
[20:21:19.208] Build 8913726184729535312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:21:19.208] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:16:0) after 1m0s
[20:22:19.228] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:16:0)
[20:22:19.228] Timer tick, asking Buildbucket for the build status
[20:22:20.325] Build 8913726184729535312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:22:20.325] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:17:0) after 1m0s
[20:23:20.343] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:17:0)
[20:23:20.343] Timer tick, asking Buildbucket for the build status
[20:23:20.608] Build 8913726184729535312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:23:20.608] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:18:0) after 1m0s
[20:24:20.634] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:18:0)
[20:24:20.634] Timer tick, asking Buildbucket for the build status
[20:24:20.774] Build 8913726184729535312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:24:20.774] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:19:0) after 1m0s
[20:25:20.838] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:19:0)
[20:25:20.838] Timer tick, asking Buildbucket for the build status
[20:25:20.901] Build 8913726184729535312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:25:20.901] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:20:0) after 1m0s
[20:26:20.938] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:20:0)
[20:26:20.938] Timer tick, asking Buildbucket for the build status
[20:26:21.975] Build 8913726184729535312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:26:21.975] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:21:0) after 1m0s
[20:27:22.026] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:21:0)
[20:27:22.026] Timer tick, asking Buildbucket for the build status
[20:27:22.245] Build 8913726184729535312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:27:22.245] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:22:0) after 1m0s
[20:28:22.286] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:22:0)
[20:28:22.286] Timer tick, asking Buildbucket for the build status
[20:28:22.385] Build 8913726184729535312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:28:22.385] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:23:0) after 1m0s
[20:29:22.404] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:23:0)
[20:29:22.404] Timer tick, asking Buildbucket for the build status
[20:29:22.891] Build 8913726184729535312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:29:22.891] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:24:0) after 1m0s
[20:30:22.875] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:24:0)
[20:30:22.875] Timer tick, asking Buildbucket for the build status
[20:30:23.000] Build 8913726184729535312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:30:23.000] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:25:0) after 1m0s
[20:31:22.981] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:25:0)
[20:31:22.981] Timer tick, asking Buildbucket for the build status
[20:31:23.149] Build 8913726184729535312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:31:23.149] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:26:0) after 1m0s
[20:32:23.235] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:26:0)
[20:32:23.235] Timer tick, asking Buildbucket for the build status
[20:32:23.394] Build 8913726184729535312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:32:23.394] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:27:0) after 1m0s
[20:33:23.445] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:27:0)
[20:33:23.445] Timer tick, asking Buildbucket for the build status
[20:33:24.255] Build 8913726184729535312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:33:24.255] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:28:0) after 1m0s
[20:34:24.306] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:28:0)
[20:34:24.306] Timer tick, asking Buildbucket for the build status
[20:34:24.392] Build 8913726184729535312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:34:24.392] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:29:0) after 1m0s
[20:35:24.427] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:29:0)
[20:35:24.427] Timer tick, asking Buildbucket for the build status
[20:35:24.871] Build 8913726184729535312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:35:24.871] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:30:0) after 1m0s
[20:36:24.887] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:30:0)
[20:36:24.887] Timer tick, asking Buildbucket for the build status
[20:36:24.934] Build 8913726184729535312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:36:24.934] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:31:0) after 1m0s
[20:37:24.967] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:31:0)
[20:37:24.967] Timer tick, asking Buildbucket for the build status
[20:37:25.155] Build 8913726184729535312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:37:25.155] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:32:0) after 1m0s
[20:38:25.212] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:32:0)
[20:38:25.212] Timer tick, asking Buildbucket for the build status
[20:38:25.490] Build 8913726184729535312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:38:25.490] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:33:0) after 1m0s
[20:39:25.512] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:33:0)
[20:39:25.512] Timer tick, asking Buildbucket for the build status
[20:39:25.551] Build 8913726184729535312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:39:25.551] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:34:0) after 1m0s
[20:40:25.568] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:34:0)
[20:40:25.568] Timer tick, asking Buildbucket for the build status
[20:40:25.841] Build 8913726184729535312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:40:25.841] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:35:0) after 1m0s
[20:41:25.864] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:35:0)
[20:41:25.864] Timer tick, asking Buildbucket for the build status
[20:41:25.945] Build 8913726184729535312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:41:25.945] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:36:0) after 1m0s
[20:42:25.969] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:36:0)
[20:42:25.969] Timer tick, asking Buildbucket for the build status
[20:42:26.102] Build 8913726184729535312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:42:26.102] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:37:0) after 1m0s
[20:43:26.129] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:37:0)
[20:43:26.129] Timer tick, asking Buildbucket for the build status
[20:43:26.362] Build 8913726184729535312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:43:26.362] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:38:0) after 1m0s
[20:44:26.415] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:38:0)
[20:44:26.415] Timer tick, asking Buildbucket for the build status
[20:44:26.469] Build 8913726184729535312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:44:26.469] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:39:0) after 1m0s
[20:45:26.488] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:39:0)
[20:45:26.488] Timer tick, asking Buildbucket for the build status
[20:45:26.601] Build 8913726184729535312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:45:26.601] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:40:0) after 1m0s
[20:46:26.602] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:40:0)
[20:46:26.602] Timer tick, asking Buildbucket for the build status
[20:46:26.643] Build 8913726184729535312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:46:26.643] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:41:0) after 1m0s
[20:47:26.670] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:41:0)
[20:47:26.670] Timer tick, asking Buildbucket for the build status
[20:47:26.760] Build 8913726184729535312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:47:26.760] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:42:0) after 1m0s
[20:48:26.798] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:42:0)
[20:48:26.798] Timer tick, asking Buildbucket for the build status
[20:48:26.969] Build 8913726184729535312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:48:26.969] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:43:0) after 1m0s
[20:49:26.992] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:43:0)
[20:49:26.992] Timer tick, asking Buildbucket for the build status
[20:49:27.039] Build 8913726184729535312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:49:27.039] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:44:0) after 1m0s
[20:50:27.058] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:44:0)
[20:50:27.058] Timer tick, asking Buildbucket for the build status
[20:50:27.153] Build 8913726184729535312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:50:27.153] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:45:0) after 1m0s
[20:51:27.218] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:45:0)
[20:51:27.218] Timer tick, asking Buildbucket for the build status
[20:51:27.306] Build 8913726184729535312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:51:27.306] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:46:0) after 1m0s
[20:52:27.328] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:46:0)
[20:52:27.328] Timer tick, asking Buildbucket for the build status
[20:52:27.385] Build 8913726184729535312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:52:27.385] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:47:0) after 1m0s
[20:53:27.406] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:47:0)
[20:53:27.406] Timer tick, asking Buildbucket for the build status
[20:53:27.556] Build 8913726184729535312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:53:27.557] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:48:0) after 1m0s
[20:54:27.592] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:48:0)
[20:54:27.592] Timer tick, asking Buildbucket for the build status
[20:54:27.664] Build 8913726184729535312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:54:27.664] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:49:0) after 1m0s
[20:55:27.753] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:49:0)
[20:55:27.753] Timer tick, asking Buildbucket for the build status
[20:55:27.891] Build 8913726184729535312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:55:27.891] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:50:0) after 1m0s
[20:56:27.920] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:50:0)
[20:56:27.920] Timer tick, asking Buildbucket for the build status
[20:56:27.993] Build 8913726184729535312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:56:27.993] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:51:0) after 1m0s
[20:57:28.016] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:51:0)
[20:57:28.016] Timer tick, asking Buildbucket for the build status
[20:57:28.166] Build 8913726184729535312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:57:28.166] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:52:0) after 1m0s
[20:58:28.153] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:52:0)
[20:58:28.153] Timer tick, asking Buildbucket for the build status
[20:58:28.228] Build 8913726184729535312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:58:28.228] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:53:0) after 1m0s
[20:59:28.285] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:53:0)
[20:59:28.285] Timer tick, asking Buildbucket for the build status
[20:59:28.352] Build 8913726184729535312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:59:28.352] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:54:0) after 1m0s
[21:00:28.377] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:54:0)
[21:00:28.377] Timer tick, asking Buildbucket for the build status
[21:00:28.444] Build 8913726184729535312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:00:28.444] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:55:0) after 1m0s
[21:01:28.457] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:55:0)
[21:01:28.457] Timer tick, asking Buildbucket for the build status
[21:01:28.581] Build 8913726184729535312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:01:28.581] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:56:0) after 1m0s
[21:02:28.618] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:56:0)
[21:02:28.618] Timer tick, asking Buildbucket for the build status
[21:02:28.692] Build 8913726184729535312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:02:28.692] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:57:0) after 1m0s
[21:03:28.729] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:57:0)
[21:03:28.729] Timer tick, asking Buildbucket for the build status
[21:03:28.805] Build 8913726184729535312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:03:28.805] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:58:0) after 1m0s
[21:04:28.823] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:58:0)
[21:04:28.823] Timer tick, asking Buildbucket for the build status
[21:04:28.972] Build 8913726184729535312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:04:28.972] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:59:0) after 1m0s
[21:05:37.379] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:59:0)
[21:05:37.379] Timer tick, asking Buildbucket for the build status
[21:05:37.825] Build 8913726184729535312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:05:37.825] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:60:0) after 1m0s
[21:06:37.861] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:60:0)
[21:06:37.861] Timer tick, asking Buildbucket for the build status
[21:06:37.956] Build 8913726184729535312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:06:37.956] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:61:0) after 1m0s
[21:07:38.045] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:61:0)
[21:07:38.045] Timer tick, asking Buildbucket for the build status
[21:07:38.165] Build 8913726184729535312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:07:38.165] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:62:0) after 1m0s
[21:08:38.209] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:62:0)
[21:08:38.209] Timer tick, asking Buildbucket for the build status
[21:08:38.283] Build 8913726184729535312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:08:38.283] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:63:0) after 1m0s
[21:09:38.308] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:63:0)
[21:09:38.308] Timer tick, asking Buildbucket for the build status
[21:09:38.495] Build 8913726184729535312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:09:38.495] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:64:0) after 1m0s
[21:10:38.515] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:64:0)
[21:10:38.515] Timer tick, asking Buildbucket for the build status
[21:10:38.788] Build 8913726184729535312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:10:38.788] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:65:0) after 1m0s
[21:11:38.809] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:65:0)
[21:11:38.809] Timer tick, asking Buildbucket for the build status
[21:11:38.850] Build 8913726184729535312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:11:38.850] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:66:0) after 1m0s
[21:12:38.874] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:66:0)
[21:12:38.874] Timer tick, asking Buildbucket for the build status
[21:12:39.030] Build 8913726184729535312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:12:39.030] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:67:0) after 1m0s
[21:13:39.022] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:67:0)
[21:13:39.022] Timer tick, asking Buildbucket for the build status
[21:13:39.155] Build 8913726184729535312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:13:39.155] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:68:0) after 1m0s
[21:14:39.354] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:68:0)
[21:14:39.354] Timer tick, asking Buildbucket for the build status
[21:14:39.886] Build 8913726184729535312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:14:39.886] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:69:0) after 1m0s
[21:15:39.908] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:69:0)
[21:15:39.908] Timer tick, asking Buildbucket for the build status
[21:15:39.941] Build 8913726184729535312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:15:39.941] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:70:0) after 1m0s
[21:16:39.966] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:70:0)
[21:16:39.966] Timer tick, asking Buildbucket for the build status
[21:16:40.010] Build 8913726184729535312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:16:40.010] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:71:0) after 1m0s
[21:17:40.021] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:71:0)
[21:17:40.021] Timer tick, asking Buildbucket for the build status
[21:17:40.419] Build 8913726184729535312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:17:40.419] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:72:0) after 1m0s
[21:18:40.482] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:72:0)
[21:18:40.482] Timer tick, asking Buildbucket for the build status
[21:18:40.812] Build 8913726184729535312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:18:40.812] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:73:0) after 1m0s
[21:19:40.833] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:73:0)
[21:19:40.833] Timer tick, asking Buildbucket for the build status
[21:19:40.918] Build 8913726184729535312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:19:40.918] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:74:0) after 1m0s
[21:20:40.945] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:74:0)
[21:20:40.945] Timer tick, asking Buildbucket for the build status
[21:20:42.768] Build 8913726184729535312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:20:42.768] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:75:0) after 1m0s
[21:21:42.772] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:75:0)
[21:21:42.772] Timer tick, asking Buildbucket for the build status
[21:21:43.142] Build 8913726184729535312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:21:43.142] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:76:0) after 1m0s
[21:22:43.164] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:76:0)
[21:22:43.164] Timer tick, asking Buildbucket for the build status
[21:22:43.206] Build 8913726184729535312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:22:43.206] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:77:0) after 1m0s
[21:23:43.241] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:77:0)
[21:23:43.241] Timer tick, asking Buildbucket for the build status
[21:23:43.332] Build 8913726184729535312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:23:43.332] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:78:0) after 1m0s
[21:24:43.358] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:78:0)
[21:24:43.358] Timer tick, asking Buildbucket for the build status
[21:24:43.558] Build 8913726184729535312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:24:43.558] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:79:0) after 1m0s
[21:25:43.579] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:79:0)
[21:25:43.579] Timer tick, asking Buildbucket for the build status
[21:25:43.723] Build 8913726184729535312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:25:43.723] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:80:0) after 1m0s
[21:26:43.745] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:80:0)
[21:26:43.745] Timer tick, asking Buildbucket for the build status
[21:26:43.994] Build 8913726184729535312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:26:43.994] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:81:0) after 1m0s
[21:27:44.018] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:81:0)
[21:27:44.018] Timer tick, asking Buildbucket for the build status
[21:27:44.123] Build 8913726184729535312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:27:44.123] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:82:0) after 1m0s
[21:28:44.164] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:82:0)
[21:28:44.164] Timer tick, asking Buildbucket for the build status
[21:28:44.209] Build 8913726184729535312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:28:44.209] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:83:0) after 1m0s
[21:29:44.226] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:83:0)
[21:29:44.226] Timer tick, asking Buildbucket for the build status
[21:29:44.588] Build 8913726184729535312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:29:44.588] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:84:0) after 1m0s
[21:30:44.609] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:84:0)
[21:30:44.609] Timer tick, asking Buildbucket for the build status
[21:30:44.857] Build 8913726184729535312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:30:44.857] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:85:0) after 1m0s
[21:31:44.898] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:85:0)
[21:31:44.898] Timer tick, asking Buildbucket for the build status
[21:31:45.562] Build 8913726184729535312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:31:45.562] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:86:0) after 1m0s
[21:32:45.583] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:86:0)
[21:32:45.583] Timer tick, asking Buildbucket for the build status
[21:32:45.771] Build 8913726184729535312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:32:45.771] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:87:0) after 1m0s
[21:33:45.805] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:87:0)
[21:33:45.805] Timer tick, asking Buildbucket for the build status
[21:33:46.281] Build 8913726184729535312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:33:46.281] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:88:0) after 1m0s
[21:34:46.304] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:88:0)
[21:34:46.304] Timer tick, asking Buildbucket for the build status
[21:34:46.484] Build 8913726184729535312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:34:46.484] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:89:0) after 1m0s
[21:35:46.503] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:89:0)
[21:35:46.503] Timer tick, asking Buildbucket for the build status
[21:35:46.565] Build 8913726184729535312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:35:46.565] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:90:0) after 1m0s
[21:36:46.585] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:90:0)
[21:36:46.585] Timer tick, asking Buildbucket for the build status
[21:36:47.298] Build 8913726184729535312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:36:47.298] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:91:0) after 1m0s
[21:37:47.318] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:91:0)
[21:37:47.318] Timer tick, asking Buildbucket for the build status
[21:37:47.388] Build 8913726184729535312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:37:47.388] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:92:0) after 1m0s
[21:38:47.441] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:92:0)
[21:38:47.441] Timer tick, asking Buildbucket for the build status
[21:38:47.556] Build 8913726184729535312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:38:47.556] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:93:0) after 1m0s
[21:39:47.556] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:93:0)
[21:39:47.556] Timer tick, asking Buildbucket for the build status
[21:39:47.919] Build 8913726184729535312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:39:47.919] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:94:0) after 1m0s
[21:40:47.941] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:94:0)
[21:40:47.941] Timer tick, asking Buildbucket for the build status
[21:40:48.056] Build 8913726184729535312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:40:48.056] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:95:0) after 1m0s
[21:41:48.086] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:95:0)
[21:41:48.086] Timer tick, asking Buildbucket for the build status
[21:41:48.151] Build 8913726184729535312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:41:48.151] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:96:0) after 1m0s
[21:42:48.180] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:96:0)
[21:42:48.180] Timer tick, asking Buildbucket for the build status
[21:42:48.496] Build 8913726184729535312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:42:48.496] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:97:0) after 1m0s
[21:43:48.528] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:97:0)
[21:43:48.528] Timer tick, asking Buildbucket for the build status
[21:43:48.892] Build 8913726184729535312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:43:48.892] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:98:0) after 1m0s
[21:44:48.918] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:98:0)
[21:44:48.918] Timer tick, asking Buildbucket for the build status
[21:44:48.994] Build 8913726184729535312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:44:48.994] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:99:0) after 1m0s
[21:45:48.979] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:99:0)
[21:45:48.979] Timer tick, asking Buildbucket for the build status
[21:45:49.114] Build 8913726184729535312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:45:49.114] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:100:0) after 1m0s
[21:46:49.137] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:100:0)
[21:46:49.137] Timer tick, asking Buildbucket for the build status
[21:46:49.602] Build 8913726184729535312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:46:49.602] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:101:0) after 1m0s
[21:47:49.623] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:101:0)
[21:47:49.623] Timer tick, asking Buildbucket for the build status
[21:47:49.776] Build 8913726184729535312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:47:49.776] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:102:0) after 1m0s
[21:48:49.808] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:102:0)
[21:48:49.808] Timer tick, asking Buildbucket for the build status
[21:48:49.892] Build 8913726184729535312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:48:49.892] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:103:0) after 1m0s
[21:49:49.928] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:103:0)
[21:49:49.928] Timer tick, asking Buildbucket for the build status
[21:49:50.018] Build 8913726184729535312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:49:50.018] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:104:0) after 1m0s
[21:50:50.058] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:104:0)
[21:50:50.058] Timer tick, asking Buildbucket for the build status
[21:50:50.201] Build 8913726184729535312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:50:50.201] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:105:0) after 1m0s
[21:51:50.224] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:105:0)
[21:51:50.224] Timer tick, asking Buildbucket for the build status
[21:51:51.376] Build 8913726184729535312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:51:51.376] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:106:0) after 1m0s
[21:52:51.347] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:106:0)
[21:52:51.347] Timer tick, asking Buildbucket for the build status
[21:52:51.975] Build 8913726184729535312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:52:51.975] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:107:0) after 1m0s
[21:53:52.004] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:107:0)
[21:53:52.004] Timer tick, asking Buildbucket for the build status
[21:53:52.226] Build 8913726184729535312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:53:52.226] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:108:0) after 1m0s
[21:54:52.253] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:108:0)
[21:54:52.253] Timer tick, asking Buildbucket for the build status
[21:54:52.452] Build 8913726184729535312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:54:52.452] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:109:0) after 1m0s
[21:55:52.487] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:109:0)
[21:55:52.487] Timer tick, asking Buildbucket for the build status
[21:55:52.802] Build 8913726184729535312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:55:52.802] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:110:0) after 1m0s
[21:56:52.827] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:110:0)
[21:56:52.827] Timer tick, asking Buildbucket for the build status
[21:56:52.928] Build 8913726184729535312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:56:52.928] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:111:0) after 1m0s
[21:57:52.963] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:111:0)
[21:57:52.963] Timer tick, asking Buildbucket for the build status
[21:57:53.039] Build 8913726184729535312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:57:53.039] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:112:0) after 1m0s
[21:58:53.072] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:112:0)
[21:58:53.072] Timer tick, asking Buildbucket for the build status
[21:58:53.202] Build 8913726184729535312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:58:53.202] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:113:0) after 1m0s
[21:59:53.221] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:113:0)
[21:59:53.221] Timer tick, asking Buildbucket for the build status
[21:59:53.505] Build 8913726184729535312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:59:53.505] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:114:0) after 1m0s
[22:00:53.545] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:114:0)
[22:00:53.546] Timer tick, asking Buildbucket for the build status
[22:00:53.750] Build 8913726184729535312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:00:53.750] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:115:0) after 1m0s
[22:01:53.787] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:115:0)
[22:01:53.788] Timer tick, asking Buildbucket for the build status
[22:01:53.933] Build 8913726184729535312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:01:53.933] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:116:0) after 1m0s
[22:02:53.974] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:116:0)
[22:02:53.974] Timer tick, asking Buildbucket for the build status
[22:02:54.045] Build 8913726184729535312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:02:54.045] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:117:0) after 1m0s
[22:03:54.190] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:117:0)
[22:03:54.190] Timer tick, asking Buildbucket for the build status
[22:03:54.340] Build 8913726184729535312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:03:54.340] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:118:0) after 1m0s
[22:04:54.362] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:118:0)
[22:04:54.362] Timer tick, asking Buildbucket for the build status
[22:04:54.537] Build 8913726184729535312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:04:54.537] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:119:0) after 1m0s
[22:05:54.571] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:119:0)
[22:05:54.571] Timer tick, asking Buildbucket for the build status
[22:05:55.448] Build 8913726184729535312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:05:55.448] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:120:0) after 1m0s
[22:06:55.487] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:120:0)
[22:06:55.487] Timer tick, asking Buildbucket for the build status
[22:06:55.694] Build 8913726184729535312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:06:55.694] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:121:0) after 1m0s
[22:07:55.682] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:121:0)
[22:07:55.682] Timer tick, asking Buildbucket for the build status
[22:07:55.928] Build 8913726184729535312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:07:55.928] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:122:0) after 1m0s
[22:08:56.366] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:122:0)
[22:08:56.366] Timer tick, asking Buildbucket for the build status
[22:08:56.433] Build 8913726184729535312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:08:56.433] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:123:0) after 1m0s
[22:09:56.482] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:123:0)
[22:09:56.482] Timer tick, asking Buildbucket for the build status
[22:09:56.659] Build 8913726184729535312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:09:56.659] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:124:0) after 1m0s
[22:10:56.690] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:124:0)
[22:10:56.690] Timer tick, asking Buildbucket for the build status
[22:10:56.779] Build 8913726184729535312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:10:56.779] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:125:0) after 1m0s
[22:11:56.803] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:125:0)
[22:11:56.803] Timer tick, asking Buildbucket for the build status
[22:11:56.992] Build 8913726184729535312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:11:56.992] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:126:0) after 1m0s
[22:12:57.028] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:126:0)
[22:12:57.028] Timer tick, asking Buildbucket for the build status
[22:12:57.125] Build 8913726184729535312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:12:57.125] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9079156246826083952:127:0) after 1m0s
[22:13:18.220] Received PubSub notification, asking Buildbucket for the build status
[22:13:18.263] Build 8913726184729535312: status "COMPLETED", result "CANCELED", failure_reason "", cancelation_reason "TIMEOUT"
[22:13:18.263] Buildbucket build:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary_preference": "AUTO",
"cancelation_reason": "TIMEOUT",
"completed_ts": "1557612795990381",
"created_by": "project:emscripten-releases",
"created_ts": "1557605296353689",
"id": "8913726184729535312",
"parameters_json": "{\"builder_name\": \"win\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"1a230712c1a11aa523cc8e078abbc2277c6a8ece\"}}",
"project": "emscripten-releases",
"result": "CANCELED",
"result_details_json": "{\"properties\": {\"$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\": \"luci-emscripten-releases-ci-win10-0-euqa\", \"branch\": \"refs/heads/master\", \"buildername\": \"win\", \"got_revision\": \"1a230712c1a11aa523cc8e078abbc2277c6a8ece\", \"path_config\": \"generic\", \"recipe\": \"emscripten_releases\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"1a230712c1a11aa523cc8e078abbc2277c6a8ece\"}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"builder_0175bca77590805533ed6e20351c9bfa7effd036e42d11a5b619a6ce75a8e486_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"host_class\": [\"default\"], \"id\": [\"luci-emscripten-releases-ci-win10-0-euqa\"], \"image\": [\"chrome-win10-1703-2018-04-20-61acaf8983f\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"n1-standard-8\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.emscripten-releases.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"4292-d8c05fa\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-b\"]}}}",
"service_account": "emscripten-releases-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1557605468444163",
"status": "COMPLETED",
"status_changed_ts": "1557612797937163",
"tags": [
"builder:win",
"buildset:commit/git/1a230712c1a11aa523cc8e078abbc2277c6a8ece",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/1a230712c1a11aa523cc8e078abbc2277c6a8ece",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9079156246826083952",
"scheduler_job_id:emscripten-releases/win",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/emscripten-releases/buildbucket/cr-buildbucket.appspot.com/8913726184729535312/+/annotations",
"swarming_tag:luci_project:emscripten-releases",
"swarming_tag:os:Windows",
"swarming_tag:recipe_name:emscripten_releases",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:44c15435c87e1d10",
"user_agent:luci-scheduler"
],
"updated_ts": "1557612797939450",
"url": "https://ci.chromium.org/b/8913726184729535312",
"utcnow_ts": "1557612798255068"
}
}
[22:13:18.263] Invocation finished in 2h5m3.293016027s with status FAILED