[22:20:33.198] New invocation is queued and will start shortly
[22:20:34.349] Starting the invocation (attempt 1)
[22:20:34.384] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[22:20:34.385] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[22:20:34.385] Buildbucket request:
{
"bucket": "luci.emscripten-releases.ci",
"client_operation_id": "9018357358552764560",
"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\":\"b5a1b1a2b8858df317dcb1b76f237a07af7ca1e6\"},\"id\":\"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@b5a1b1a2b8858df317dcb1b76f237a07af7ca1e6\",\"title\":\"b5a1b1a2b8858df317dcb1b76f237a07af7ca1e6\",\"url\":\"https://chromium.googlesource.com/emscripten-releases/+/b5a1b1a2b8858df317dcb1b76f237a07af7ca1e6\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/emscripten-releases\",\"revision\":\"b5a1b1a2b8858df317dcb1b76f237a07af7ca1e6\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win",
"scheduler_invocation_id:9018357358552764560",
"scheduler_job_id:emscripten-releases/win",
"user_agent:luci-scheduler",
"buildset:commit/git/b5a1b1a2b8858df317dcb1b76f237a07af7ca1e6",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/b5a1b1a2b8858df317dcb1b76f237a07af7ca1e6",
"gitiles_ref:refs/heads/master"
]
}
[22:20:34.847] Buildbucket response:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary_preference": "PROD",
"created_by": "project:emscripten-releases",
"created_ts": "1615587634514791",
"id": "8852927296509748816",
"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\": \"b5a1b1a2b8858df317dcb1b76f237a07af7ca1e6\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@b5a1b1a2b8858df317dcb1b76f237a07af7ca1e6\", \"title\": \"b5a1b1a2b8858df317dcb1b76f237a07af7ca1e6\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/b5a1b1a2b8858df317dcb1b76f237a07af7ca1e6\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"b5a1b1a2b8858df317dcb1b76f237a07af7ca1e6\"}}",
"project": "emscripten-releases",
"result_details_json": "{\"properties\": {}}",
"service_account": "emscripten-releases-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1615587634729053",
"tags": [
"builder:win",
"buildset:commit/git/b5a1b1a2b8858df317dcb1b76f237a07af7ca1e6",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/b5a1b1a2b8858df317dcb1b76f237a07af7ca1e6",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9018357358552764560",
"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/8852927296509748816/+/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": "1615587634729156",
"url": "https://ci.chromium.org/b/8852927296509748816",
"utcnow_ts": "1615587634840093"
}
}
[22:20:34.847] Task URL: https://ci.chromium.org/b/8852927296509748816
[22:20:34.847] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:2:0) after 1m0s
[22:21:34.870] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:2:0)
[22:21:34.870] Timer tick, asking Buildbucket for the build status
[22:21:34.956] Build 8852927296509748816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:21:34.956] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:3:0) after 1m0s
[22:22:35.035] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:3:0)
[22:22:35.035] Timer tick, asking Buildbucket for the build status
[22:22:35.098] Build 8852927296509748816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:22:35.098] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:4:0) after 1m0s
[22:23:35.303] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:4:0)
[22:23:35.303] Timer tick, asking Buildbucket for the build status
[22:23:35.383] Build 8852927296509748816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:23:35.383] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:5:0) after 1m0s
[22:24:35.467] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:5:0)
[22:24:35.467] Timer tick, asking Buildbucket for the build status
[22:24:35.533] Build 8852927296509748816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:24:35.533] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:6:0) after 1m0s
[22:25:35.599] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:6:0)
[22:25:35.599] Timer tick, asking Buildbucket for the build status
[22:25:35.676] Build 8852927296509748816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:25:35.676] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:7:0) after 1m0s
[22:26:35.709] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:7:0)
[22:26:35.709] Timer tick, asking Buildbucket for the build status
[22:26:35.779] Build 8852927296509748816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:26:35.779] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:8:0) after 1m0s
[22:27:35.902] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:8:0)
[22:27:35.902] Timer tick, asking Buildbucket for the build status
[22:27:35.972] Build 8852927296509748816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:27:35.972] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:9:0) after 1m0s
[22:28:36.097] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:9:0)
[22:28:36.097] Timer tick, asking Buildbucket for the build status
[22:28:36.162] Build 8852927296509748816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:28:36.162] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:10:0) after 1m0s
[22:29:36.239] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:10:0)
[22:29:36.239] Timer tick, asking Buildbucket for the build status
[22:29:36.400] Build 8852927296509748816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:29:36.400] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:11:0) after 1m0s
[22:30:36.464] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:11:0)
[22:30:36.464] Timer tick, asking Buildbucket for the build status
[22:30:36.575] Build 8852927296509748816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:30:36.575] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:12:0) after 1m0s
[22:31:36.577] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:12:0)
[22:31:36.577] Timer tick, asking Buildbucket for the build status
[22:31:36.638] Build 8852927296509748816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:31:36.638] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:13:0) after 1m0s
[22:32:36.854] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:13:0)
[22:32:36.854] Timer tick, asking Buildbucket for the build status
[22:32:36.909] Build 8852927296509748816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:32:36.909] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:14:0) after 1m0s
[22:33:37.021] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:14:0)
[22:33:37.021] Timer tick, asking Buildbucket for the build status
[22:33:37.163] Build 8852927296509748816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:33:37.163] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:15:0) after 1m0s
[22:34:37.259] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:15:0)
[22:34:37.259] Timer tick, asking Buildbucket for the build status
[22:34:37.339] Build 8852927296509748816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:34:37.339] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:16:0) after 1m0s
[22:35:37.394] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:16:0)
[22:35:37.394] Timer tick, asking Buildbucket for the build status
[22:35:37.705] Build 8852927296509748816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:35:37.705] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:17:0) after 1m0s
[22:36:37.855] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:17:0)
[22:36:37.855] Timer tick, asking Buildbucket for the build status
[22:36:37.922] Build 8852927296509748816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:36:37.922] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:18:0) after 1m0s
[22:37:38.004] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:18:0)
[22:37:38.004] Timer tick, asking Buildbucket for the build status
[22:37:38.211] Build 8852927296509748816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:37:38.211] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:19:0) after 1m0s
[22:38:38.277] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:19:0)
[22:38:38.277] Timer tick, asking Buildbucket for the build status
[22:38:38.343] Build 8852927296509748816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:38:38.343] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:20:0) after 1m0s
[22:39:38.524] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:20:0)
[22:39:38.524] Timer tick, asking Buildbucket for the build status
[22:39:38.589] Build 8852927296509748816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:39:38.589] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:21:0) after 1m0s
[22:40:38.701] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:21:0)
[22:40:38.701] Timer tick, asking Buildbucket for the build status
[22:40:39.143] Build 8852927296509748816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:40:39.143] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:22:0) after 1m0s
[22:41:39.217] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:22:0)
[22:41:39.217] Timer tick, asking Buildbucket for the build status
[22:41:39.288] Build 8852927296509748816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:41:39.288] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:23:0) after 1m0s
[22:42:39.393] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:23:0)
[22:42:39.393] Timer tick, asking Buildbucket for the build status
[22:42:39.509] Build 8852927296509748816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:42:39.509] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:24:0) after 1m0s
[22:43:39.655] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:24:0)
[22:43:39.655] Timer tick, asking Buildbucket for the build status
[22:43:39.742] Build 8852927296509748816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:43:39.742] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:25:0) after 1m0s
[22:44:39.812] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:25:0)
[22:44:39.812] Timer tick, asking Buildbucket for the build status
[22:44:39.867] Build 8852927296509748816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:44:39.867] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:26:0) after 1m0s
[22:45:39.992] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:26:0)
[22:45:39.992] Timer tick, asking Buildbucket for the build status
[22:45:40.085] Build 8852927296509748816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:45:40.085] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:27:0) after 1m0s
[22:46:40.107] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:27:0)
[22:46:40.107] Timer tick, asking Buildbucket for the build status
[22:46:40.178] Build 8852927296509748816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:46:40.178] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:28:0) after 1m0s
[22:47:40.253] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:28:0)
[22:47:40.253] Timer tick, asking Buildbucket for the build status
[22:47:40.392] Build 8852927296509748816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:47:40.392] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:29:0) after 1m0s
[22:48:40.484] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:29:0)
[22:48:40.484] Timer tick, asking Buildbucket for the build status
[22:48:40.552] Build 8852927296509748816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:48:40.552] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:30:0) after 1m0s
[22:49:40.607] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:30:0)
[22:49:40.607] Timer tick, asking Buildbucket for the build status
[22:49:40.812] Build 8852927296509748816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:49:40.812] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:31:0) after 1m0s
[22:50:40.831] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:31:0)
[22:50:40.831] Timer tick, asking Buildbucket for the build status
[22:50:40.966] Build 8852927296509748816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:50:40.966] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:32:0) after 1m0s
[22:51:41.056] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:32:0)
[22:51:41.056] Timer tick, asking Buildbucket for the build status
[22:51:41.129] Build 8852927296509748816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:51:41.129] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:33:0) after 1m0s
[22:52:41.231] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:33:0)
[22:52:41.231] Timer tick, asking Buildbucket for the build status
[22:52:41.317] Build 8852927296509748816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:52:41.317] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:34:0) after 1m0s
[22:53:41.393] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:34:0)
[22:53:41.393] Timer tick, asking Buildbucket for the build status
[22:53:41.544] Build 8852927296509748816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:53:41.544] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:35:0) after 1m0s
[22:54:41.616] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:35:0)
[22:54:41.616] Timer tick, asking Buildbucket for the build status
[22:54:41.689] Build 8852927296509748816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:54:41.689] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:36:0) after 1m0s
[22:55:41.704] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:36:0)
[22:55:41.704] Timer tick, asking Buildbucket for the build status
[22:55:41.847] Build 8852927296509748816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:55:41.847] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:37:0) after 1m0s
[22:56:41.836] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:37:0)
[22:56:41.836] Timer tick, asking Buildbucket for the build status
[22:56:41.901] Build 8852927296509748816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:56:41.901] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:38:0) after 1m0s
[22:57:41.932] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:38:0)
[22:57:41.932] Timer tick, asking Buildbucket for the build status
[22:57:41.997] Build 8852927296509748816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:57:41.998] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:39:0) after 1m0s
[22:58:42.026] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:39:0)
[22:58:42.026] Timer tick, asking Buildbucket for the build status
[22:58:42.087] Build 8852927296509748816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:58:42.087] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:40:0) after 1m0s
[22:59:42.111] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:40:0)
[22:59:42.111] Timer tick, asking Buildbucket for the build status
[22:59:42.499] Build 8852927296509748816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:59:42.499] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:41:0) after 1m0s
[23:00:42.519] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:41:0)
[23:00:42.519] Timer tick, asking Buildbucket for the build status
[23:00:42.606] Build 8852927296509748816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:00:42.606] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:42:0) after 1m0s
[23:01:42.725] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:42:0)
[23:01:42.725] Timer tick, asking Buildbucket for the build status
[23:01:42.831] Build 8852927296509748816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:01:42.831] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:43:0) after 1m0s
[23:02:42.887] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:43:0)
[23:02:42.887] Timer tick, asking Buildbucket for the build status
[23:02:42.956] Build 8852927296509748816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:02:42.956] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:44:0) after 1m0s
[23:03:43.015] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:44:0)
[23:03:43.015] Timer tick, asking Buildbucket for the build status
[23:03:43.082] Build 8852927296509748816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:03:43.082] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:45:0) after 1m0s
[23:04:43.053] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:45:0)
[23:04:43.053] Timer tick, asking Buildbucket for the build status
[23:04:43.121] Build 8852927296509748816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:04:43.121] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:46:0) after 1m0s
[23:05:43.186] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:46:0)
[23:05:43.186] Timer tick, asking Buildbucket for the build status
[23:05:43.413] Build 8852927296509748816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:05:43.413] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:47:0) after 1m0s
[23:06:43.447] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:47:0)
[23:06:43.447] Timer tick, asking Buildbucket for the build status
[23:06:43.513] Build 8852927296509748816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:06:43.513] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:48:0) after 1m0s
[23:07:43.538] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:48:0)
[23:07:43.538] Timer tick, asking Buildbucket for the build status
[23:07:43.751] Build 8852927296509748816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:07:43.751] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:49:0) after 1m0s
[23:08:43.835] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:49:0)
[23:08:43.835] Timer tick, asking Buildbucket for the build status
[23:08:43.908] Build 8852927296509748816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:08:43.908] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:50:0) after 1m0s
[23:09:43.975] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:50:0)
[23:09:43.975] Timer tick, asking Buildbucket for the build status
[23:09:44.124] Build 8852927296509748816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:09:44.124] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:51:0) after 1m0s
[23:10:44.177] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:51:0)
[23:10:44.177] Timer tick, asking Buildbucket for the build status
[23:10:44.355] Build 8852927296509748816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:10:44.355] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:52:0) after 1m0s
[23:11:44.402] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:52:0)
[23:11:44.402] Timer tick, asking Buildbucket for the build status
[23:11:44.476] Build 8852927296509748816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:11:44.476] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:53:0) after 1m0s
[23:12:44.466] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:53:0)
[23:12:44.466] Timer tick, asking Buildbucket for the build status
[23:12:44.536] Build 8852927296509748816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:12:44.536] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:54:0) after 1m0s
[23:13:44.562] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:54:0)
[23:13:44.562] Timer tick, asking Buildbucket for the build status
[23:13:44.629] Build 8852927296509748816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:13:44.629] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:55:0) after 1m0s
[23:14:44.659] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:55:0)
[23:14:44.659] Timer tick, asking Buildbucket for the build status
[23:14:44.805] Build 8852927296509748816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:14:44.805] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:56:0) after 1m0s
[23:15:44.866] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:56:0)
[23:15:44.866] Timer tick, asking Buildbucket for the build status
[23:15:45.010] Build 8852927296509748816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:15:45.010] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:57:0) after 1m0s
[23:16:45.138] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:57:0)
[23:16:45.138] Timer tick, asking Buildbucket for the build status
[23:16:45.210] Build 8852927296509748816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:16:45.210] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:58:0) after 1m0s
[23:17:45.338] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:58:0)
[23:17:45.338] Timer tick, asking Buildbucket for the build status
[23:17:45.408] Build 8852927296509748816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:17:45.408] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:59:0) after 1m0s
[23:18:45.465] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:59:0)
[23:18:45.465] Timer tick, asking Buildbucket for the build status
[23:18:45.531] Build 8852927296509748816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:18:45.531] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:60:0) after 1m0s
[23:19:45.554] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:60:0)
[23:19:45.554] Timer tick, asking Buildbucket for the build status
[23:19:45.608] Build 8852927296509748816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:19:45.608] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:61:0) after 1m0s
[23:20:45.660] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:61:0)
[23:20:45.660] Timer tick, asking Buildbucket for the build status
[23:20:45.749] Build 8852927296509748816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:20:45.749] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:62:0) after 1m0s
[23:21:45.842] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:62:0)
[23:21:45.842] Timer tick, asking Buildbucket for the build status
[23:21:45.904] Build 8852927296509748816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:21:45.905] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:63:0) after 1m0s
[23:22:45.949] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:63:0)
[23:22:45.950] Timer tick, asking Buildbucket for the build status
[23:22:46.022] Build 8852927296509748816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:22:46.022] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:64:0) after 1m0s
[23:23:46.005] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:64:0)
[23:23:46.005] Timer tick, asking Buildbucket for the build status
[23:23:46.130] Build 8852927296509748816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:23:46.131] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:65:0) after 1m0s
[23:24:46.180] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:65:0)
[23:24:46.180] Timer tick, asking Buildbucket for the build status
[23:24:46.529] Build 8852927296509748816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:24:46.529] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:66:0) after 1m0s
[23:25:46.591] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:66:0)
[23:25:46.591] Timer tick, asking Buildbucket for the build status
[23:25:46.663] Build 8852927296509748816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:25:46.663] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:67:0) after 1m0s
[23:26:46.704] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:67:0)
[23:26:46.704] Timer tick, asking Buildbucket for the build status
[23:26:46.838] Build 8852927296509748816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:26:46.838] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:68:0) after 1m0s
[23:27:46.892] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:68:0)
[23:27:46.892] Timer tick, asking Buildbucket for the build status
[23:27:46.959] Build 8852927296509748816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:27:46.959] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:69:0) after 1m0s
[23:28:47.022] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:69:0)
[23:28:47.022] Timer tick, asking Buildbucket for the build status
[23:28:47.347] Build 8852927296509748816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:28:47.347] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:70:0) after 1m0s
[23:29:47.433] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:70:0)
[23:29:47.433] Timer tick, asking Buildbucket for the build status
[23:29:47.546] Build 8852927296509748816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:29:47.546] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:71:0) after 1m0s
[23:30:47.610] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:71:0)
[23:30:47.610] Timer tick, asking Buildbucket for the build status
[23:30:47.679] Build 8852927296509748816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:30:47.679] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:72:0) after 1m0s
[23:31:47.776] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:72:0)
[23:31:47.776] Timer tick, asking Buildbucket for the build status
[23:31:47.841] Build 8852927296509748816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:31:47.841] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:73:0) after 1m0s
[23:32:47.924] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:73:0)
[23:32:47.924] Timer tick, asking Buildbucket for the build status
[23:32:48.005] Build 8852927296509748816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:32:48.005] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:74:0) after 1m0s
[23:33:48.213] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:74:0)
[23:33:48.213] Timer tick, asking Buildbucket for the build status
[23:33:48.342] Build 8852927296509748816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:33:48.342] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:75:0) after 1m0s
[23:34:14.600] Received PubSub notification, asking Buildbucket for the build status
[23:34:14.744] Build 8852927296509748816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:34:48.540] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:75:0)
[23:34:48.540] Timer tick, asking Buildbucket for the build status
[23:34:48.979] Build 8852927296509748816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:34:48.979] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:77:0) after 1m0s
[23:35:49.113] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:77:0)
[23:35:49.113] Timer tick, asking Buildbucket for the build status
[23:35:49.194] Build 8852927296509748816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:35:49.194] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:78:0) after 1m0s
[23:36:49.219] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:78:0)
[23:36:49.219] Timer tick, asking Buildbucket for the build status
[23:36:49.287] Build 8852927296509748816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:36:49.287] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:79:0) after 1m0s
[23:37:49.393] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:79:0)
[23:37:49.393] Timer tick, asking Buildbucket for the build status
[23:37:49.535] Build 8852927296509748816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:37:49.535] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:80:0) after 1m0s
[23:38:49.589] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:80:0)
[23:38:49.589] Timer tick, asking Buildbucket for the build status
[23:38:49.764] Build 8852927296509748816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:38:49.764] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:81:0) after 1m0s
[23:39:49.856] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:81:0)
[23:39:49.856] Timer tick, asking Buildbucket for the build status
[23:39:49.933] Build 8852927296509748816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:39:49.933] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:82:0) after 1m0s
[23:40:49.956] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:82:0)
[23:40:49.956] Timer tick, asking Buildbucket for the build status
[23:40:50.311] Build 8852927296509748816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:40:50.311] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:83:0) after 1m0s
[23:41:50.357] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:83:0)
[23:41:50.357] Timer tick, asking Buildbucket for the build status
[23:41:50.434] Build 8852927296509748816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:41:50.434] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:84:0) after 1m0s
[23:42:50.500] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:84:0)
[23:42:50.500] Timer tick, asking Buildbucket for the build status
[23:42:50.575] Build 8852927296509748816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:42:50.575] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:85:0) after 1m0s
[23:43:50.688] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:85:0)
[23:43:50.688] Timer tick, asking Buildbucket for the build status
[23:43:50.755] Build 8852927296509748816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:43:50.755] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:86:0) after 1m0s
[23:44:50.949] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:86:0)
[23:44:50.949] Timer tick, asking Buildbucket for the build status
[23:44:51.018] Build 8852927296509748816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:44:51.018] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:87:0) after 1m0s
[23:45:51.129] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:87:0)
[23:45:51.129] Timer tick, asking Buildbucket for the build status
[23:45:51.200] Build 8852927296509748816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:45:51.200] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:88:0) after 1m0s
[23:46:51.335] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:88:0)
[23:46:51.335] Timer tick, asking Buildbucket for the build status
[23:46:51.443] Build 8852927296509748816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:46:51.443] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:89:0) after 1m0s
[23:47:51.585] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:89:0)
[23:47:51.585] Timer tick, asking Buildbucket for the build status
[23:47:51.708] Build 8852927296509748816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:47:51.708] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:90:0) after 1m0s
[23:48:51.830] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:90:0)
[23:48:51.830] Timer tick, asking Buildbucket for the build status
[23:48:51.909] Build 8852927296509748816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:48:51.909] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:91:0) after 1m0s
[23:49:51.944] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:91:0)
[23:49:51.944] Timer tick, asking Buildbucket for the build status
[23:49:52.274] Build 8852927296509748816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:49:52.274] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:92:0) after 1m0s
[23:50:52.345] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:92:0)
[23:50:52.345] Timer tick, asking Buildbucket for the build status
[23:50:52.757] Build 8852927296509748816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:50:52.757] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:93:0) after 1m0s
[23:51:52.899] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:93:0)
[23:51:52.899] Timer tick, asking Buildbucket for the build status
[23:51:52.980] Build 8852927296509748816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:51:52.980] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:94:0) after 1m0s
[23:52:53.006] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:94:0)
[23:52:53.006] Timer tick, asking Buildbucket for the build status
[23:52:53.152] Build 8852927296509748816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:52:53.152] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:95:0) after 1m0s
[23:53:53.268] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:95:0)
[23:53:53.268] Timer tick, asking Buildbucket for the build status
[23:53:53.638] Build 8852927296509748816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:53:53.638] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:96:0) after 1m0s
[23:54:53.724] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:96:0)
[23:54:53.724] Timer tick, asking Buildbucket for the build status
[23:54:54.046] Build 8852927296509748816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:54:54.046] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:97:0) after 1m0s
[23:55:54.282] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:97:0)
[23:55:54.282] Timer tick, asking Buildbucket for the build status
[23:55:54.357] Build 8852927296509748816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:55:54.357] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:98:0) after 1m0s
[23:56:54.410] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:98:0)
[23:56:54.410] Timer tick, asking Buildbucket for the build status
[23:56:54.486] Build 8852927296509748816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:56:54.486] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:99:0) after 1m0s
[23:57:54.588] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:99:0)
[23:57:54.588] Timer tick, asking Buildbucket for the build status
[23:57:54.708] Build 8852927296509748816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:57:54.708] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:100:0) after 1m0s
[23:58:54.754] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:100:0)
[23:58:54.754] Timer tick, asking Buildbucket for the build status
[23:58:54.827] Build 8852927296509748816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:58:54.827] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:101:0) after 1m0s
[23:59:54.989] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:101:0)
[23:59:54.989] Timer tick, asking Buildbucket for the build status
[23:59:55.066] Build 8852927296509748816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:59:55.066] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:102:0) after 1m0s
[00:00:55.091] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:102:0)
[00:00:55.091] Timer tick, asking Buildbucket for the build status
[00:00:55.405] Build 8852927296509748816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:00:55.405] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:103:0) after 1m0s
[00:01:55.484] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:103:0)
[00:01:55.484] Timer tick, asking Buildbucket for the build status
[00:01:55.548] Build 8852927296509748816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:01:55.548] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:104:0) after 1m0s
[00:02:55.553] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:104:0)
[00:02:55.553] Timer tick, asking Buildbucket for the build status
[00:02:55.694] Build 8852927296509748816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:02:55.694] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:105:0) after 1m0s
[00:03:55.704] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:105:0)
[00:03:55.704] Timer tick, asking Buildbucket for the build status
[00:03:56.133] Build 8852927296509748816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:03:56.133] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:106:0) after 1m0s
[00:04:56.210] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:106:0)
[00:04:56.210] Timer tick, asking Buildbucket for the build status
[00:04:56.411] Build 8852927296509748816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:04:56.411] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:107:0) after 1m0s
[00:05:56.522] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:107:0)
[00:05:56.522] Timer tick, asking Buildbucket for the build status
[00:05:56.602] Build 8852927296509748816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:05:56.602] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:108:0) after 1m0s
[00:06:56.582] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:108:0)
[00:06:56.582] Timer tick, asking Buildbucket for the build status
[00:06:56.655] Build 8852927296509748816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:06:56.655] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:109:0) after 1m0s
[00:07:56.681] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:109:0)
[00:07:56.681] Timer tick, asking Buildbucket for the build status
[00:07:56.824] Build 8852927296509748816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:07:56.824] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:110:0) after 1m0s
[00:08:56.855] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:110:0)
[00:08:56.855] Timer tick, asking Buildbucket for the build status
[00:08:56.933] Build 8852927296509748816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:08:56.933] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:111:0) after 1m0s
[00:09:56.979] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:111:0)
[00:09:56.979] Timer tick, asking Buildbucket for the build status
[00:09:57.151] Build 8852927296509748816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:09:57.151] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:112:0) after 1m0s
[00:10:57.175] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:112:0)
[00:10:57.175] Timer tick, asking Buildbucket for the build status
[00:10:57.422] Build 8852927296509748816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:10:57.422] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:113:0) after 1m0s
[00:11:57.509] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:113:0)
[00:11:57.509] Timer tick, asking Buildbucket for the build status
[00:11:57.809] Build 8852927296509748816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:11:57.809] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:114:0) after 1m0s
[00:12:57.829] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:114:0)
[00:12:57.829] Timer tick, asking Buildbucket for the build status
[00:12:57.919] Build 8852927296509748816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:12:57.919] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:115:0) after 1m0s
[00:13:58.002] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:115:0)
[00:13:58.002] Timer tick, asking Buildbucket for the build status
[00:13:58.211] Build 8852927296509748816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:13:58.211] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:116:0) after 1m0s
[00:14:58.234] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:116:0)
[00:14:58.234] Timer tick, asking Buildbucket for the build status
[00:14:58.444] Build 8852927296509748816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:14:58.444] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:117:0) after 1m0s
[00:15:58.489] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:117:0)
[00:15:58.489] Timer tick, asking Buildbucket for the build status
[00:15:58.588] Build 8852927296509748816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:15:58.589] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:118:0) after 1m0s
[00:16:58.618] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:118:0)
[00:16:58.618] Timer tick, asking Buildbucket for the build status
[00:16:58.706] Build 8852927296509748816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:16:58.706] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:119:0) after 1m0s
[00:17:58.730] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:119:0)
[00:17:58.730] Timer tick, asking Buildbucket for the build status
[00:17:58.800] Build 8852927296509748816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:17:58.800] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:120:0) after 1m0s
[00:18:58.835] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:120:0)
[00:18:58.835] Timer tick, asking Buildbucket for the build status
[00:18:58.907] Build 8852927296509748816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:18:58.907] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:121:0) after 1m0s
[00:19:58.929] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:121:0)
[00:19:58.929] Timer tick, asking Buildbucket for the build status
[00:19:58.999] Build 8852927296509748816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:19:58.999] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:122:0) after 1m0s
[00:20:59.022] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:122:0)
[00:20:59.022] Timer tick, asking Buildbucket for the build status
[00:20:59.113] Build 8852927296509748816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:20:59.113] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:123:0) after 1m0s
[00:21:59.147] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:123:0)
[00:21:59.147] Timer tick, asking Buildbucket for the build status
[00:21:59.221] Build 8852927296509748816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:21:59.221] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:124:0) after 1m0s
[00:22:59.305] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:124:0)
[00:22:59.305] Timer tick, asking Buildbucket for the build status
[00:22:59.434] Build 8852927296509748816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:22:59.434] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:125:0) after 1m0s
[00:23:59.482] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:125:0)
[00:23:59.482] Timer tick, asking Buildbucket for the build status
[00:23:59.624] Build 8852927296509748816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:23:59.624] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:126:0) after 1m0s
[00:25:01.988] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:126:0)
[00:25:01.988] Timer tick, asking Buildbucket for the build status
[00:25:02.079] Build 8852927296509748816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:25:02.079] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018357358552764560:127:0) after 1m0s
[00:25:31.872] Received PubSub notification, asking Buildbucket for the build status
[00:25:32.011] Build 8852927296509748816: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[00:25:32.011] Buildbucket build:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary_preference": "PROD",
"completed_ts": "1615595131248355",
"created_by": "project:emscripten-releases",
"created_ts": "1615587634514791",
"id": "8852927296509748816",
"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\": \"b5a1b1a2b8858df317dcb1b76f237a07af7ca1e6\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@b5a1b1a2b8858df317dcb1b76f237a07af7ca1e6\", \"title\": \"b5a1b1a2b8858df317dcb1b76f237a07af7ca1e6\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/b5a1b1a2b8858df317dcb1b76f237a07af7ca1e6\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"b5a1b1a2b8858df317dcb1b76f237a07af7ca1e6\"}}",
"project": "emscripten-releases",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/goma\": {\"enable_ats\": true, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\", \"use_luci_auth\": true}, \"$recipe_engine/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\": \"b5a1b1a2b8858df317dcb1b76f237a07af7ca1e6\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@b5a1b1a2b8858df317dcb1b76f237a07af7ca1e6\", \"title\": \"b5a1b1a2b8858df317dcb1b76f237a07af7ca1e6\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/b5a1b1a2b8858df317dcb1b76f237a07af7ca1e6\"}]}, \"bot_id\": \"luci-emscripten-releases-ci-win10-0-1uhs\", \"branch\": \"refs/heads/master\", \"buildername\": \"win\", \"got_revision\": \"b5a1b1a2b8858df317dcb1b76f237a07af7ca1e6\", \"path_config\": \"generic\", \"recipe\": \"emscripten_releases\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"b5a1b1a2b8858df317dcb1b76f237a07af7ca1e6\"}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"builder_0175bca77590805533ed6e20351c9bfa7effd036e42d11a5b619a6ce75a8e486_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"host_class\": [\"default\"], \"id\": [\"luci-emscripten-releases-ci-win10-0-1uhs\"], \"image\": [\"chrome-win10-1703-20111600-a937a23cefb\"], \"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\": [\"5754-8c4f760\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-b\"]}}}",
"service_account": "emscripten-releases-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1615592053807351",
"status": "COMPLETED",
"status_changed_ts": "1615595131677885",
"tags": [
"builder:win",
"buildset:commit/git/b5a1b1a2b8858df317dcb1b76f237a07af7ca1e6",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/b5a1b1a2b8858df317dcb1b76f237a07af7ca1e6",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9018357358552764560",
"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/8852927296509748816/+/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:524158787991d010",
"user_agent:luci-scheduler"
],
"updated_ts": "1615595131688834",
"url": "https://ci.chromium.org/b/8852927296509748816",
"utcnow_ts": "1615595132004617"
}
}
[00:25:32.011] Invocation finished in 2h4m58.829082445s with status SUCCEEDED