[09:09:24.844] New invocation is queued and will start shortly
[09:09:25.932] Starting the invocation (attempt 1)
[09:09:25.976] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[09:09:25.976] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[09:09:25.976] Buildbucket request:
{
"bucket": "luci.emscripten-releases.ci",
"client_operation_id": "9063977406830786096",
"parameters_json": "{\"builder_name\":\"win\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/emscripten-releases\",\"revision\":\"2e7033e5b9eedba925b58ea6cfea50c9b54af883\"},\"id\":\"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@2e7033e5b9eedba925b58ea6cfea50c9b54af883\",\"title\":\"2e7033e5b9eedba925b58ea6cfea50c9b54af883\",\"url\":\"https://chromium.googlesource.com/emscripten-releases/+/2e7033e5b9eedba925b58ea6cfea50c9b54af883\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/emscripten-releases\",\"revision\":\"2e7033e5b9eedba925b58ea6cfea50c9b54af883\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win",
"scheduler_invocation_id:9063977406830786096",
"scheduler_job_id:emscripten-releases/win",
"user_agent:luci-scheduler",
"buildset:commit/git/2e7033e5b9eedba925b58ea6cfea50c9b54af883",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/2e7033e5b9eedba925b58ea6cfea50c9b54af883",
"gitiles_ref:refs/heads/master"
]
}
[09:09:27.341] Buildbucket response:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary_preference": "PROD",
"created_by": "project:emscripten-releases",
"created_ts": "1572080966173079",
"id": "8898547344772578704",
"parameters_json": "{\"builder_name\": \"win\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"2e7033e5b9eedba925b58ea6cfea50c9b54af883\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@2e7033e5b9eedba925b58ea6cfea50c9b54af883\", \"title\": \"2e7033e5b9eedba925b58ea6cfea50c9b54af883\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/2e7033e5b9eedba925b58ea6cfea50c9b54af883\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"2e7033e5b9eedba925b58ea6cfea50c9b54af883\"}}",
"project": "emscripten-releases",
"result_details_json": "{\"properties\": {}}",
"service_account": "emscripten-releases-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1572080967079320",
"tags": [
"builder:win",
"buildset:commit/git/2e7033e5b9eedba925b58ea6cfea50c9b54af883",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/2e7033e5b9eedba925b58ea6cfea50c9b54af883",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9063977406830786096",
"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/8898547344772578704/+/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": "1572080967079527",
"url": "https://ci.chromium.org/b/8898547344772578704",
"utcnow_ts": "1572080967328713"
}
}
[09:09:27.341] Task URL: https://ci.chromium.org/b/8898547344772578704
[09:09:27.341] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:2:0) after 1m0s
[09:10:02.230] Received PubSub notification, asking Buildbucket for the build status
[09:10:02.352] Build 8898547344772578704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:10:27.499] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:2:0)
[09:10:27.500] Timer tick, asking Buildbucket for the build status
[09:10:27.615] Build 8898547344772578704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:10:27.615] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:4:0) after 1m0s
[09:11:27.856] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:4:0)
[09:11:27.856] Timer tick, asking Buildbucket for the build status
[09:11:28.039] Build 8898547344772578704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:11:28.039] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:5:0) after 1m0s
[09:12:28.067] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:5:0)
[09:12:28.067] Timer tick, asking Buildbucket for the build status
[09:12:28.201] Build 8898547344772578704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:12:28.201] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:6:0) after 1m0s
[09:13:32.503] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:6:0)
[09:13:32.503] Timer tick, asking Buildbucket for the build status
[09:13:32.758] Build 8898547344772578704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:13:32.758] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:7:0) after 1m0s
[09:14:32.861] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:7:0)
[09:14:32.861] Timer tick, asking Buildbucket for the build status
[09:14:33.022] Build 8898547344772578704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:14:33.022] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:8:0) after 1m0s
[09:15:33.105] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:8:0)
[09:15:33.105] Timer tick, asking Buildbucket for the build status
[09:15:33.336] Build 8898547344772578704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:15:33.336] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:9:0) after 1m0s
[09:16:33.406] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:9:0)
[09:16:33.406] Timer tick, asking Buildbucket for the build status
[09:16:33.536] Build 8898547344772578704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:16:33.536] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:10:0) after 1m0s
[09:17:33.581] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:10:0)
[09:17:33.581] Timer tick, asking Buildbucket for the build status
[09:17:33.750] Build 8898547344772578704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:17:33.750] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:11:0) after 1m0s
[09:18:33.910] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:11:0)
[09:18:33.910] Timer tick, asking Buildbucket for the build status
[09:18:34.152] Build 8898547344772578704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:18:34.152] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:12:0) after 1m0s
[09:19:34.253] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:12:0)
[09:19:34.253] Timer tick, asking Buildbucket for the build status
[09:19:35.341] Build 8898547344772578704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:19:35.341] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:13:0) after 1m0s
[09:20:35.404] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:13:0)
[09:20:35.404] Timer tick, asking Buildbucket for the build status
[09:20:36.940] Build 8898547344772578704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:20:36.940] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:14:0) after 1m0s
[09:21:36.976] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:14:0)
[09:21:36.976] Timer tick, asking Buildbucket for the build status
[09:21:37.123] Build 8898547344772578704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:21:37.123] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:15:0) after 1m0s
[09:22:37.203] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:15:0)
[09:22:37.203] Timer tick, asking Buildbucket for the build status
[09:22:37.338] Build 8898547344772578704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:22:37.338] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:16:0) after 1m0s
[09:23:37.361] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:16:0)
[09:23:37.361] Timer tick, asking Buildbucket for the build status
[09:23:37.525] Build 8898547344772578704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:23:37.525] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:17:0) after 1m0s
[09:24:37.549] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:17:0)
[09:24:37.549] Timer tick, asking Buildbucket for the build status
[09:24:37.695] Build 8898547344772578704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:24:37.695] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:18:0) after 1m0s
[09:25:37.757] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:18:0)
[09:25:37.757] Timer tick, asking Buildbucket for the build status
[09:25:37.996] Build 8898547344772578704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:25:37.996] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:19:0) after 1m0s
[09:26:38.095] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:19:0)
[09:26:38.095] Timer tick, asking Buildbucket for the build status
[09:26:38.249] Build 8898547344772578704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:26:38.249] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:20:0) after 1m0s
[09:27:38.301] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:20:0)
[09:27:38.301] Timer tick, asking Buildbucket for the build status
[09:27:38.602] Build 8898547344772578704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:27:38.602] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:21:0) after 1m0s
[09:28:38.643] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:21:0)
[09:28:38.643] Timer tick, asking Buildbucket for the build status
[09:28:38.760] Build 8898547344772578704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:28:38.760] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:22:0) after 1m0s
[09:29:38.797] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:22:0)
[09:29:38.797] Timer tick, asking Buildbucket for the build status
[09:29:39.246] Build 8898547344772578704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:29:39.246] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:23:0) after 1m0s
[09:30:39.314] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:23:0)
[09:30:39.314] Timer tick, asking Buildbucket for the build status
[09:30:39.511] Build 8898547344772578704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:30:39.511] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:24:0) after 1m0s
[09:31:39.570] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:24:0)
[09:31:39.570] Timer tick, asking Buildbucket for the build status
[09:31:39.818] Build 8898547344772578704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:31:39.818] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:25:0) after 1m0s
[09:32:39.887] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:25:0)
[09:32:39.887] Timer tick, asking Buildbucket for the build status
[09:32:40.071] Build 8898547344772578704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:32:40.071] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:26:0) after 1m0s
[09:33:40.127] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:26:0)
[09:33:40.127] Timer tick, asking Buildbucket for the build status
[09:33:40.348] Build 8898547344772578704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:33:40.348] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:27:0) after 1m0s
[09:34:40.366] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:27:0)
[09:34:40.366] Timer tick, asking Buildbucket for the build status
[09:34:40.571] Build 8898547344772578704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:34:40.571] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:28:0) after 1m0s
[09:35:40.618] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:28:0)
[09:35:40.618] Timer tick, asking Buildbucket for the build status
[09:35:40.858] Build 8898547344772578704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:35:40.858] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:29:0) after 1m0s
[09:36:40.971] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:29:0)
[09:36:40.971] Timer tick, asking Buildbucket for the build status
[09:36:41.195] Build 8898547344772578704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:36:41.195] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:30:0) after 1m0s
[09:37:41.188] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:30:0)
[09:37:41.188] Timer tick, asking Buildbucket for the build status
[09:37:42.850] Build 8898547344772578704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:37:42.850] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:31:0) after 1m0s
[09:38:42.935] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:31:0)
[09:38:42.935] Timer tick, asking Buildbucket for the build status
[09:38:44.553] Build 8898547344772578704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:38:44.553] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:32:0) after 1m0s
[09:39:44.554] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:32:0)
[09:39:44.554] Timer tick, asking Buildbucket for the build status
[09:39:44.671] Build 8898547344772578704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:39:44.671] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:33:0) after 1m0s
[09:40:44.695] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:33:0)
[09:40:44.695] Timer tick, asking Buildbucket for the build status
[09:40:44.912] Build 8898547344772578704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:40:44.912] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:34:0) after 1m0s
[09:41:44.956] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:34:0)
[09:41:44.956] Timer tick, asking Buildbucket for the build status
[09:41:46.236] Build 8898547344772578704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:41:46.236] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:35:0) after 1m0s
[09:42:46.230] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:35:0)
[09:42:46.230] Timer tick, asking Buildbucket for the build status
[09:42:46.445] Build 8898547344772578704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:42:46.445] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:36:0) after 1m0s
[09:43:46.503] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:36:0)
[09:43:46.503] Timer tick, asking Buildbucket for the build status
[09:43:46.642] Build 8898547344772578704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:43:46.642] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:37:0) after 1m0s
[09:44:46.720] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:37:0)
[09:44:46.720] Timer tick, asking Buildbucket for the build status
[09:44:46.840] Build 8898547344772578704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:44:46.840] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:38:0) after 1m0s
[09:45:46.902] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:38:0)
[09:45:46.902] Timer tick, asking Buildbucket for the build status
[09:45:47.119] Build 8898547344772578704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:45:47.119] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:39:0) after 1m0s
[09:46:47.186] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:39:0)
[09:46:47.186] Timer tick, asking Buildbucket for the build status
[09:46:47.410] Build 8898547344772578704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:46:47.411] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:40:0) after 1m0s
[09:47:47.676] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:40:0)
[09:47:47.676] Timer tick, asking Buildbucket for the build status
[09:47:47.794] Build 8898547344772578704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:47:47.794] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:41:0) after 1m0s
[09:48:47.929] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:41:0)
[09:48:47.929] Timer tick, asking Buildbucket for the build status
[09:48:48.304] Build 8898547344772578704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:48:48.304] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:42:0) after 1m0s
[09:49:48.345] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:42:0)
[09:49:48.345] Timer tick, asking Buildbucket for the build status
[09:49:48.515] Build 8898547344772578704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:49:48.515] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:43:0) after 1m0s
[09:50:48.559] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:43:0)
[09:50:48.559] Timer tick, asking Buildbucket for the build status
[09:50:48.749] Build 8898547344772578704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:50:48.749] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:44:0) after 1m0s
[09:51:48.777] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:44:0)
[09:51:48.777] Timer tick, asking Buildbucket for the build status
[09:51:48.916] Build 8898547344772578704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:51:48.916] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:45:0) after 1m0s
[09:52:48.945] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:45:0)
[09:52:48.945] Timer tick, asking Buildbucket for the build status
[09:52:49.443] Build 8898547344772578704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:52:49.443] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:46:0) after 1m0s
[09:53:49.485] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:46:0)
[09:53:49.485] Timer tick, asking Buildbucket for the build status
[09:53:49.646] Build 8898547344772578704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:53:49.646] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:47:0) after 1m0s
[09:54:49.692] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:47:0)
[09:54:49.692] Timer tick, asking Buildbucket for the build status
[09:54:50.041] Build 8898547344772578704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:54:50.041] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:48:0) after 1m0s
[09:55:50.068] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:48:0)
[09:55:50.068] Timer tick, asking Buildbucket for the build status
[09:55:50.242] Build 8898547344772578704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:55:50.242] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:49:0) after 1m0s
[09:56:50.353] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:49:0)
[09:56:50.353] Timer tick, asking Buildbucket for the build status
[09:56:50.726] Build 8898547344772578704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:56:50.726] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:50:0) after 1m0s
[09:57:50.855] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:50:0)
[09:57:50.855] Timer tick, asking Buildbucket for the build status
[09:57:50.956] Build 8898547344772578704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:57:50.956] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:51:0) after 1m0s
[09:58:51.018] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:51:0)
[09:58:51.018] Timer tick, asking Buildbucket for the build status
[09:58:51.332] Build 8898547344772578704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:58:51.332] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:52:0) after 1m0s
[09:59:51.432] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:52:0)
[09:59:51.432] Timer tick, asking Buildbucket for the build status
[09:59:51.823] Build 8898547344772578704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:59:51.823] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:53:0) after 1m0s
[10:00:51.846] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:53:0)
[10:00:51.846] Timer tick, asking Buildbucket for the build status
[10:00:52.285] Build 8898547344772578704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:00:52.285] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:54:0) after 1m0s
[10:01:52.466] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:54:0)
[10:01:52.466] Timer tick, asking Buildbucket for the build status
[10:01:52.673] Build 8898547344772578704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:01:52.673] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:55:0) after 1m0s
[10:02:52.800] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:55:0)
[10:02:52.800] Timer tick, asking Buildbucket for the build status
[10:02:53.690] Build 8898547344772578704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:02:53.690] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:56:0) after 1m0s
[10:03:53.853] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:56:0)
[10:03:53.853] Timer tick, asking Buildbucket for the build status
[10:03:54.079] Build 8898547344772578704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:03:54.079] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:57:0) after 1m0s
[10:04:54.166] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:57:0)
[10:04:54.166] Timer tick, asking Buildbucket for the build status
[10:04:55.021] Build 8898547344772578704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:04:55.021] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:58:0) after 1m0s
[10:05:55.142] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:58:0)
[10:05:55.142] Timer tick, asking Buildbucket for the build status
[10:05:55.327] Build 8898547344772578704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:05:55.327] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:59:0) after 1m0s
[10:06:55.415] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:59:0)
[10:06:55.415] Timer tick, asking Buildbucket for the build status
[10:06:55.545] Build 8898547344772578704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:06:55.545] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:60:0) after 1m0s
[10:07:55.588] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:60:0)
[10:07:55.588] Timer tick, asking Buildbucket for the build status
[10:07:55.940] Build 8898547344772578704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:07:55.940] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:61:0) after 1m0s
[10:08:56.111] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:61:0)
[10:08:56.111] Timer tick, asking Buildbucket for the build status
[10:08:56.302] Build 8898547344772578704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:08:56.302] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:62:0) after 1m0s
[10:09:56.403] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:62:0)
[10:09:56.403] Timer tick, asking Buildbucket for the build status
[10:09:56.561] Build 8898547344772578704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:09:56.561] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:63:0) after 1m0s
[10:10:56.585] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:63:0)
[10:10:56.585] Timer tick, asking Buildbucket for the build status
[10:10:56.834] Build 8898547344772578704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:10:56.834] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:64:0) after 1m0s
[10:11:56.992] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:64:0)
[10:11:56.992] Timer tick, asking Buildbucket for the build status
[10:11:57.102] Build 8898547344772578704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:11:57.102] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:65:0) after 1m0s
[10:12:57.082] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:65:0)
[10:12:57.082] Timer tick, asking Buildbucket for the build status
[10:12:57.199] Build 8898547344772578704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:12:57.199] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:66:0) after 1m0s
[10:13:57.396] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:66:0)
[10:13:57.396] Timer tick, asking Buildbucket for the build status
[10:13:58.283] Build 8898547344772578704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:13:58.283] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:67:0) after 1m0s
[10:14:58.310] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:67:0)
[10:14:58.310] Timer tick, asking Buildbucket for the build status
[10:14:59.217] Build 8898547344772578704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:14:59.217] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:68:0) after 1m0s
[10:15:59.247] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:68:0)
[10:15:59.247] Timer tick, asking Buildbucket for the build status
[10:15:59.398] Build 8898547344772578704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:15:59.398] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:69:0) after 1m0s
[10:16:59.449] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:69:0)
[10:16:59.449] Timer tick, asking Buildbucket for the build status
[10:16:59.716] Build 8898547344772578704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:16:59.716] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:70:0) after 1m0s
[10:17:59.762] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:70:0)
[10:17:59.762] Timer tick, asking Buildbucket for the build status
[10:17:59.984] Build 8898547344772578704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:17:59.984] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:71:0) after 1m0s
[10:18:59.999] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:71:0)
[10:18:59.999] Timer tick, asking Buildbucket for the build status
[10:19:00.173] Build 8898547344772578704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:19:00.173] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:72:0) after 1m0s
[10:20:00.240] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:72:0)
[10:20:00.240] Timer tick, asking Buildbucket for the build status
[10:20:00.391] Build 8898547344772578704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:20:00.391] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:73:0) after 1m0s
[10:21:00.417] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:73:0)
[10:21:00.417] Timer tick, asking Buildbucket for the build status
[10:21:00.617] Build 8898547344772578704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:21:00.617] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:74:0) after 1m0s
[10:22:00.809] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:74:0)
[10:22:00.809] Timer tick, asking Buildbucket for the build status
[10:22:00.917] Build 8898547344772578704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:22:00.917] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:75:0) after 1m0s
[10:23:01.500] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:75:0)
[10:23:01.500] Timer tick, asking Buildbucket for the build status
[10:23:02.556] Build 8898547344772578704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:23:02.556] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:76:0) after 1m0s
[10:24:02.743] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:76:0)
[10:24:02.743] Timer tick, asking Buildbucket for the build status
[10:24:02.901] Build 8898547344772578704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:24:02.901] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:77:0) after 1m0s
[10:25:03.091] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:77:0)
[10:25:03.091] Timer tick, asking Buildbucket for the build status
[10:25:03.652] Build 8898547344772578704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:25:03.652] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:78:0) after 1m0s
[10:26:03.736] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:78:0)
[10:26:03.736] Timer tick, asking Buildbucket for the build status
[10:26:03.849] Build 8898547344772578704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:26:03.849] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:79:0) after 1m0s
[10:27:04.000] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:79:0)
[10:27:04.000] Timer tick, asking Buildbucket for the build status
[10:27:04.121] Build 8898547344772578704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:27:04.121] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:80:0) after 1m0s
[10:28:04.377] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:80:0)
[10:28:04.377] Timer tick, asking Buildbucket for the build status
[10:28:04.505] Build 8898547344772578704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:28:04.505] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:81:0) after 1m0s
[10:29:04.530] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:81:0)
[10:29:04.530] Timer tick, asking Buildbucket for the build status
[10:29:04.694] Build 8898547344772578704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:29:04.694] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:82:0) after 1m0s
[10:30:04.844] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:82:0)
[10:30:04.844] Timer tick, asking Buildbucket for the build status
[10:30:04.971] Build 8898547344772578704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:30:04.971] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:83:0) after 1m0s
[10:31:05.519] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:83:0)
[10:31:05.519] Timer tick, asking Buildbucket for the build status
[10:31:05.670] Build 8898547344772578704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:31:05.670] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:84:0) after 1m0s
[10:32:05.820] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:84:0)
[10:32:05.820] Timer tick, asking Buildbucket for the build status
[10:32:05.934] Build 8898547344772578704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:32:05.934] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:85:0) after 1m0s
[10:33:05.965] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:85:0)
[10:33:05.965] Timer tick, asking Buildbucket for the build status
[10:33:06.058] Build 8898547344772578704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:33:06.058] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:86:0) after 1m0s
[10:34:06.299] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:86:0)
[10:34:06.299] Timer tick, asking Buildbucket for the build status
[10:34:06.439] Build 8898547344772578704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:34:06.439] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:87:0) after 1m0s
[10:35:06.650] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:87:0)
[10:35:06.650] Timer tick, asking Buildbucket for the build status
[10:35:06.759] Build 8898547344772578704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:35:06.759] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:88:0) after 1m0s
[10:36:06.753] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:88:0)
[10:36:06.753] Timer tick, asking Buildbucket for the build status
[10:36:06.958] Build 8898547344772578704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:36:06.958] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:89:0) after 1m0s
[10:37:06.987] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:89:0)
[10:37:06.987] Timer tick, asking Buildbucket for the build status
[10:37:07.113] Build 8898547344772578704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:37:07.113] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:90:0) after 1m0s
[10:38:07.412] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:90:0)
[10:38:07.412] Timer tick, asking Buildbucket for the build status
[10:38:07.670] Build 8898547344772578704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:38:07.670] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:91:0) after 1m0s
[10:39:08.117] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:91:0)
[10:39:08.118] Timer tick, asking Buildbucket for the build status
[10:39:08.177] Build 8898547344772578704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:39:08.177] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:92:0) after 1m0s
[10:40:08.357] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:92:0)
[10:40:08.357] Timer tick, asking Buildbucket for the build status
[10:40:08.768] Build 8898547344772578704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:40:08.768] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:93:0) after 1m0s
[10:41:08.825] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:93:0)
[10:41:08.825] Timer tick, asking Buildbucket for the build status
[10:41:09.009] Build 8898547344772578704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:41:09.009] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:94:0) after 1m0s
[10:42:09.147] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:94:0)
[10:42:09.147] Timer tick, asking Buildbucket for the build status
[10:42:09.273] Build 8898547344772578704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:42:09.273] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:95:0) after 1m0s
[10:43:09.403] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:95:0)
[10:43:09.403] Timer tick, asking Buildbucket for the build status
[10:43:09.546] Build 8898547344772578704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:43:09.546] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:96:0) after 1m0s
[10:44:09.587] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:96:0)
[10:44:09.587] Timer tick, asking Buildbucket for the build status
[10:44:09.802] Build 8898547344772578704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:44:09.802] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:97:0) after 1m0s
[10:45:09.809] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:97:0)
[10:45:09.809] Timer tick, asking Buildbucket for the build status
[10:45:09.993] Build 8898547344772578704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:45:09.993] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:98:0) after 1m0s
[10:46:10.027] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:98:0)
[10:46:10.027] Timer tick, asking Buildbucket for the build status
[10:46:10.453] Build 8898547344772578704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:46:10.453] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:99:0) after 1m0s
[10:47:10.515] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:99:0)
[10:47:10.515] Timer tick, asking Buildbucket for the build status
[10:47:10.741] Build 8898547344772578704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:47:10.741] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:100:0) after 1m0s
[10:48:10.908] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:100:0)
[10:48:10.908] Timer tick, asking Buildbucket for the build status
[10:48:11.307] Build 8898547344772578704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:48:11.307] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:101:0) after 1m0s
[10:49:11.440] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:101:0)
[10:49:11.440] Timer tick, asking Buildbucket for the build status
[10:49:11.601] Build 8898547344772578704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:49:11.601] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:102:0) after 1m0s
[10:50:11.656] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:102:0)
[10:50:11.656] Timer tick, asking Buildbucket for the build status
[10:50:11.768] Build 8898547344772578704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:50:11.768] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:103:0) after 1m0s
[10:51:11.857] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:103:0)
[10:51:11.857] Timer tick, asking Buildbucket for the build status
[10:51:11.998] Build 8898547344772578704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:51:11.998] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:104:0) after 1m0s
[10:52:12.164] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:104:0)
[10:52:12.164] Timer tick, asking Buildbucket for the build status
[10:52:12.239] Build 8898547344772578704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:52:12.239] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:105:0) after 1m0s
[10:53:12.266] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:105:0)
[10:53:12.266] Timer tick, asking Buildbucket for the build status
[10:53:12.514] Build 8898547344772578704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:53:12.514] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:106:0) after 1m0s
[10:54:12.545] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:106:0)
[10:54:12.545] Timer tick, asking Buildbucket for the build status
[10:54:12.947] Build 8898547344772578704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:54:12.947] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:107:0) after 1m0s
[10:55:12.973] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:107:0)
[10:55:12.973] Timer tick, asking Buildbucket for the build status
[10:55:13.152] Build 8898547344772578704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:55:13.152] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:108:0) after 1m0s
[10:56:13.179] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:108:0)
[10:56:13.179] Timer tick, asking Buildbucket for the build status
[10:56:13.438] Build 8898547344772578704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:56:13.438] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:109:0) after 1m0s
[10:57:13.519] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:109:0)
[10:57:13.519] Timer tick, asking Buildbucket for the build status
[10:57:13.648] Build 8898547344772578704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:57:13.648] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:110:0) after 1m0s
[10:58:13.761] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:110:0)
[10:58:13.761] Timer tick, asking Buildbucket for the build status
[10:58:13.981] Build 8898547344772578704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:58:13.981] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:111:0) after 1m0s
[10:59:14.069] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:111:0)
[10:59:14.069] Timer tick, asking Buildbucket for the build status
[10:59:14.299] Build 8898547344772578704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:59:14.299] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:112:0) after 1m0s
[11:00:14.435] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:112:0)
[11:00:14.435] Timer tick, asking Buildbucket for the build status
[11:00:14.695] Build 8898547344772578704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:00:14.695] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:113:0) after 1m0s
[11:01:14.723] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:113:0)
[11:01:14.723] Timer tick, asking Buildbucket for the build status
[11:01:14.837] Build 8898547344772578704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:01:14.837] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:114:0) after 1m0s
[11:02:14.834] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:114:0)
[11:02:14.834] Timer tick, asking Buildbucket for the build status
[11:02:15.181] Build 8898547344772578704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:02:15.182] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:115:0) after 1m0s
[11:03:15.277] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:115:0)
[11:03:15.277] Timer tick, asking Buildbucket for the build status
[11:03:15.410] Build 8898547344772578704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:03:15.410] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:116:0) after 1m0s
[11:04:15.520] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:116:0)
[11:04:15.520] Timer tick, asking Buildbucket for the build status
[11:04:15.588] Build 8898547344772578704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:04:15.588] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:117:0) after 1m0s
[11:05:15.602] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:117:0)
[11:05:15.602] Timer tick, asking Buildbucket for the build status
[11:05:15.756] Build 8898547344772578704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:05:15.756] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:118:0) after 1m0s
[11:06:15.919] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:118:0)
[11:06:15.919] Timer tick, asking Buildbucket for the build status
[11:06:16.182] Build 8898547344772578704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:06:16.182] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:119:0) after 1m0s
[11:07:16.316] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:119:0)
[11:07:16.316] Timer tick, asking Buildbucket for the build status
[11:07:16.560] Build 8898547344772578704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:07:16.560] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:120:0) after 1m0s
[11:08:16.650] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:120:0)
[11:08:16.650] Timer tick, asking Buildbucket for the build status
[11:08:16.834] Build 8898547344772578704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:08:16.834] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:121:0) after 1m0s
[11:09:16.867] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:121:0)
[11:09:16.867] Timer tick, asking Buildbucket for the build status
[11:09:17.142] Build 8898547344772578704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:09:17.142] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:122:0) after 1m0s
[11:10:17.268] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:122:0)
[11:10:17.268] Timer tick, asking Buildbucket for the build status
[11:10:17.554] Build 8898547344772578704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:10:17.554] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:123:0) after 1m0s
[11:11:17.667] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:123:0)
[11:11:17.667] Timer tick, asking Buildbucket for the build status
[11:11:17.864] Build 8898547344772578704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:11:17.864] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:124:0) after 1m0s
[11:12:17.942] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:124:0)
[11:12:17.942] Timer tick, asking Buildbucket for the build status
[11:12:18.104] Build 8898547344772578704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:12:18.104] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:125:0) after 1m0s
[11:13:18.115] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:125:0)
[11:13:18.115] Timer tick, asking Buildbucket for the build status
[11:13:18.394] Build 8898547344772578704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:13:18.394] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063977406830786096:126:0) after 1m0s
[11:13:26.028] Received PubSub notification, asking Buildbucket for the build status
[11:13:26.118] Build 8898547344772578704: status "COMPLETED", result "CANCELED", failure_reason "", cancelation_reason "TIMEOUT"
[11:13:26.118] Buildbucket build:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary_preference": "PROD",
"cancelation_reason": "TIMEOUT",
"completed_ts": "1572088404881510",
"created_by": "project:emscripten-releases",
"created_ts": "1572080966173079",
"id": "8898547344772578704",
"parameters_json": "{\"builder_name\": \"win\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"2e7033e5b9eedba925b58ea6cfea50c9b54af883\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@2e7033e5b9eedba925b58ea6cfea50c9b54af883\", \"title\": \"2e7033e5b9eedba925b58ea6cfea50c9b54af883\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/2e7033e5b9eedba925b58ea6cfea50c9b54af883\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"2e7033e5b9eedba925b58ea6cfea50c9b54af883\"}}",
"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}, \"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"2e7033e5b9eedba925b58ea6cfea50c9b54af883\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@2e7033e5b9eedba925b58ea6cfea50c9b54af883\", \"title\": \"2e7033e5b9eedba925b58ea6cfea50c9b54af883\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/2e7033e5b9eedba925b58ea6cfea50c9b54af883\"}]}, \"bot_id\": \"luci-emscripten-releases-ci-win10-0-01e0\", \"branch\": \"refs/heads/master\", \"buildername\": \"win\", \"got_revision\": \"2e7033e5b9eedba925b58ea6cfea50c9b54af883\", \"path_config\": \"generic\", \"recipe\": \"emscripten_releases\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"2e7033e5b9eedba925b58ea6cfea50c9b54af883\"}, \"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-01e0\"], \"image\": [\"chrome-win10-1703-19102400-d35fedf5a0d\"], \"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\": [\"4549-8dc3ae7\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-b\"]}}}",
"service_account": "emscripten-releases-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1572081000579423",
"status": "COMPLETED",
"status_changed_ts": "1572088405415601",
"tags": [
"builder:win",
"buildset:commit/git/2e7033e5b9eedba925b58ea6cfea50c9b54af883",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/2e7033e5b9eedba925b58ea6cfea50c9b54af883",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9063977406830786096",
"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/8898547344772578704/+/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:4820256f2ffe8910",
"user_agent:luci-scheduler"
],
"updated_ts": "1572088405515764",
"url": "https://ci.chromium.org/b/8898547344772578704",
"utcnow_ts": "1572088406109172"
}
}
[11:13:26.118] Invocation finished in 2h4m1.291787722s with status FAILED