[21:35:24.762] New invocation is queued and will start shortly
[21:35:26.233] Starting the invocation (attempt 1)
[21:35:26.312] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[21:35:26.312] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[21:35:26.312] Buildbucket request:
{
"bucket": "luci.emscripten-releases.ci",
"client_operation_id": "9034486458573820224",
"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\":\"c541e23116195208a113d67ed9c3773b597a8b37\"},\"id\":\"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@c541e23116195208a113d67ed9c3773b597a8b37\",\"title\":\"c541e23116195208a113d67ed9c3773b597a8b37\",\"url\":\"https://chromium.googlesource.com/emscripten-releases/+/c541e23116195208a113d67ed9c3773b597a8b37\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/emscripten-releases\",\"revision\":\"c541e23116195208a113d67ed9c3773b597a8b37\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win",
"scheduler_invocation_id:9034486458573820224",
"scheduler_job_id:emscripten-releases/win",
"user_agent:luci-scheduler",
"buildset:commit/git/c541e23116195208a113d67ed9c3773b597a8b37",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/c541e23116195208a113d67ed9c3773b597a8b37",
"gitiles_ref:refs/heads/master"
]
}
[21:35:27.673] Buildbucket response:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary_preference": "PROD",
"created_by": "project:emscripten-releases",
"created_ts": "1600205726441734",
"id": "8869056396149436672",
"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\": \"c541e23116195208a113d67ed9c3773b597a8b37\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@c541e23116195208a113d67ed9c3773b597a8b37\", \"title\": \"c541e23116195208a113d67ed9c3773b597a8b37\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/c541e23116195208a113d67ed9c3773b597a8b37\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"c541e23116195208a113d67ed9c3773b597a8b37\"}}",
"project": "emscripten-releases",
"result_details_json": "{\"properties\": {}}",
"service_account": "emscripten-releases-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1600205727534635",
"tags": [
"builder:win",
"buildset:commit/git/c541e23116195208a113d67ed9c3773b597a8b37",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/c541e23116195208a113d67ed9c3773b597a8b37",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9034486458573820224",
"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/8869056396149436672/+/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": "1600205727534746",
"url": "https://ci.chromium.org/b/8869056396149436672",
"utcnow_ts": "1600205727661807"
}
}
[21:35:27.673] Task URL: https://ci.chromium.org/b/8869056396149436672
[21:35:27.673] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:2:0) after 1m0s
[21:36:27.741] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:2:0)
[21:36:27.742] Timer tick, asking Buildbucket for the build status
[21:36:27.867] Build 8869056396149436672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:36:27.867] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:3:0) after 1m0s
[21:37:27.966] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:3:0)
[21:37:27.966] Timer tick, asking Buildbucket for the build status
[21:37:29.102] Build 8869056396149436672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:37:29.102] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:4:0) after 1m0s
[21:38:29.162] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:4:0)
[21:38:29.162] Timer tick, asking Buildbucket for the build status
[21:38:29.382] Build 8869056396149436672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:38:29.382] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:5:0) after 1m0s
[21:39:29.454] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:5:0)
[21:39:29.454] Timer tick, asking Buildbucket for the build status
[21:39:29.530] Build 8869056396149436672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:39:29.530] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:6:0) after 1m0s
[21:40:29.563] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:6:0)
[21:40:29.563] Timer tick, asking Buildbucket for the build status
[21:40:29.827] Build 8869056396149436672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:40:29.827] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:7:0) after 1m0s
[21:41:29.888] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:7:0)
[21:41:29.888] Timer tick, asking Buildbucket for the build status
[21:41:29.988] Build 8869056396149436672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:41:29.988] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:8:0) after 1m0s
[21:42:30.032] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:8:0)
[21:42:30.032] Timer tick, asking Buildbucket for the build status
[21:42:30.127] Build 8869056396149436672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:42:30.127] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:9:0) after 1m0s
[21:43:30.238] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:9:0)
[21:43:30.238] Timer tick, asking Buildbucket for the build status
[21:43:31.004] Build 8869056396149436672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:43:31.005] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:10:0) after 1m0s
[21:44:31.076] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:10:0)
[21:44:31.076] Timer tick, asking Buildbucket for the build status
[21:44:31.151] Build 8869056396149436672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:44:31.151] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:11:0) after 1m0s
[21:45:31.200] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:11:0)
[21:45:31.200] Timer tick, asking Buildbucket for the build status
[21:45:31.438] Build 8869056396149436672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:45:31.438] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:12:0) after 1m0s
[21:46:31.512] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:12:0)
[21:46:31.512] Timer tick, asking Buildbucket for the build status
[21:46:31.607] Build 8869056396149436672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:46:31.607] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:13:0) after 1m0s
[21:47:31.635] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:13:0)
[21:47:31.635] Timer tick, asking Buildbucket for the build status
[21:47:31.789] Build 8869056396149436672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:47:31.789] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:14:0) after 1m0s
[21:48:31.849] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:14:0)
[21:48:31.849] Timer tick, asking Buildbucket for the build status
[21:48:31.915] Build 8869056396149436672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:48:31.915] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:15:0) after 1m0s
[21:49:31.961] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:15:0)
[21:49:31.961] Timer tick, asking Buildbucket for the build status
[21:49:32.248] Build 8869056396149436672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:49:32.248] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:16:0) after 1m0s
[21:50:32.301] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:16:0)
[21:50:32.301] Timer tick, asking Buildbucket for the build status
[21:50:32.734] Build 8869056396149436672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:50:32.734] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:17:0) after 1m0s
[21:51:32.895] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:17:0)
[21:51:32.895] Timer tick, asking Buildbucket for the build status
[21:51:33.048] Build 8869056396149436672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:51:33.048] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:18:0) after 1m0s
[21:52:33.085] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:18:0)
[21:52:33.085] Timer tick, asking Buildbucket for the build status
[21:52:33.156] Build 8869056396149436672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:52:33.156] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:19:0) after 1m0s
[21:53:33.326] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:19:0)
[21:53:33.326] Timer tick, asking Buildbucket for the build status
[21:53:34.501] Build 8869056396149436672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:53:34.501] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:20:0) after 1m0s
[21:54:34.848] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:20:0)
[21:54:34.848] Timer tick, asking Buildbucket for the build status
[21:54:35.978] Build 8869056396149436672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:54:35.978] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:21:0) after 1m0s
[21:55:36.159] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:21:0)
[21:55:36.159] Timer tick, asking Buildbucket for the build status
[21:55:36.286] Build 8869056396149436672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:55:36.286] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:22:0) after 1m0s
[21:56:36.289] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:22:0)
[21:56:36.289] Timer tick, asking Buildbucket for the build status
[21:56:36.454] Build 8869056396149436672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:56:36.454] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:23:0) after 1m0s
[21:57:36.473] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:23:0)
[21:57:36.473] Timer tick, asking Buildbucket for the build status
[21:57:36.993] Build 8869056396149436672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:57:36.993] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:24:0) after 1m0s
[21:58:37.021] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:24:0)
[21:58:37.021] Timer tick, asking Buildbucket for the build status
[21:58:37.243] Build 8869056396149436672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:58:37.243] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:25:0) after 1m0s
[21:59:37.392] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:25:0)
[21:59:37.393] Timer tick, asking Buildbucket for the build status
[21:59:37.515] Build 8869056396149436672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:59:37.515] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:26:0) after 1m0s
[22:00:37.571] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:26:0)
[22:00:37.571] Timer tick, asking Buildbucket for the build status
[22:00:38.318] Build 8869056396149436672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:00:38.318] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:27:0) after 1m0s
[22:01:38.373] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:27:0)
[22:01:38.373] Timer tick, asking Buildbucket for the build status
[22:01:38.454] Build 8869056396149436672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:01:38.454] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:28:0) after 1m0s
[22:02:38.509] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:28:0)
[22:02:38.509] Timer tick, asking Buildbucket for the build status
[22:02:38.588] Build 8869056396149436672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:02:38.588] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:29:0) after 1m0s
[22:03:38.656] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:29:0)
[22:03:38.656] Timer tick, asking Buildbucket for the build status
[22:03:38.738] Build 8869056396149436672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:03:38.738] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:30:0) after 1m0s
[22:04:38.761] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:30:0)
[22:04:38.761] Timer tick, asking Buildbucket for the build status
[22:04:38.831] Build 8869056396149436672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:04:38.831] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:31:0) after 1m0s
[22:05:38.864] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:31:0)
[22:05:38.864] Timer tick, asking Buildbucket for the build status
[22:05:39.012] Build 8869056396149436672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:05:39.012] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:32:0) after 1m0s
[22:06:39.026] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:32:0)
[22:06:39.026] Timer tick, asking Buildbucket for the build status
[22:06:39.283] Build 8869056396149436672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:06:39.283] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:33:0) after 1m0s
[22:07:39.307] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:33:0)
[22:07:39.307] Timer tick, asking Buildbucket for the build status
[22:07:39.412] Build 8869056396149436672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:07:39.412] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:34:0) after 1m0s
[22:08:39.464] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:34:0)
[22:08:39.464] Timer tick, asking Buildbucket for the build status
[22:08:39.783] Build 8869056396149436672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:08:39.783] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:35:0) after 1m0s
[22:09:39.805] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:35:0)
[22:09:39.805] Timer tick, asking Buildbucket for the build status
[22:09:39.908] Build 8869056396149436672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:09:39.908] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:36:0) after 1m0s
[22:10:39.935] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:36:0)
[22:10:39.935] Timer tick, asking Buildbucket for the build status
[22:10:40.043] Build 8869056396149436672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:10:40.043] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:37:0) after 1m0s
[22:11:40.095] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:37:0)
[22:11:40.095] Timer tick, asking Buildbucket for the build status
[22:11:40.191] Build 8869056396149436672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:11:40.191] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:38:0) after 1m0s
[22:12:40.217] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:38:0)
[22:12:40.217] Timer tick, asking Buildbucket for the build status
[22:12:40.293] Build 8869056396149436672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:12:40.293] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:39:0) after 1m0s
[22:13:40.316] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:39:0)
[22:13:40.316] Timer tick, asking Buildbucket for the build status
[22:13:40.425] Build 8869056396149436672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:13:40.426] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:40:0) after 1m0s
[22:14:40.477] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:40:0)
[22:14:40.477] Timer tick, asking Buildbucket for the build status
[22:14:40.552] Build 8869056396149436672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:14:40.552] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:41:0) after 1m0s
[22:15:40.616] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:41:0)
[22:15:40.616] Timer tick, asking Buildbucket for the build status
[22:15:40.713] Build 8869056396149436672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:15:40.713] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:42:0) after 1m0s
[22:16:40.728] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:42:0)
[22:16:40.728] Timer tick, asking Buildbucket for the build status
[22:16:41.262] Build 8869056396149436672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:16:41.262] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:43:0) after 1m0s
[22:17:41.296] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:43:0)
[22:17:41.296] Timer tick, asking Buildbucket for the build status
[22:17:41.528] Build 8869056396149436672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:17:41.529] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:44:0) after 1m0s
[22:18:41.579] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:44:0)
[22:18:41.579] Timer tick, asking Buildbucket for the build status
[22:18:43.640] Build 8869056396149436672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:18:43.640] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:45:0) after 1m0s
[22:19:43.695] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:45:0)
[22:19:43.695] Timer tick, asking Buildbucket for the build status
[22:19:43.783] Build 8869056396149436672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:19:43.783] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:46:0) after 1m0s
[22:20:43.818] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:46:0)
[22:20:43.818] Timer tick, asking Buildbucket for the build status
[22:20:43.971] Build 8869056396149436672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:20:43.971] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:47:0) after 1m0s
[22:21:43.994] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:47:0)
[22:21:43.994] Timer tick, asking Buildbucket for the build status
[22:21:44.215] Build 8869056396149436672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:21:44.215] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:48:0) after 1m0s
[22:22:44.261] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:48:0)
[22:22:44.261] Timer tick, asking Buildbucket for the build status
[22:22:44.399] Build 8869056396149436672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:22:44.399] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:49:0) after 1m0s
[22:23:44.439] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:49:0)
[22:23:44.439] Timer tick, asking Buildbucket for the build status
[22:23:44.819] Build 8869056396149436672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:23:44.819] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:50:0) after 1m0s
[22:23:54.604] Received PubSub notification, asking Buildbucket for the build status
[22:23:54.864] Build 8869056396149436672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:24:44.947] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:50:0)
[22:24:44.947] Timer tick, asking Buildbucket for the build status
[22:24:45.205] Build 8869056396149436672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:24:45.205] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:52:0) after 1m0s
[22:25:45.316] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:52:0)
[22:25:45.316] Timer tick, asking Buildbucket for the build status
[22:25:45.527] Build 8869056396149436672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:25:45.527] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:53:0) after 1m0s
[22:26:45.666] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:53:0)
[22:26:45.666] Timer tick, asking Buildbucket for the build status
[22:26:45.782] Build 8869056396149436672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:26:45.782] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:54:0) after 1m0s
[22:27:46.024] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:54:0)
[22:27:46.024] Timer tick, asking Buildbucket for the build status
[22:27:46.188] Build 8869056396149436672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:27:46.188] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:55:0) after 1m0s
[22:28:46.262] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:55:0)
[22:28:46.262] Timer tick, asking Buildbucket for the build status
[22:28:46.375] Build 8869056396149436672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:28:46.375] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:56:0) after 1m0s
[22:29:46.396] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:56:0)
[22:29:46.396] Timer tick, asking Buildbucket for the build status
[22:29:46.485] Build 8869056396149436672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:29:46.485] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:57:0) after 1m0s
[22:30:46.514] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:57:0)
[22:30:46.514] Timer tick, asking Buildbucket for the build status
[22:30:46.711] Build 8869056396149436672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:30:46.711] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:58:0) after 1m0s
[22:31:46.785] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:58:0)
[22:31:46.785] Timer tick, asking Buildbucket for the build status
[22:31:46.865] Build 8869056396149436672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:31:46.865] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:59:0) after 1m0s
[22:32:46.894] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:59:0)
[22:32:46.894] Timer tick, asking Buildbucket for the build status
[22:32:47.012] Build 8869056396149436672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:32:47.012] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:60:0) after 1m0s
[22:33:47.071] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:60:0)
[22:33:47.071] Timer tick, asking Buildbucket for the build status
[22:33:47.264] Build 8869056396149436672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:33:47.264] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:61:0) after 1m0s
[22:34:47.306] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:61:0)
[22:34:47.306] Timer tick, asking Buildbucket for the build status
[22:34:47.390] Build 8869056396149436672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:34:47.390] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:62:0) after 1m0s
[22:35:47.461] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:62:0)
[22:35:47.461] Timer tick, asking Buildbucket for the build status
[22:35:47.689] Build 8869056396149436672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:35:47.689] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:63:0) after 1m0s
[22:36:47.714] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:63:0)
[22:36:47.714] Timer tick, asking Buildbucket for the build status
[22:36:48.116] Build 8869056396149436672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:36:48.116] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:64:0) after 1m0s
[22:37:48.182] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:64:0)
[22:37:48.182] Timer tick, asking Buildbucket for the build status
[22:37:48.724] Build 8869056396149436672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:37:48.724] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:65:0) after 1m0s
[22:38:48.747] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:65:0)
[22:38:48.747] Timer tick, asking Buildbucket for the build status
[22:38:48.845] Build 8869056396149436672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:38:48.845] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:66:0) after 1m0s
[22:39:48.834] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:66:0)
[22:39:48.834] Timer tick, asking Buildbucket for the build status
[22:39:48.984] Build 8869056396149436672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:39:48.984] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:67:0) after 1m0s
[22:40:49.009] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:67:0)
[22:40:49.009] Timer tick, asking Buildbucket for the build status
[22:40:49.120] Build 8869056396149436672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:40:49.120] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:68:0) after 1m0s
[22:41:49.152] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:68:0)
[22:41:49.152] Timer tick, asking Buildbucket for the build status
[22:41:49.401] Build 8869056396149436672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:41:49.401] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:69:0) after 1m0s
[22:42:49.445] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:69:0)
[22:42:49.445] Timer tick, asking Buildbucket for the build status
[22:42:49.589] Build 8869056396149436672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:42:49.589] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:70:0) after 1m0s
[22:43:49.610] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:70:0)
[22:43:49.610] Timer tick, asking Buildbucket for the build status
[22:43:49.704] Build 8869056396149436672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:43:49.704] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:71:0) after 1m0s
[22:44:49.706] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:71:0)
[22:44:49.706] Timer tick, asking Buildbucket for the build status
[22:44:49.792] Build 8869056396149436672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:44:49.792] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:72:0) after 1m0s
[22:45:49.818] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:72:0)
[22:45:49.818] Timer tick, asking Buildbucket for the build status
[22:45:49.940] Build 8869056396149436672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:45:49.940] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:73:0) after 1m0s
[22:46:50.011] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:73:0)
[22:46:50.011] Timer tick, asking Buildbucket for the build status
[22:46:50.082] Build 8869056396149436672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:46:50.082] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:74:0) after 1m0s
[22:47:50.094] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:74:0)
[22:47:50.094] Timer tick, asking Buildbucket for the build status
[22:47:50.280] Build 8869056396149436672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:47:50.280] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:75:0) after 1m0s
[22:48:50.331] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:75:0)
[22:48:50.331] Timer tick, asking Buildbucket for the build status
[22:48:50.412] Build 8869056396149436672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:48:50.412] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:76:0) after 1m0s
[22:49:50.436] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:76:0)
[22:49:50.436] Timer tick, asking Buildbucket for the build status
[22:49:50.603] Build 8869056396149436672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:49:50.603] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:77:0) after 1m0s
[22:50:50.683] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:77:0)
[22:50:50.683] Timer tick, asking Buildbucket for the build status
[22:50:50.927] Build 8869056396149436672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:50:50.927] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:78:0) after 1m0s
[22:51:51.112] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:78:0)
[22:51:51.112] Timer tick, asking Buildbucket for the build status
[22:51:51.477] Build 8869056396149436672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:51:51.477] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:79:0) after 1m0s
[22:52:51.548] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:79:0)
[22:52:51.548] Timer tick, asking Buildbucket for the build status
[22:52:51.647] Build 8869056396149436672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:52:51.647] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:80:0) after 1m0s
[22:53:51.720] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:80:0)
[22:53:51.720] Timer tick, asking Buildbucket for the build status
[22:53:51.970] Build 8869056396149436672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:53:51.970] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:81:0) after 1m0s
[22:54:52.042] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:81:0)
[22:54:52.042] Timer tick, asking Buildbucket for the build status
[22:54:52.184] Build 8869056396149436672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:54:52.184] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:82:0) after 1m0s
[22:55:52.220] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:82:0)
[22:55:52.220] Timer tick, asking Buildbucket for the build status
[22:55:52.344] Build 8869056396149436672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:55:52.344] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:83:0) after 1m0s
[22:56:52.349] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:83:0)
[22:56:52.349] Timer tick, asking Buildbucket for the build status
[22:56:52.430] Build 8869056396149436672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:56:52.430] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:84:0) after 1m0s
[22:57:52.518] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:84:0)
[22:57:52.518] Timer tick, asking Buildbucket for the build status
[22:57:53.388] Build 8869056396149436672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:57:53.388] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:85:0) after 1m0s
[22:58:53.448] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:85:0)
[22:58:53.448] Timer tick, asking Buildbucket for the build status
[22:58:53.528] Build 8869056396149436672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:58:53.528] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:86:0) after 1m0s
[22:59:53.580] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:86:0)
[22:59:53.580] Timer tick, asking Buildbucket for the build status
[22:59:53.642] Build 8869056396149436672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:59:53.642] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:87:0) after 1m0s
[23:00:53.850] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:87:0)
[23:00:53.850] Timer tick, asking Buildbucket for the build status
[23:00:53.965] Build 8869056396149436672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:00:53.965] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:88:0) after 1m0s
[23:01:54.099] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:88:0)
[23:01:54.099] Timer tick, asking Buildbucket for the build status
[23:01:54.212] Build 8869056396149436672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:01:54.212] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:89:0) after 1m0s
[23:02:54.310] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:89:0)
[23:02:54.310] Timer tick, asking Buildbucket for the build status
[23:02:54.628] Build 8869056396149436672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:02:54.628] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:90:0) after 1m0s
[23:03:54.698] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:90:0)
[23:03:54.698] Timer tick, asking Buildbucket for the build status
[23:03:54.812] Build 8869056396149436672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:03:54.812] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:91:0) after 1m0s
[23:04:55.008] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:91:0)
[23:04:55.008] Timer tick, asking Buildbucket for the build status
[23:04:55.076] Build 8869056396149436672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:04:55.076] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:92:0) after 1m0s
[23:05:55.695] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:92:0)
[23:05:55.695] Timer tick, asking Buildbucket for the build status
[23:05:55.834] Build 8869056396149436672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:05:55.834] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:93:0) after 1m0s
[23:06:55.881] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:93:0)
[23:06:55.881] Timer tick, asking Buildbucket for the build status
[23:06:55.995] Build 8869056396149436672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:06:55.995] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:94:0) after 1m0s
[23:07:56.141] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:94:0)
[23:07:56.141] Timer tick, asking Buildbucket for the build status
[23:07:56.242] Build 8869056396149436672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:07:56.242] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:95:0) after 1m0s
[23:08:56.524] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:95:0)
[23:08:56.524] Timer tick, asking Buildbucket for the build status
[23:08:56.591] Build 8869056396149436672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:08:56.591] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:96:0) after 1m0s
[23:09:56.912] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:96:0)
[23:09:56.912] Timer tick, asking Buildbucket for the build status
[23:09:56.988] Build 8869056396149436672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:09:56.988] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:97:0) after 1m0s
[23:10:57.141] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:97:0)
[23:10:57.141] Timer tick, asking Buildbucket for the build status
[23:10:57.293] Build 8869056396149436672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:10:57.293] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:98:0) after 1m0s
[23:11:57.404] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:98:0)
[23:11:57.404] Timer tick, asking Buildbucket for the build status
[23:11:57.550] Build 8869056396149436672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:11:57.550] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:99:0) after 1m0s
[23:12:57.670] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:99:0)
[23:12:57.670] Timer tick, asking Buildbucket for the build status
[23:12:57.796] Build 8869056396149436672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:12:57.796] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:100:0) after 1m0s
[23:13:57.845] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:100:0)
[23:13:57.845] Timer tick, asking Buildbucket for the build status
[23:13:57.953] Build 8869056396149436672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:13:57.953] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:101:0) after 1m0s
[23:14:57.933] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:101:0)
[23:14:57.933] Timer tick, asking Buildbucket for the build status
[23:14:58.047] Build 8869056396149436672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:14:58.047] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:102:0) after 1m0s
[23:15:58.105] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:102:0)
[23:15:58.105] Timer tick, asking Buildbucket for the build status
[23:15:58.250] Build 8869056396149436672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:15:58.250] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:103:0) after 1m0s
[23:16:58.324] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:103:0)
[23:16:58.324] Timer tick, asking Buildbucket for the build status
[23:16:58.386] Build 8869056396149436672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:16:58.386] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:104:0) after 1m0s
[23:17:58.397] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:104:0)
[23:17:58.397] Timer tick, asking Buildbucket for the build status
[23:17:58.841] Build 8869056396149436672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:17:58.841] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:105:0) after 1m0s
[23:18:58.848] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:105:0)
[23:18:58.848] Timer tick, asking Buildbucket for the build status
[23:18:58.949] Build 8869056396149436672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:18:58.949] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:106:0) after 1m0s
[23:19:58.961] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:106:0)
[23:19:58.961] Timer tick, asking Buildbucket for the build status
[23:19:59.118] Build 8869056396149436672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:19:59.118] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:107:0) after 1m0s
[23:20:59.211] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:107:0)
[23:20:59.211] Timer tick, asking Buildbucket for the build status
[23:20:59.401] Build 8869056396149436672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:20:59.401] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:108:0) after 1m0s
[23:21:59.487] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:108:0)
[23:21:59.487] Timer tick, asking Buildbucket for the build status
[23:21:59.618] Build 8869056396149436672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:21:59.618] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:109:0) after 1m0s
[23:22:59.673] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:109:0)
[23:22:59.673] Timer tick, asking Buildbucket for the build status
[23:23:00.135] Build 8869056396149436672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:23:00.135] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:110:0) after 1m0s
[23:24:00.172] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:110:0)
[23:24:00.172] Timer tick, asking Buildbucket for the build status
[23:24:00.240] Build 8869056396149436672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:24:00.240] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:111:0) after 1m0s
[23:25:00.238] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:111:0)
[23:25:00.238] Timer tick, asking Buildbucket for the build status
[23:25:00.411] Build 8869056396149436672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:25:00.411] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:112:0) after 1m0s
[23:26:01.219] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:112:0)
[23:26:01.219] Timer tick, asking Buildbucket for the build status
[23:26:01.321] Build 8869056396149436672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:26:01.321] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:113:0) after 1m0s
[23:27:01.595] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:113:0)
[23:27:01.595] Timer tick, asking Buildbucket for the build status
[23:27:02.133] Build 8869056396149436672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:27:02.133] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9034486458573820224:114:0) after 1m0s
[23:27:20.567] Received PubSub notification, asking Buildbucket for the build status
[23:27:20.678] Build 8869056396149436672: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[23:27:20.678] Buildbucket build:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary_preference": "PROD",
"completed_ts": "1600212439700630",
"created_by": "project:emscripten-releases",
"created_ts": "1600205726441734",
"id": "8869056396149436672",
"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\": \"c541e23116195208a113d67ed9c3773b597a8b37\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@c541e23116195208a113d67ed9c3773b597a8b37\", \"title\": \"c541e23116195208a113d67ed9c3773b597a8b37\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/c541e23116195208a113d67ed9c3773b597a8b37\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"c541e23116195208a113d67ed9c3773b597a8b37\"}}",
"project": "emscripten-releases",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/goma\": {\"enable_ats\": true, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\"}, \"$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\": \"c541e23116195208a113d67ed9c3773b597a8b37\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@c541e23116195208a113d67ed9c3773b597a8b37\", \"title\": \"c541e23116195208a113d67ed9c3773b597a8b37\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/c541e23116195208a113d67ed9c3773b597a8b37\"}]}, \"bot_id\": \"luci-emscripten-releases-ci-win10-0-0szh\", \"branch\": \"refs/heads/master\", \"buildername\": \"win\", \"got_revision\": \"c541e23116195208a113d67ed9c3773b597a8b37\", \"path_config\": \"generic\", \"recipe\": \"emscripten_releases\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"c541e23116195208a113d67ed9c3773b597a8b37\"}, \"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-0szh\"], \"image\": [\"chrome-win10-1703-20083100-22dcea0839e\"], \"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\": [\"5522-67570db\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-b\"]}}}",
"service_account": "emscripten-releases-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1600208634067826",
"status": "COMPLETED",
"status_changed_ts": "1600212440215177",
"tags": [
"builder:win",
"buildset:commit/git/c541e23116195208a113d67ed9c3773b597a8b37",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/c541e23116195208a113d67ed9c3773b597a8b37",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9034486458573820224",
"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/8869056396149436672/+/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:4eac83310dbb8710",
"user_agent:luci-scheduler"
],
"updated_ts": "1600212440295908",
"url": "https://ci.chromium.org/b/8869056396149436672",
"utcnow_ts": "1600212440667079"
}
}
[23:27:20.678] Invocation finished in 1h51m55.931487489s with status SUCCEEDED