[08:29:06.795] New invocation is queued and will start shortly
[08:29:07.977] Starting the invocation (attempt 1)
[08:29:08.048] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[08:29:08.048] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[08:29:08.048] Buildbucket request:
{
"bucket": "luci.emscripten-releases.ci",
"client_operation_id": "9078384859995434880",
"parameters_json": "{\"builder_name\":\"win\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/emscripten-releases\",\"revision\":\"6a6438959bc140ebd713456218c4edbfc5e0d876\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win",
"scheduler_invocation_id:9078384859995434880",
"scheduler_job_id:emscripten-releases/win",
"user_agent:luci-scheduler",
"buildset:commit/git/6a6438959bc140ebd713456218c4edbfc5e0d876",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/6a6438959bc140ebd713456218c4edbfc5e0d876",
"gitiles_ref:refs/heads/master"
]
}
[08:29:08.723] Buildbucket response:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary_preference": "AUTO",
"created_by": "project:emscripten-releases",
"created_ts": "1558340948143859",
"id": "8912954797918276848",
"parameters_json": "{\"builder_name\": \"win\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"6a6438959bc140ebd713456218c4edbfc5e0d876\"}}",
"project": "emscripten-releases",
"result_details_json": "{\"properties\": {}}",
"service_account": "emscripten-releases-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1558340948545442",
"tags": [
"builder:win",
"buildset:commit/git/6a6438959bc140ebd713456218c4edbfc5e0d876",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/6a6438959bc140ebd713456218c4edbfc5e0d876",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9078384859995434880",
"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/8912954797918276848/+/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": "1558340948545536",
"url": "https://ci.chromium.org/b/8912954797918276848",
"utcnow_ts": "1558340948691662"
}
}
[08:29:08.723] Task URL: https://ci.chromium.org/b/8912954797918276848
[08:29:08.723] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:2:0) after 1m0s
[08:29:20.592] Received PubSub notification, asking Buildbucket for the build status
[08:29:21.885] Build 8912954797918276848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:30:08.764] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:2:0)
[08:30:08.764] Timer tick, asking Buildbucket for the build status
[08:30:08.903] Build 8912954797918276848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:30:08.903] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:4:0) after 1m0s
[08:31:08.991] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:4:0)
[08:31:08.991] Timer tick, asking Buildbucket for the build status
[08:31:09.118] Build 8912954797918276848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:31:09.118] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:5:0) after 1m0s
[08:32:09.206] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:5:0)
[08:32:09.206] Timer tick, asking Buildbucket for the build status
[08:32:09.240] Build 8912954797918276848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:32:09.240] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:6:0) after 1m0s
[08:33:09.262] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:6:0)
[08:33:09.262] Timer tick, asking Buildbucket for the build status
[08:33:09.370] Build 8912954797918276848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:33:09.370] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:7:0) after 1m0s
[08:34:09.535] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:7:0)
[08:34:09.535] Timer tick, asking Buildbucket for the build status
[08:34:09.963] Build 8912954797918276848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:34:09.963] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:8:0) after 1m0s
[08:35:09.946] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:8:0)
[08:35:09.946] Timer tick, asking Buildbucket for the build status
[08:35:10.063] Build 8912954797918276848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:35:10.063] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:9:0) after 1m0s
[08:36:10.089] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:9:0)
[08:36:10.089] Timer tick, asking Buildbucket for the build status
[08:36:10.438] Build 8912954797918276848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:36:10.438] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:10:0) after 1m0s
[08:37:10.463] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:10:0)
[08:37:10.463] Timer tick, asking Buildbucket for the build status
[08:37:12.077] Build 8912954797918276848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:37:12.077] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:11:0) after 1m0s
[08:38:12.097] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:11:0)
[08:38:12.097] Timer tick, asking Buildbucket for the build status
[08:38:12.190] Build 8912954797918276848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:38:12.190] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:12:0) after 1m0s
[08:39:12.207] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:12:0)
[08:39:12.207] Timer tick, asking Buildbucket for the build status
[08:39:12.850] Build 8912954797918276848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:39:12.850] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:13:0) after 1m0s
[08:40:12.887] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:13:0)
[08:40:12.887] Timer tick, asking Buildbucket for the build status
[08:40:12.942] Build 8912954797918276848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:40:12.942] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:14:0) after 1m0s
[08:41:12.961] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:14:0)
[08:41:12.961] Timer tick, asking Buildbucket for the build status
[08:41:13.138] Build 8912954797918276848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:41:13.138] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:15:0) after 1m0s
[08:42:13.208] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:15:0)
[08:42:13.208] Timer tick, asking Buildbucket for the build status
[08:42:13.292] Build 8912954797918276848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:42:13.292] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:16:0) after 1m0s
[08:43:13.311] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:16:0)
[08:43:13.311] Timer tick, asking Buildbucket for the build status
[08:43:13.487] Build 8912954797918276848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:43:13.487] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:17:0) after 1m0s
[08:44:13.475] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:17:0)
[08:44:13.475] Timer tick, asking Buildbucket for the build status
[08:44:13.543] Build 8912954797918276848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:44:13.543] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:18:0) after 1m0s
[08:45:13.568] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:18:0)
[08:45:13.568] Timer tick, asking Buildbucket for the build status
[08:45:14.030] Build 8912954797918276848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:45:14.030] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:19:0) after 1m0s
[08:46:14.051] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:19:0)
[08:46:14.051] Timer tick, asking Buildbucket for the build status
[08:46:14.104] Build 8912954797918276848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:46:14.104] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:20:0) after 1m0s
[08:47:14.125] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:20:0)
[08:47:14.125] Timer tick, asking Buildbucket for the build status
[08:47:14.249] Build 8912954797918276848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:47:14.249] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:21:0) after 1m0s
[08:48:14.292] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:21:0)
[08:48:14.292] Timer tick, asking Buildbucket for the build status
[08:48:14.625] Build 8912954797918276848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:48:14.625] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:22:0) after 1m0s
[08:49:14.688] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:22:0)
[08:49:14.688] Timer tick, asking Buildbucket for the build status
[08:49:14.728] Build 8912954797918276848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:49:14.728] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:23:0) after 1m0s
[08:50:14.788] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:23:0)
[08:50:14.788] Timer tick, asking Buildbucket for the build status
[08:50:14.956] Build 8912954797918276848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:50:14.956] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:24:0) after 1m0s
[08:51:15.040] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:24:0)
[08:51:15.040] Timer tick, asking Buildbucket for the build status
[08:51:15.096] Build 8912954797918276848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:51:15.096] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:25:0) after 1m0s
[08:52:15.129] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:25:0)
[08:52:15.129] Timer tick, asking Buildbucket for the build status
[08:52:15.192] Build 8912954797918276848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:52:15.192] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:26:0) after 1m0s
[08:53:15.275] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:26:0)
[08:53:15.275] Timer tick, asking Buildbucket for the build status
[08:53:15.334] Build 8912954797918276848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:53:15.334] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:27:0) after 1m0s
[08:54:15.373] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:27:0)
[08:54:15.373] Timer tick, asking Buildbucket for the build status
[08:54:15.438] Build 8912954797918276848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:54:15.438] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:28:0) after 1m0s
[08:55:15.459] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:28:0)
[08:55:15.459] Timer tick, asking Buildbucket for the build status
[08:55:16.197] Build 8912954797918276848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:55:16.197] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:29:0) after 1m0s
[08:56:16.217] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:29:0)
[08:56:16.217] Timer tick, asking Buildbucket for the build status
[08:56:16.466] Build 8912954797918276848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:56:16.466] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:30:0) after 1m0s
[08:57:16.499] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:30:0)
[08:57:16.499] Timer tick, asking Buildbucket for the build status
[08:57:16.567] Build 8912954797918276848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:57:16.567] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:31:0) after 1m0s
[08:58:16.568] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:31:0)
[08:58:16.569] Timer tick, asking Buildbucket for the build status
[08:58:16.731] Build 8912954797918276848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:58:16.731] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:32:0) after 1m0s
[08:59:17.341] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:32:0)
[08:59:17.341] Timer tick, asking Buildbucket for the build status
[08:59:17.469] Build 8912954797918276848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:59:17.469] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:33:0) after 1m0s
[09:00:17.546] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:33:0)
[09:00:17.546] Timer tick, asking Buildbucket for the build status
[09:00:17.677] Build 8912954797918276848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:00:17.677] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:34:0) after 1m0s
[09:01:17.950] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:34:0)
[09:01:17.950] Timer tick, asking Buildbucket for the build status
[09:01:18.115] Build 8912954797918276848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:01:18.115] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:35:0) after 1m0s
[09:02:18.113] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:35:0)
[09:02:18.113] Timer tick, asking Buildbucket for the build status
[09:02:18.195] Build 8912954797918276848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:02:18.195] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:36:0) after 1m0s
[09:03:18.168] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:36:0)
[09:03:18.168] Timer tick, asking Buildbucket for the build status
[09:03:18.502] Build 8912954797918276848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:03:18.502] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:37:0) after 1m0s
[09:04:18.590] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:37:0)
[09:04:18.590] Timer tick, asking Buildbucket for the build status
[09:04:18.826] Build 8912954797918276848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:04:18.826] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:38:0) after 1m0s
[09:05:18.810] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:38:0)
[09:05:18.810] Timer tick, asking Buildbucket for the build status
[09:05:19.016] Build 8912954797918276848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:05:19.016] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:39:0) after 1m0s
[09:06:19.055] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:39:0)
[09:06:19.055] Timer tick, asking Buildbucket for the build status
[09:06:19.225] Build 8912954797918276848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:06:19.225] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:40:0) after 1m0s
[09:07:19.307] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:40:0)
[09:07:19.307] Timer tick, asking Buildbucket for the build status
[09:07:19.844] Build 8912954797918276848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:07:19.844] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:41:0) after 1m0s
[09:08:19.864] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:41:0)
[09:08:19.864] Timer tick, asking Buildbucket for the build status
[09:08:20.156] Build 8912954797918276848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:08:20.156] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:42:0) after 1m0s
[09:09:20.185] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:42:0)
[09:09:20.185] Timer tick, asking Buildbucket for the build status
[09:09:20.315] Build 8912954797918276848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:09:20.315] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:43:0) after 1m0s
[09:10:20.328] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:43:0)
[09:10:20.328] Timer tick, asking Buildbucket for the build status
[09:10:20.604] Build 8912954797918276848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:10:20.604] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:44:0) after 1m0s
[09:11:20.611] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:44:0)
[09:11:20.611] Timer tick, asking Buildbucket for the build status
[09:11:20.700] Build 8912954797918276848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:11:20.700] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:45:0) after 1m0s
[09:12:20.689] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:45:0)
[09:12:20.689] Timer tick, asking Buildbucket for the build status
[09:12:20.734] Build 8912954797918276848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:12:20.734] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:46:0) after 1m0s
[09:13:20.756] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:46:0)
[09:13:20.756] Timer tick, asking Buildbucket for the build status
[09:13:20.815] Build 8912954797918276848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:13:20.815] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:47:0) after 1m0s
[09:14:20.822] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:47:0)
[09:14:20.822] Timer tick, asking Buildbucket for the build status
[09:14:21.216] Build 8912954797918276848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:14:21.216] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:48:0) after 1m0s
[09:15:21.208] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:48:0)
[09:15:21.208] Timer tick, asking Buildbucket for the build status
[09:15:21.335] Build 8912954797918276848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:15:21.335] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:49:0) after 1m0s
[09:16:21.355] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:49:0)
[09:16:21.355] Timer tick, asking Buildbucket for the build status
[09:16:21.430] Build 8912954797918276848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:16:21.430] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:50:0) after 1m0s
[09:17:21.626] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:50:0)
[09:17:21.626] Timer tick, asking Buildbucket for the build status
[09:17:21.720] Build 8912954797918276848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:17:21.720] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:51:0) after 1m0s
[09:18:21.740] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:51:0)
[09:18:21.740] Timer tick, asking Buildbucket for the build status
[09:18:21.796] Build 8912954797918276848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:18:21.796] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:52:0) after 1m0s
[09:19:21.825] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:52:0)
[09:19:21.825] Timer tick, asking Buildbucket for the build status
[09:19:21.896] Build 8912954797918276848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:19:21.896] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:53:0) after 1m0s
[09:20:22.095] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:53:0)
[09:20:22.095] Timer tick, asking Buildbucket for the build status
[09:20:22.205] Build 8912954797918276848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:20:22.205] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:54:0) after 1m0s
[09:21:22.197] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:54:0)
[09:21:22.197] Timer tick, asking Buildbucket for the build status
[09:21:22.282] Build 8912954797918276848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:21:22.282] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:55:0) after 1m0s
[09:22:22.403] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:55:0)
[09:22:22.403] Timer tick, asking Buildbucket for the build status
[09:22:23.941] Build 8912954797918276848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:22:23.941] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:56:0) after 1m0s
[09:23:23.965] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:56:0)
[09:23:23.965] Timer tick, asking Buildbucket for the build status
[09:23:24.205] Build 8912954797918276848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:23:24.205] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:57:0) after 1m0s
[09:24:24.182] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:57:0)
[09:24:24.182] Timer tick, asking Buildbucket for the build status
[09:24:24.261] Build 8912954797918276848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:24:24.261] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:58:0) after 1m0s
[09:25:24.255] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:58:0)
[09:25:24.255] Timer tick, asking Buildbucket for the build status
[09:25:24.347] Build 8912954797918276848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:25:24.347] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:59:0) after 1m0s
[09:26:24.324] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:59:0)
[09:26:24.324] Timer tick, asking Buildbucket for the build status
[09:26:24.398] Build 8912954797918276848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:26:24.398] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:60:0) after 1m0s
[09:27:24.458] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:60:0)
[09:27:24.458] Timer tick, asking Buildbucket for the build status
[09:27:25.110] Build 8912954797918276848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:27:25.110] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:61:0) after 1m0s
[09:28:25.135] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:61:0)
[09:28:25.135] Timer tick, asking Buildbucket for the build status
[09:28:25.236] Build 8912954797918276848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:28:25.236] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:62:0) after 1m0s
[09:29:25.257] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:62:0)
[09:29:25.257] Timer tick, asking Buildbucket for the build status
[09:29:25.362] Build 8912954797918276848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:29:25.362] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:63:0) after 1m0s
[09:30:25.410] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:63:0)
[09:30:25.410] Timer tick, asking Buildbucket for the build status
[09:30:25.730] Build 8912954797918276848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:30:25.730] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:64:0) after 1m0s
[09:31:25.761] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:64:0)
[09:31:25.761] Timer tick, asking Buildbucket for the build status
[09:31:25.818] Build 8912954797918276848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:31:25.818] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:65:0) after 1m0s
[09:32:25.841] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:65:0)
[09:32:25.841] Timer tick, asking Buildbucket for the build status
[09:32:26.001] Build 8912954797918276848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:32:26.001] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:66:0) after 1m0s
[09:33:25.986] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:66:0)
[09:33:25.986] Timer tick, asking Buildbucket for the build status
[09:33:26.119] Build 8912954797918276848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:33:26.119] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:67:0) after 1m0s
[09:34:26.342] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:67:0)
[09:34:26.342] Timer tick, asking Buildbucket for the build status
[09:34:26.448] Build 8912954797918276848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:34:26.448] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:68:0) after 1m0s
[09:35:26.495] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:68:0)
[09:35:26.495] Timer tick, asking Buildbucket for the build status
[09:35:26.608] Build 8912954797918276848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:35:26.608] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:69:0) after 1m0s
[09:36:26.624] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:69:0)
[09:36:26.624] Timer tick, asking Buildbucket for the build status
[09:36:26.914] Build 8912954797918276848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:36:26.914] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:70:0) after 1m0s
[09:37:26.951] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:70:0)
[09:37:26.951] Timer tick, asking Buildbucket for the build status
[09:37:27.456] Build 8912954797918276848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:37:27.456] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:71:0) after 1m0s
[09:38:27.683] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:71:0)
[09:38:27.683] Timer tick, asking Buildbucket for the build status
[09:38:27.874] Build 8912954797918276848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:38:27.875] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:72:0) after 1m0s
[09:39:27.972] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:72:0)
[09:39:27.972] Timer tick, asking Buildbucket for the build status
[09:39:28.067] Build 8912954797918276848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:39:28.067] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:73:0) after 1m0s
[09:40:28.093] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:73:0)
[09:40:28.093] Timer tick, asking Buildbucket for the build status
[09:40:28.245] Build 8912954797918276848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:40:28.245] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:74:0) after 1m0s
[09:41:28.267] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:74:0)
[09:41:28.267] Timer tick, asking Buildbucket for the build status
[09:41:28.666] Build 8912954797918276848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:41:28.666] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:75:0) after 1m0s
[09:42:28.806] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:75:0)
[09:42:28.806] Timer tick, asking Buildbucket for the build status
[09:42:29.129] Build 8912954797918276848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:42:29.129] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:76:0) after 1m0s
[09:43:29.117] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:76:0)
[09:43:29.117] Timer tick, asking Buildbucket for the build status
[09:43:29.165] Build 8912954797918276848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:43:29.165] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:77:0) after 1m0s
[09:44:29.187] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:77:0)
[09:44:29.187] Timer tick, asking Buildbucket for the build status
[09:44:29.285] Build 8912954797918276848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:44:29.285] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:78:0) after 1m0s
[09:45:29.354] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:78:0)
[09:45:29.354] Timer tick, asking Buildbucket for the build status
[09:45:29.450] Build 8912954797918276848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:45:29.450] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:79:0) after 1m0s
[09:46:29.501] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:79:0)
[09:46:29.501] Timer tick, asking Buildbucket for the build status
[09:46:29.562] Build 8912954797918276848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:46:29.562] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:80:0) after 1m0s
[09:47:29.577] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:80:0)
[09:47:29.577] Timer tick, asking Buildbucket for the build status
[09:47:29.649] Build 8912954797918276848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:47:29.649] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:81:0) after 1m0s
[09:48:29.674] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:81:0)
[09:48:29.674] Timer tick, asking Buildbucket for the build status
[09:48:29.757] Build 8912954797918276848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:48:29.757] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:82:0) after 1m0s
[09:49:29.778] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:82:0)
[09:49:29.778] Timer tick, asking Buildbucket for the build status
[09:49:29.868] Build 8912954797918276848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:49:29.868] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:83:0) after 1m0s
[09:50:29.910] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:83:0)
[09:50:29.910] Timer tick, asking Buildbucket for the build status
[09:50:30.469] Build 8912954797918276848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:50:30.469] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:84:0) after 1m0s
[09:51:30.493] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:84:0)
[09:51:30.493] Timer tick, asking Buildbucket for the build status
[09:51:30.556] Build 8912954797918276848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:51:30.556] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:85:0) after 1m0s
[09:52:30.548] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:85:0)
[09:52:30.548] Timer tick, asking Buildbucket for the build status
[09:52:31.176] Build 8912954797918276848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:52:31.176] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:86:0) after 1m0s
[09:53:31.197] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:86:0)
[09:53:31.197] Timer tick, asking Buildbucket for the build status
[09:53:31.833] Build 8912954797918276848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:53:31.833] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:87:0) after 1m0s
[09:54:31.851] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:87:0)
[09:54:31.851] Timer tick, asking Buildbucket for the build status
[09:54:31.947] Build 8912954797918276848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:54:31.947] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:88:0) after 1m0s
[09:55:31.968] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:88:0)
[09:55:31.968] Timer tick, asking Buildbucket for the build status
[09:55:32.068] Build 8912954797918276848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:55:32.068] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:89:0) after 1m0s
[09:56:32.105] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:89:0)
[09:56:32.105] Timer tick, asking Buildbucket for the build status
[09:56:32.190] Build 8912954797918276848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:56:32.190] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:90:0) after 1m0s
[09:57:32.213] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:90:0)
[09:57:32.213] Timer tick, asking Buildbucket for the build status
[09:57:32.311] Build 8912954797918276848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:57:32.311] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:91:0) after 1m0s
[09:58:32.346] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:91:0)
[09:58:32.347] Timer tick, asking Buildbucket for the build status
[09:58:32.455] Build 8912954797918276848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:58:32.455] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:92:0) after 1m0s
[09:59:32.482] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:92:0)
[09:59:32.482] Timer tick, asking Buildbucket for the build status
[09:59:32.528] Build 8912954797918276848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:59:32.528] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:93:0) after 1m0s
[10:00:32.528] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:93:0)
[10:00:32.528] Timer tick, asking Buildbucket for the build status
[10:00:32.639] Build 8912954797918276848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:00:32.639] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:94:0) after 1m0s
[10:01:32.663] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:94:0)
[10:01:32.663] Timer tick, asking Buildbucket for the build status
[10:01:32.743] Build 8912954797918276848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:01:32.743] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:95:0) after 1m0s
[10:02:32.772] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:95:0)
[10:02:32.772] Timer tick, asking Buildbucket for the build status
[10:02:32.847] Build 8912954797918276848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:02:32.847] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:96:0) after 1m0s
[10:03:32.899] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:96:0)
[10:03:32.899] Timer tick, asking Buildbucket for the build status
[10:03:33.046] Build 8912954797918276848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:03:33.046] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:97:0) after 1m0s
[10:04:33.077] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:97:0)
[10:04:33.077] Timer tick, asking Buildbucket for the build status
[10:04:33.154] Build 8912954797918276848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:04:33.154] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:98:0) after 1m0s
[10:05:33.212] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:98:0)
[10:05:33.212] Timer tick, asking Buildbucket for the build status
[10:05:34.017] Build 8912954797918276848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:05:34.017] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:99:0) after 1m0s
[10:06:34.085] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:99:0)
[10:06:34.085] Timer tick, asking Buildbucket for the build status
[10:06:34.407] Build 8912954797918276848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:06:34.407] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:100:0) after 1m0s
[10:07:34.431] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:100:0)
[10:07:34.431] Timer tick, asking Buildbucket for the build status
[10:07:34.570] Build 8912954797918276848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:07:34.570] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:101:0) after 1m0s
[10:08:34.578] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:101:0)
[10:08:34.578] Timer tick, asking Buildbucket for the build status
[10:08:34.703] Build 8912954797918276848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:08:34.703] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:102:0) after 1m0s
[10:09:34.712] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:102:0)
[10:09:34.712] Timer tick, asking Buildbucket for the build status
[10:09:34.835] Build 8912954797918276848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:09:34.835] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:103:0) after 1m0s
[10:10:34.855] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:103:0)
[10:10:34.855] Timer tick, asking Buildbucket for the build status
[10:10:35.091] Build 8912954797918276848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:10:35.091] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:104:0) after 1m0s
[10:11:35.115] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:104:0)
[10:11:35.115] Timer tick, asking Buildbucket for the build status
[10:11:35.444] Build 8912954797918276848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:11:35.444] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:105:0) after 1m0s
[10:12:35.486] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:105:0)
[10:12:35.486] Timer tick, asking Buildbucket for the build status
[10:12:35.555] Build 8912954797918276848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:12:35.555] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:106:0) after 1m0s
[10:13:35.547] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:106:0)
[10:13:35.547] Timer tick, asking Buildbucket for the build status
[10:13:35.657] Build 8912954797918276848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:13:35.657] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:107:0) after 1m0s
[10:14:35.677] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:107:0)
[10:14:35.677] Timer tick, asking Buildbucket for the build status
[10:14:35.778] Build 8912954797918276848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:14:35.778] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:108:0) after 1m0s
[10:15:35.806] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:108:0)
[10:15:35.806] Timer tick, asking Buildbucket for the build status
[10:15:35.864] Build 8912954797918276848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:15:35.864] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:109:0) after 1m0s
[10:16:35.888] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:109:0)
[10:16:35.888] Timer tick, asking Buildbucket for the build status
[10:16:35.978] Build 8912954797918276848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:16:35.978] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:110:0) after 1m0s
[10:17:35.979] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:110:0)
[10:17:35.979] Timer tick, asking Buildbucket for the build status
[10:17:36.097] Build 8912954797918276848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:17:36.097] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:111:0) after 1m0s
[10:18:36.143] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:111:0)
[10:18:36.143] Timer tick, asking Buildbucket for the build status
[10:18:36.386] Build 8912954797918276848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:18:36.386] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:112:0) after 1m0s
[10:19:36.419] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:112:0)
[10:19:36.419] Timer tick, asking Buildbucket for the build status
[10:19:37.158] Build 8912954797918276848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:19:37.158] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:113:0) after 1m0s
[10:20:37.214] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:113:0)
[10:20:37.214] Timer tick, asking Buildbucket for the build status
[10:20:37.830] Build 8912954797918276848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:20:37.830] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:114:0) after 1m0s
[10:21:37.835] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:114:0)
[10:21:37.835] Timer tick, asking Buildbucket for the build status
[10:21:38.126] Build 8912954797918276848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:21:38.126] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:115:0) after 1m0s
[10:22:38.178] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:115:0)
[10:22:38.178] Timer tick, asking Buildbucket for the build status
[10:22:38.572] Build 8912954797918276848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:22:38.572] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:116:0) after 1m0s
[10:23:38.595] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:116:0)
[10:23:38.595] Timer tick, asking Buildbucket for the build status
[10:23:38.641] Build 8912954797918276848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:23:38.641] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:117:0) after 1m0s
[10:24:38.669] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:117:0)
[10:24:38.669] Timer tick, asking Buildbucket for the build status
[10:24:38.770] Build 8912954797918276848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:24:38.770] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:118:0) after 1m0s
[10:25:38.792] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:118:0)
[10:25:38.792] Timer tick, asking Buildbucket for the build status
[10:25:38.841] Build 8912954797918276848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:25:38.841] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:119:0) after 1m0s
[10:26:38.889] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:119:0)
[10:26:38.889] Timer tick, asking Buildbucket for the build status
[10:26:38.967] Build 8912954797918276848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:26:38.968] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:120:0) after 1m0s
[10:27:38.971] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:120:0)
[10:27:38.971] Timer tick, asking Buildbucket for the build status
[10:27:39.037] Build 8912954797918276848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:27:39.037] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:121:0) after 1m0s
[10:28:39.062] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:121:0)
[10:28:39.062] Timer tick, asking Buildbucket for the build status
[10:28:39.382] Build 8912954797918276848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:28:39.382] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:122:0) after 1m0s
[10:29:39.392] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:122:0)
[10:29:39.392] Timer tick, asking Buildbucket for the build status
[10:29:39.457] Build 8912954797918276848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:29:39.457] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:123:0) after 1m0s
[10:30:39.480] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:123:0)
[10:30:39.480] Timer tick, asking Buildbucket for the build status
[10:30:39.708] Build 8912954797918276848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:30:39.708] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078384859995434880:124:0) after 1m0s
[10:31:33.734] Received PubSub notification, asking Buildbucket for the build status
[10:31:33.820] Build 8912954797918276848: status "COMPLETED", result "CANCELED", failure_reason "", cancelation_reason "TIMEOUT"
[10:31:33.820] Buildbucket build:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary_preference": "AUTO",
"cancelation_reason": "TIMEOUT",
"completed_ts": "1558348293077566",
"created_by": "project:emscripten-releases",
"created_ts": "1558340948143859",
"id": "8912954797918276848",
"parameters_json": "{\"builder_name\": \"win\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"6a6438959bc140ebd713456218c4edbfc5e0d876\"}}",
"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-aa9u\", \"branch\": \"refs/heads/master\", \"buildername\": \"win\", \"got_revision\": \"6a6438959bc140ebd713456218c4edbfc5e0d876\", \"path_config\": \"generic\", \"recipe\": \"emscripten_releases\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"6a6438959bc140ebd713456218c4edbfc5e0d876\"}, \"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-aa9u\"], \"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\": [\"4302-e8210c4\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-b\"]}}}",
"service_account": "emscripten-releases-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1558340959788509",
"status": "COMPLETED",
"status_changed_ts": "1558348293417018",
"tags": [
"builder:win",
"buildset:commit/git/6a6438959bc140ebd713456218c4edbfc5e0d876",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/6a6438959bc140ebd713456218c4edbfc5e0d876",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9078384859995434880",
"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/8912954797918276848/+/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:44ed2d5bf18d8010",
"user_agent:luci-scheduler"
],
"updated_ts": "1558348293439900",
"url": "https://ci.chromium.org/b/8912954797918276848",
"utcnow_ts": "1558348293800612"
}
}
[10:31:33.820] Invocation finished in 2h2m27.041125837s with status FAILED