[19:38:07.992] New invocation is queued and will start shortly
[19:38:09.801] Starting the invocation (attempt 1)
[19:38:09.876] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[19:38:09.877] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[19:38:09.877] Buildbucket request:
{
"bucket": "luci.emscripten-releases.ci",
"client_operation_id": "9049623530558598080",
"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\":\"4e399b1c58f854d137a7248f8b732895441c283e\"},\"id\":\"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@4e399b1c58f854d137a7248f8b732895441c283e\",\"title\":\"4e399b1c58f854d137a7248f8b732895441c283e\",\"url\":\"https://chromium.googlesource.com/emscripten-releases/+/4e399b1c58f854d137a7248f8b732895441c283e\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/emscripten-releases\",\"revision\":\"4e399b1c58f854d137a7248f8b732895441c283e\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win",
"scheduler_invocation_id:9049623530558598080",
"scheduler_job_id:emscripten-releases/win",
"user_agent:luci-scheduler",
"buildset:commit/git/4e399b1c58f854d137a7248f8b732895441c283e",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/4e399b1c58f854d137a7248f8b732895441c283e",
"gitiles_ref:refs/heads/master"
]
}
[19:38:11.597] Buildbucket response:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary_preference": "PROD",
"created_by": "project:emscripten-releases",
"created_ts": "1585769890121851",
"id": "8884193467654481808",
"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\": \"4e399b1c58f854d137a7248f8b732895441c283e\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@4e399b1c58f854d137a7248f8b732895441c283e\", \"title\": \"4e399b1c58f854d137a7248f8b732895441c283e\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/4e399b1c58f854d137a7248f8b732895441c283e\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"4e399b1c58f854d137a7248f8b732895441c283e\"}}",
"project": "emscripten-releases",
"result_details_json": "{\"properties\": {}}",
"service_account": "emscripten-releases-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1585769891122644",
"tags": [
"builder:win",
"buildset:commit/git/4e399b1c58f854d137a7248f8b732895441c283e",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/4e399b1c58f854d137a7248f8b732895441c283e",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9049623530558598080",
"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/8884193467654481808/+/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": "1585769891122800",
"url": "https://ci.chromium.org/b/8884193467654481808",
"utcnow_ts": "1585769891563486"
}
}
[19:38:11.597] Task URL: https://ci.chromium.org/b/8884193467654481808
[19:38:11.597] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:2:0) after 1m0s
[19:39:11.658] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:2:0)
[19:39:11.658] Timer tick, asking Buildbucket for the build status
[19:39:13.624] Build 8884193467654481808: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:39:13.624] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:3:0) after 1m0s
[19:40:13.697] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:3:0)
[19:40:13.697] Timer tick, asking Buildbucket for the build status
[19:40:14.116] Build 8884193467654481808: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:40:14.116] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:4:0) after 1m0s
[19:41:14.177] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:4:0)
[19:41:14.177] Timer tick, asking Buildbucket for the build status
[19:41:14.684] Build 8884193467654481808: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:41:14.684] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:5:0) after 1m0s
[19:42:14.739] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:5:0)
[19:42:14.739] Timer tick, asking Buildbucket for the build status
[19:42:15.614] Build 8884193467654481808: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:42:15.614] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:6:0) after 1m0s
[19:43:15.724] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:6:0)
[19:43:15.724] Timer tick, asking Buildbucket for the build status
[19:43:17.000] Build 8884193467654481808: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:43:17.000] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:7:0) after 1m0s
[19:44:17.121] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:7:0)
[19:44:17.121] Timer tick, asking Buildbucket for the build status
[19:44:17.337] Build 8884193467654481808: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:44:17.337] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:8:0) after 1m0s
[19:45:17.390] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:8:0)
[19:45:17.390] Timer tick, asking Buildbucket for the build status
[19:45:17.780] Build 8884193467654481808: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:45:17.780] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:9:0) after 1m0s
[19:46:17.904] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:9:0)
[19:46:17.904] Timer tick, asking Buildbucket for the build status
[19:46:18.154] Build 8884193467654481808: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:46:18.154] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:10:0) after 1m0s
[19:47:18.295] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:10:0)
[19:47:18.295] Timer tick, asking Buildbucket for the build status
[19:47:18.462] Build 8884193467654481808: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:47:18.462] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:11:0) after 1m0s
[19:48:18.530] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:11:0)
[19:48:18.530] Timer tick, asking Buildbucket for the build status
[19:48:19.221] Build 8884193467654481808: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:48:19.221] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:12:0) after 1m0s
[19:49:19.258] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:12:0)
[19:49:19.258] Timer tick, asking Buildbucket for the build status
[19:49:19.710] Build 8884193467654481808: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:49:19.710] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:13:0) after 1m0s
[19:50:19.759] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:13:0)
[19:50:19.759] Timer tick, asking Buildbucket for the build status
[19:50:20.005] Build 8884193467654481808: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:50:20.005] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:14:0) after 1m0s
[19:51:20.135] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:14:0)
[19:51:20.135] Timer tick, asking Buildbucket for the build status
[19:51:20.411] Build 8884193467654481808: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:51:20.411] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:15:0) after 1m0s
[19:52:20.439] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:15:0)
[19:52:20.439] Timer tick, asking Buildbucket for the build status
[19:52:21.173] Build 8884193467654481808: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:52:21.173] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:16:0) after 1m0s
[19:53:21.154] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:16:0)
[19:53:21.154] Timer tick, asking Buildbucket for the build status
[19:53:21.375] Build 8884193467654481808: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:53:21.375] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:17:0) after 1m0s
[19:54:21.399] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:17:0)
[19:54:21.399] Timer tick, asking Buildbucket for the build status
[19:54:22.013] Build 8884193467654481808: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:54:22.013] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:18:0) after 1m0s
[19:55:22.049] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:18:0)
[19:55:22.049] Timer tick, asking Buildbucket for the build status
[19:55:22.273] Build 8884193467654481808: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:55:22.273] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:19:0) after 1m0s
[19:56:22.336] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:19:0)
[19:56:22.336] Timer tick, asking Buildbucket for the build status
[19:56:23.072] Build 8884193467654481808: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:56:23.072] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:20:0) after 1m0s
[19:57:23.098] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:20:0)
[19:57:23.098] Timer tick, asking Buildbucket for the build status
[19:57:23.315] Build 8884193467654481808: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:57:23.315] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:21:0) after 1m0s
[19:58:23.370] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:21:0)
[19:58:23.370] Timer tick, asking Buildbucket for the build status
[19:58:23.791] Build 8884193467654481808: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:58:23.791] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:22:0) after 1m0s
[19:59:23.811] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:22:0)
[19:59:23.811] Timer tick, asking Buildbucket for the build status
[19:59:24.006] Build 8884193467654481808: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:59:24.006] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:23:0) after 1m0s
[20:00:24.071] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:23:0)
[20:00:24.071] Timer tick, asking Buildbucket for the build status
[20:00:24.251] Build 8884193467654481808: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:00:24.251] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:24:0) after 1m0s
[20:01:24.271] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:24:0)
[20:01:24.271] Timer tick, asking Buildbucket for the build status
[20:01:24.561] Build 8884193467654481808: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:01:24.561] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:25:0) after 1m0s
[20:02:24.648] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:25:0)
[20:02:24.648] Timer tick, asking Buildbucket for the build status
[20:02:24.828] Build 8884193467654481808: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:02:24.828] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:26:0) after 1m0s
[20:03:24.871] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:26:0)
[20:03:24.871] Timer tick, asking Buildbucket for the build status
[20:03:25.135] Build 8884193467654481808: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:03:25.135] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:27:0) after 1m0s
[20:04:25.160] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:27:0)
[20:04:25.160] Timer tick, asking Buildbucket for the build status
[20:04:25.365] Build 8884193467654481808: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:04:25.365] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:28:0) after 1m0s
[20:05:25.392] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:28:0)
[20:05:25.392] Timer tick, asking Buildbucket for the build status
[20:05:25.599] Build 8884193467654481808: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:05:25.599] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:29:0) after 1m0s
[20:06:25.628] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:29:0)
[20:06:25.628] Timer tick, asking Buildbucket for the build status
[20:06:25.738] Build 8884193467654481808: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:06:25.738] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:30:0) after 1m0s
[20:07:25.716] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:30:0)
[20:07:25.716] Timer tick, asking Buildbucket for the build status
[20:07:25.877] Build 8884193467654481808: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:07:25.877] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:31:0) after 1m0s
[20:08:25.901] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:31:0)
[20:08:25.901] Timer tick, asking Buildbucket for the build status
[20:08:26.485] Build 8884193467654481808: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:08:26.486] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:32:0) after 1m0s
[20:09:26.533] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:32:0)
[20:09:26.533] Timer tick, asking Buildbucket for the build status
[20:09:27.347] Build 8884193467654481808: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:09:27.347] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:33:0) after 1m0s
[20:10:27.357] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:33:0)
[20:10:27.357] Timer tick, asking Buildbucket for the build status
[20:10:27.651] Build 8884193467654481808: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:10:27.651] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:34:0) after 1m0s
[20:11:27.677] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:34:0)
[20:11:27.677] Timer tick, asking Buildbucket for the build status
[20:11:28.997] Build 8884193467654481808: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:11:28.997] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:35:0) after 1m0s
[20:12:29.119] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:35:0)
[20:12:29.119] Timer tick, asking Buildbucket for the build status
[20:12:29.269] Build 8884193467654481808: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:12:29.269] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:36:0) after 1m0s
[20:13:29.342] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:36:0)
[20:13:29.342] Timer tick, asking Buildbucket for the build status
[20:13:30.082] Build 8884193467654481808: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:13:30.082] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:37:0) after 1m0s
[20:14:30.120] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:37:0)
[20:14:30.120] Timer tick, asking Buildbucket for the build status
[20:14:30.254] Build 8884193467654481808: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:14:30.254] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:38:0) after 1m0s
[20:15:30.340] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:38:0)
[20:15:30.340] Timer tick, asking Buildbucket for the build status
[20:15:30.693] Build 8884193467654481808: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:15:30.693] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:39:0) after 1m0s
[20:16:30.792] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:39:0)
[20:16:30.792] Timer tick, asking Buildbucket for the build status
[20:16:31.117] Build 8884193467654481808: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:16:31.117] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:40:0) after 1m0s
[20:17:31.250] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:40:0)
[20:17:31.250] Timer tick, asking Buildbucket for the build status
[20:17:31.527] Build 8884193467654481808: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:17:31.527] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:41:0) after 1m0s
[20:18:31.674] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:41:0)
[20:18:31.674] Timer tick, asking Buildbucket for the build status
[20:18:32.655] Build 8884193467654481808: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:18:32.655] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:42:0) after 1m0s
[20:19:32.712] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:42:0)
[20:19:32.712] Timer tick, asking Buildbucket for the build status
[20:19:32.876] Build 8884193467654481808: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:19:32.876] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:43:0) after 1m0s
[20:20:32.899] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:43:0)
[20:20:32.899] Timer tick, asking Buildbucket for the build status
[20:20:33.401] Build 8884193467654481808: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:20:33.401] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:44:0) after 1m0s
[20:21:33.430] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:44:0)
[20:21:33.430] Timer tick, asking Buildbucket for the build status
[20:21:33.681] Build 8884193467654481808: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:21:33.681] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:45:0) after 1m0s
[20:22:33.790] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:45:0)
[20:22:33.790] Timer tick, asking Buildbucket for the build status
[20:22:34.266] Build 8884193467654481808: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:22:34.266] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:46:0) after 1m0s
[20:23:34.366] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:46:0)
[20:23:34.366] Timer tick, asking Buildbucket for the build status
[20:23:34.730] Build 8884193467654481808: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:23:34.730] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:47:0) after 1m0s
[20:24:34.901] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:47:0)
[20:24:34.901] Timer tick, asking Buildbucket for the build status
[20:24:36.485] Build 8884193467654481808: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:24:36.485] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:48:0) after 1m0s
[20:25:36.550] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:48:0)
[20:25:36.550] Timer tick, asking Buildbucket for the build status
[20:25:36.726] Build 8884193467654481808: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:25:36.726] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:49:0) after 1m0s
[20:26:36.786] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:49:0)
[20:26:36.786] Timer tick, asking Buildbucket for the build status
[20:26:36.913] Build 8884193467654481808: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:26:36.913] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:50:0) after 1m0s
[20:27:36.998] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:50:0)
[20:27:36.998] Timer tick, asking Buildbucket for the build status
[20:27:37.157] Build 8884193467654481808: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:27:37.157] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:51:0) after 1m0s
[20:28:37.194] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:51:0)
[20:28:37.194] Timer tick, asking Buildbucket for the build status
[20:28:37.513] Build 8884193467654481808: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:28:37.513] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:52:0) after 1m0s
[20:29:37.625] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:52:0)
[20:29:37.625] Timer tick, asking Buildbucket for the build status
[20:29:38.462] Build 8884193467654481808: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:29:38.462] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:53:0) after 1m0s
[20:30:38.537] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:53:0)
[20:30:38.537] Timer tick, asking Buildbucket for the build status
[20:30:39.060] Build 8884193467654481808: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:30:39.060] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:54:0) after 1m0s
[20:31:39.115] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:54:0)
[20:31:39.115] Timer tick, asking Buildbucket for the build status
[20:31:39.702] Build 8884193467654481808: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:31:39.702] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:55:0) after 1m0s
[20:32:39.792] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:55:0)
[20:32:39.792] Timer tick, asking Buildbucket for the build status
[20:32:39.887] Build 8884193467654481808: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:32:39.887] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:56:0) after 1m0s
[20:33:39.934] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:56:0)
[20:33:39.934] Timer tick, asking Buildbucket for the build status
[20:33:40.303] Build 8884193467654481808: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:33:40.303] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:57:0) after 1m0s
[20:34:40.369] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:57:0)
[20:34:40.369] Timer tick, asking Buildbucket for the build status
[20:34:40.567] Build 8884193467654481808: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:34:40.567] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:58:0) after 1m0s
[20:35:40.663] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:58:0)
[20:35:40.663] Timer tick, asking Buildbucket for the build status
[20:35:40.910] Build 8884193467654481808: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:35:40.910] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:59:0) after 1m0s
[20:36:41.003] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:59:0)
[20:36:41.003] Timer tick, asking Buildbucket for the build status
[20:36:41.195] Build 8884193467654481808: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:36:41.195] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:60:0) after 1m0s
[20:37:15.396] Received PubSub notification, asking Buildbucket for the build status
[20:37:15.478] Build 8884193467654481808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:37:41.279] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:60:0)
[20:37:41.279] Timer tick, asking Buildbucket for the build status
[20:37:41.433] Build 8884193467654481808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:37:41.433] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:62:0) after 1m0s
[20:38:41.576] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:62:0)
[20:38:41.576] Timer tick, asking Buildbucket for the build status
[20:38:42.095] Build 8884193467654481808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:38:42.095] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:63:0) after 1m0s
[20:39:42.354] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:63:0)
[20:39:42.354] Timer tick, asking Buildbucket for the build status
[20:39:42.509] Build 8884193467654481808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:39:42.509] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:64:0) after 1m0s
[20:40:42.645] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:64:0)
[20:40:42.645] Timer tick, asking Buildbucket for the build status
[20:40:42.824] Build 8884193467654481808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:40:42.824] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:65:0) after 1m0s
[20:41:42.957] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:65:0)
[20:41:42.957] Timer tick, asking Buildbucket for the build status
[20:41:43.467] Build 8884193467654481808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:41:43.467] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:66:0) after 1m0s
[20:42:43.575] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:66:0)
[20:42:43.575] Timer tick, asking Buildbucket for the build status
[20:42:43.996] Build 8884193467654481808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:42:43.996] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:67:0) after 1m0s
[20:43:44.187] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:67:0)
[20:43:44.187] Timer tick, asking Buildbucket for the build status
[20:43:44.561] Build 8884193467654481808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:43:44.561] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:68:0) after 1m0s
[20:44:44.804] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:68:0)
[20:44:44.804] Timer tick, asking Buildbucket for the build status
[20:44:45.320] Build 8884193467654481808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:44:45.320] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:69:0) after 1m0s
[20:45:46.171] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:69:0)
[20:45:46.171] Timer tick, asking Buildbucket for the build status
[20:45:46.644] Build 8884193467654481808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:45:46.644] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:70:0) after 1m0s
[20:46:46.809] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:70:0)
[20:46:46.809] Timer tick, asking Buildbucket for the build status
[20:46:47.173] Build 8884193467654481808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:46:47.173] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:71:0) after 1m0s
[20:47:47.728] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:71:0)
[20:47:47.728] Timer tick, asking Buildbucket for the build status
[20:47:48.145] Build 8884193467654481808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:47:48.145] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:72:0) after 1m0s
[20:48:48.297] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:72:0)
[20:48:48.297] Timer tick, asking Buildbucket for the build status
[20:48:48.680] Build 8884193467654481808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:48:48.680] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:73:0) after 1m0s
[20:49:49.046] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:73:0)
[20:49:49.046] Timer tick, asking Buildbucket for the build status
[20:49:49.226] Build 8884193467654481808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:49:49.226] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:74:0) after 1m0s
[20:50:49.299] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:74:0)
[20:50:49.299] Timer tick, asking Buildbucket for the build status
[20:50:49.572] Build 8884193467654481808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:50:49.572] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:75:0) after 1m0s
[20:51:49.653] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:75:0)
[20:51:49.653] Timer tick, asking Buildbucket for the build status
[20:51:49.840] Build 8884193467654481808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:51:49.840] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:76:0) after 1m0s
[20:52:50.001] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:76:0)
[20:52:50.001] Timer tick, asking Buildbucket for the build status
[20:52:50.206] Build 8884193467654481808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:52:50.206] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:77:0) after 1m0s
[20:53:50.188] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:77:0)
[20:53:50.188] Timer tick, asking Buildbucket for the build status
[20:53:50.587] Build 8884193467654481808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:53:50.587] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:78:0) after 1m0s
[20:54:50.631] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:78:0)
[20:54:50.631] Timer tick, asking Buildbucket for the build status
[20:54:50.730] Build 8884193467654481808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:54:50.730] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:79:0) after 1m0s
[20:55:50.818] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:79:0)
[20:55:50.818] Timer tick, asking Buildbucket for the build status
[20:55:51.087] Build 8884193467654481808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:55:51.087] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:80:0) after 1m0s
[20:56:51.164] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:80:0)
[20:56:51.164] Timer tick, asking Buildbucket for the build status
[20:56:51.367] Build 8884193467654481808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:56:51.367] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:81:0) after 1m0s
[20:57:51.446] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:81:0)
[20:57:51.446] Timer tick, asking Buildbucket for the build status
[20:57:51.641] Build 8884193467654481808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:57:51.641] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:82:0) after 1m0s
[20:58:51.789] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:82:0)
[20:58:51.789] Timer tick, asking Buildbucket for the build status
[20:58:51.929] Build 8884193467654481808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:58:51.929] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:83:0) after 1m0s
[20:59:52.007] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:83:0)
[20:59:52.007] Timer tick, asking Buildbucket for the build status
[20:59:52.470] Build 8884193467654481808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:59:52.470] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:84:0) after 1m0s
[21:00:52.519] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:84:0)
[21:00:52.519] Timer tick, asking Buildbucket for the build status
[21:00:52.735] Build 8884193467654481808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:00:52.735] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:85:0) after 1m0s
[21:01:52.798] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:85:0)
[21:01:52.798] Timer tick, asking Buildbucket for the build status
[21:01:52.942] Build 8884193467654481808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:01:52.942] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:86:0) after 1m0s
[21:02:52.994] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:86:0)
[21:02:52.994] Timer tick, asking Buildbucket for the build status
[21:02:53.245] Build 8884193467654481808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:02:53.245] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:87:0) after 1m0s
[21:03:53.289] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:87:0)
[21:03:53.289] Timer tick, asking Buildbucket for the build status
[21:03:53.481] Build 8884193467654481808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:03:53.481] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:88:0) after 1m0s
[21:04:53.640] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:88:0)
[21:04:53.640] Timer tick, asking Buildbucket for the build status
[21:04:53.912] Build 8884193467654481808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:04:53.912] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:89:0) after 1m0s
[21:05:54.026] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:89:0)
[21:05:54.026] Timer tick, asking Buildbucket for the build status
[21:05:54.216] Build 8884193467654481808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:05:54.216] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:90:0) after 1m0s
[21:06:54.360] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:90:0)
[21:06:54.360] Timer tick, asking Buildbucket for the build status
[21:06:54.615] Build 8884193467654481808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:06:54.615] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:91:0) after 1m0s
[21:07:54.668] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:91:0)
[21:07:54.668] Timer tick, asking Buildbucket for the build status
[21:07:54.781] Build 8884193467654481808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:07:54.781] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:92:0) after 1m0s
[21:08:54.968] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:92:0)
[21:08:54.968] Timer tick, asking Buildbucket for the build status
[21:08:55.213] Build 8884193467654481808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:08:55.213] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:93:0) after 1m0s
[21:09:55.537] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:93:0)
[21:09:55.537] Timer tick, asking Buildbucket for the build status
[21:09:55.732] Build 8884193467654481808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:09:55.732] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:94:0) after 1m0s
[21:10:55.849] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:94:0)
[21:10:55.849] Timer tick, asking Buildbucket for the build status
[21:10:56.007] Build 8884193467654481808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:10:56.007] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:95:0) after 1m0s
[21:11:56.036] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:95:0)
[21:11:56.036] Timer tick, asking Buildbucket for the build status
[21:11:56.479] Build 8884193467654481808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:11:56.479] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:96:0) after 1m0s
[21:12:56.512] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:96:0)
[21:12:56.512] Timer tick, asking Buildbucket for the build status
[21:12:56.653] Build 8884193467654481808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:12:56.653] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:97:0) after 1m0s
[21:13:56.692] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:97:0)
[21:13:56.692] Timer tick, asking Buildbucket for the build status
[21:13:56.858] Build 8884193467654481808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:13:56.858] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:98:0) after 1m0s
[21:14:57.474] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:98:0)
[21:14:57.474] Timer tick, asking Buildbucket for the build status
[21:14:57.639] Build 8884193467654481808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:14:57.639] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:99:0) after 1m0s
[21:15:57.942] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:99:0)
[21:15:57.942] Timer tick, asking Buildbucket for the build status
[21:15:58.627] Build 8884193467654481808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:15:58.627] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:100:0) after 1m0s
[21:16:58.733] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:100:0)
[21:16:58.733] Timer tick, asking Buildbucket for the build status
[21:16:59.079] Build 8884193467654481808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:16:59.079] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:101:0) after 1m0s
[21:17:59.090] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:101:0)
[21:17:59.090] Timer tick, asking Buildbucket for the build status
[21:17:59.262] Build 8884193467654481808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:17:59.262] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:102:0) after 1m0s
[21:18:59.358] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:102:0)
[21:18:59.358] Timer tick, asking Buildbucket for the build status
[21:18:59.581] Build 8884193467654481808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:18:59.581] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:103:0) after 1m0s
[21:19:59.624] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:103:0)
[21:19:59.624] Timer tick, asking Buildbucket for the build status
[21:19:59.765] Build 8884193467654481808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:19:59.765] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:104:0) after 1m0s
[21:20:59.802] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:104:0)
[21:20:59.802] Timer tick, asking Buildbucket for the build status
[21:21:00.092] Build 8884193467654481808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:21:00.092] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:105:0) after 1m0s
[21:22:00.248] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:105:0)
[21:22:00.248] Timer tick, asking Buildbucket for the build status
[21:22:00.501] Build 8884193467654481808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:22:00.501] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:106:0) after 1m0s
[21:23:00.660] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:106:0)
[21:23:00.660] Timer tick, asking Buildbucket for the build status
[21:23:01.546] Build 8884193467654481808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:23:01.546] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:107:0) after 1m0s
[21:24:01.604] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:107:0)
[21:24:01.604] Timer tick, asking Buildbucket for the build status
[21:24:01.944] Build 8884193467654481808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:24:01.945] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:108:0) after 1m0s
[21:25:02.058] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:108:0)
[21:25:02.058] Timer tick, asking Buildbucket for the build status
[21:25:02.666] Build 8884193467654481808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:25:02.666] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:109:0) after 1m0s
[21:26:02.846] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:109:0)
[21:26:02.846] Timer tick, asking Buildbucket for the build status
[21:26:03.231] Build 8884193467654481808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:26:03.231] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:110:0) after 1m0s
[21:27:03.331] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:110:0)
[21:27:03.331] Timer tick, asking Buildbucket for the build status
[21:27:03.804] Build 8884193467654481808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:27:03.804] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:111:0) after 1m0s
[21:28:03.874] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:111:0)
[21:28:03.874] Timer tick, asking Buildbucket for the build status
[21:28:04.117] Build 8884193467654481808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:28:04.117] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:112:0) after 1m0s
[21:29:04.303] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:112:0)
[21:29:04.303] Timer tick, asking Buildbucket for the build status
[21:29:04.583] Build 8884193467654481808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:29:04.583] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:113:0) after 1m0s
[21:30:04.804] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:113:0)
[21:30:04.804] Timer tick, asking Buildbucket for the build status
[21:30:04.955] Build 8884193467654481808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:30:04.955] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:114:0) after 1m0s
[21:31:05.221] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:114:0)
[21:31:05.221] Timer tick, asking Buildbucket for the build status
[21:31:05.465] Build 8884193467654481808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:31:05.465] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:115:0) after 1m0s
[21:32:05.526] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:115:0)
[21:32:05.526] Timer tick, asking Buildbucket for the build status
[21:32:05.777] Build 8884193467654481808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:32:05.777] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:116:0) after 1m0s
[21:33:05.913] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:116:0)
[21:33:05.913] Timer tick, asking Buildbucket for the build status
[21:33:06.095] Build 8884193467654481808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:33:06.095] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:117:0) after 1m0s
[21:34:06.393] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:117:0)
[21:34:06.393] Timer tick, asking Buildbucket for the build status
[21:34:06.765] Build 8884193467654481808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:34:06.765] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:118:0) after 1m0s
[21:35:07.115] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:118:0)
[21:35:07.115] Timer tick, asking Buildbucket for the build status
[21:35:07.325] Build 8884193467654481808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:35:07.325] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:119:0) after 1m0s
[21:36:07.518] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:119:0)
[21:36:07.518] Timer tick, asking Buildbucket for the build status
[21:36:07.783] Build 8884193467654481808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:36:07.783] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:120:0) after 1m0s
[21:37:08.187] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:120:0)
[21:37:08.187] Timer tick, asking Buildbucket for the build status
[21:37:08.647] Build 8884193467654481808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:37:08.647] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:121:0) after 1m0s
[21:38:08.679] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:121:0)
[21:38:08.679] Timer tick, asking Buildbucket for the build status
[21:38:08.821] Build 8884193467654481808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:38:08.821] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:122:0) after 1m0s
[21:39:08.976] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:122:0)
[21:39:08.976] Timer tick, asking Buildbucket for the build status
[21:39:09.165] Build 8884193467654481808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:39:09.165] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:123:0) after 1m0s
[21:40:09.299] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:123:0)
[21:40:09.299] Timer tick, asking Buildbucket for the build status
[21:40:09.611] Build 8884193467654481808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:40:09.611] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:124:0) after 1m0s
[21:41:09.830] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:124:0)
[21:41:09.830] Timer tick, asking Buildbucket for the build status
[21:41:10.092] Build 8884193467654481808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:41:10.092] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:125:0) after 1m0s
[21:42:10.273] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:125:0)
[21:42:10.273] Timer tick, asking Buildbucket for the build status
[21:42:10.561] Build 8884193467654481808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:42:10.561] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:126:0) after 1m0s
[21:43:10.646] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:126:0)
[21:43:10.646] Timer tick, asking Buildbucket for the build status
[21:43:11.088] Build 8884193467654481808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:43:11.088] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:127:0) after 1m0s
[21:44:11.179] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:127:0)
[21:44:11.179] Timer tick, asking Buildbucket for the build status
[21:44:12.186] Build 8884193467654481808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:44:12.186] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:128:0) after 1m0s
[21:45:12.268] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:128:0)
[21:45:12.268] Timer tick, asking Buildbucket for the build status
[21:45:12.620] Build 8884193467654481808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:45:12.620] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:129:0) after 1m0s
[21:46:12.680] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:129:0)
[21:46:12.681] Timer tick, asking Buildbucket for the build status
[21:46:12.987] Build 8884193467654481808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:46:12.987] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:130:0) after 1m0s
[21:47:13.125] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:130:0)
[21:47:13.125] Timer tick, asking Buildbucket for the build status
[21:47:13.259] Build 8884193467654481808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:47:13.259] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:131:0) after 1m0s
[21:48:13.277] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:131:0)
[21:48:13.277] Timer tick, asking Buildbucket for the build status
[21:48:13.607] Build 8884193467654481808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:48:13.607] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:132:0) after 1m0s
[21:49:13.708] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:132:0)
[21:49:13.708] Timer tick, asking Buildbucket for the build status
[21:49:14.288] Build 8884193467654481808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:49:14.288] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:133:0) after 1m0s
[21:50:14.346] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:133:0)
[21:50:14.347] Timer tick, asking Buildbucket for the build status
[21:50:14.513] Build 8884193467654481808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:50:14.513] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:134:0) after 1m0s
[21:51:14.585] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:134:0)
[21:51:14.585] Timer tick, asking Buildbucket for the build status
[21:51:14.809] Build 8884193467654481808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:51:14.809] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:135:0) after 1m0s
[21:52:14.841] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:135:0)
[21:52:14.842] Timer tick, asking Buildbucket for the build status
[21:52:14.996] Build 8884193467654481808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:52:14.996] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:136:0) after 1m0s
[21:53:15.055] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:136:0)
[21:53:15.055] Timer tick, asking Buildbucket for the build status
[21:53:15.367] Build 8884193467654481808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:53:15.367] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:137:0) after 1m0s
[21:54:15.476] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:137:0)
[21:54:15.476] Timer tick, asking Buildbucket for the build status
[21:54:15.646] Build 8884193467654481808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:54:15.647] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:138:0) after 1m0s
[21:55:16.030] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:138:0)
[21:55:16.030] Timer tick, asking Buildbucket for the build status
[21:55:16.314] Build 8884193467654481808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:55:16.314] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:139:0) after 1m0s
[21:56:16.453] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:139:0)
[21:56:16.453] Timer tick, asking Buildbucket for the build status
[21:56:16.652] Build 8884193467654481808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:56:16.652] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:140:0) after 1m0s
[21:57:16.742] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:140:0)
[21:57:16.742] Timer tick, asking Buildbucket for the build status
[21:57:18.202] Build 8884193467654481808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:57:18.202] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:141:0) after 1m0s
[21:58:18.255] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:141:0)
[21:58:18.255] Timer tick, asking Buildbucket for the build status
[21:58:18.941] Build 8884193467654481808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:58:18.941] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049623530558598080:142:0) after 1m0s
[21:58:25.609] Received PubSub notification, asking Buildbucket for the build status
[21:58:25.801] Build 8884193467654481808: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[21:58:25.801] Buildbucket build:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary_preference": "PROD",
"completed_ts": "1585778304786512",
"created_by": "project:emscripten-releases",
"created_ts": "1585769890121851",
"id": "8884193467654481808",
"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\": \"4e399b1c58f854d137a7248f8b732895441c283e\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@4e399b1c58f854d137a7248f8b732895441c283e\", \"title\": \"4e399b1c58f854d137a7248f8b732895441c283e\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/4e399b1c58f854d137a7248f8b732895441c283e\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"4e399b1c58f854d137a7248f8b732895441c283e\"}}",
"project": "emscripten-releases",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$recipe_engine/path\": {\"cache_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\cache\", \"temp_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\tmp\\\\rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"4e399b1c58f854d137a7248f8b732895441c283e\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@4e399b1c58f854d137a7248f8b732895441c283e\", \"title\": \"4e399b1c58f854d137a7248f8b732895441c283e\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/4e399b1c58f854d137a7248f8b732895441c283e\"}]}, \"bot_id\": \"luci-emscripten-releases-ci-win10-0-epb0\", \"branch\": \"refs/heads/master\", \"buildername\": \"win\", \"got_revision\": \"4e399b1c58f854d137a7248f8b732895441c283e\", \"path_config\": \"generic\", \"recipe\": \"emscripten_releases\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"4e399b1c58f854d137a7248f8b732895441c283e\"}, \"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-epb0\"], \"image\": [\"chrome-win10-1703-20032700-43453ad22a9\"], \"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\": [\"4916-b2318d4\"], \"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": "1585773434633189",
"status": "COMPLETED",
"status_changed_ts": "1585778305269241",
"tags": [
"builder:win",
"buildset:commit/git/4e399b1c58f854d137a7248f8b732895441c283e",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/4e399b1c58f854d137a7248f8b732895441c283e",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9049623530558598080",
"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/8884193467654481808/+/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:4b5011cf551c2210",
"user_agent:luci-scheduler"
],
"updated_ts": "1585778305286475",
"url": "https://ci.chromium.org/b/8884193467654481808",
"utcnow_ts": "1585778305780170"
}
}
[21:58:25.801] Invocation finished in 2h20m17.833497299s with status SUCCEEDED