[21:49:58.455] New invocation is queued and will start shortly
[21:49:59.553] Starting the invocation (attempt 1)
[21:49:59.592] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[21:49:59.592] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[21:49:59.592] Buildbucket request:
{
"bucket": "luci.emscripten-releases.ci",
"client_operation_id": "9050974190324511680",
"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\":\"c6bffe2520cd35f2b42dceb4e75b5bbf4160004f\"},\"id\":\"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@c6bffe2520cd35f2b42dceb4e75b5bbf4160004f\",\"title\":\"c6bffe2520cd35f2b42dceb4e75b5bbf4160004f\",\"url\":\"https://chromium.googlesource.com/emscripten-releases/+/c6bffe2520cd35f2b42dceb4e75b5bbf4160004f\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/emscripten-releases\",\"revision\":\"c6bffe2520cd35f2b42dceb4e75b5bbf4160004f\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win",
"scheduler_invocation_id:9050974190324511680",
"scheduler_job_id:emscripten-releases/win",
"user_agent:luci-scheduler",
"buildset:commit/git/c6bffe2520cd35f2b42dceb4e75b5bbf4160004f",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/c6bffe2520cd35f2b42dceb4e75b5bbf4160004f",
"gitiles_ref:refs/heads/master"
]
}
[21:50:01.547] Buildbucket response:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary_preference": "PROD",
"created_by": "project:emscripten-releases",
"created_ts": "1584481799891162",
"id": "8885544128155778880",
"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\": \"c6bffe2520cd35f2b42dceb4e75b5bbf4160004f\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@c6bffe2520cd35f2b42dceb4e75b5bbf4160004f\", \"title\": \"c6bffe2520cd35f2b42dceb4e75b5bbf4160004f\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/c6bffe2520cd35f2b42dceb4e75b5bbf4160004f\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"c6bffe2520cd35f2b42dceb4e75b5bbf4160004f\"}}",
"project": "emscripten-releases",
"result_details_json": "{\"properties\": {}}",
"service_account": "emscripten-releases-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1584481801063018",
"tags": [
"builder:win",
"buildset:commit/git/c6bffe2520cd35f2b42dceb4e75b5bbf4160004f",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/c6bffe2520cd35f2b42dceb4e75b5bbf4160004f",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9050974190324511680",
"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/8885544128155778880/+/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": "1584481801063166",
"url": "https://ci.chromium.org/b/8885544128155778880",
"utcnow_ts": "1584481801511400"
}
}
[21:50:01.547] Task URL: https://ci.chromium.org/b/8885544128155778880
[21:50:01.547] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:2:0) after 1m0s
[21:51:01.767] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:2:0)
[21:51:01.767] Timer tick, asking Buildbucket for the build status
[21:51:02.358] Build 8885544128155778880: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:51:02.359] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:3:0) after 1m0s
[21:52:02.537] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:3:0)
[21:52:02.537] Timer tick, asking Buildbucket for the build status
[21:52:03.039] Build 8885544128155778880: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:52:03.039] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:4:0) after 1m0s
[21:53:03.071] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:4:0)
[21:53:03.071] Timer tick, asking Buildbucket for the build status
[21:53:03.506] Build 8885544128155778880: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:53:03.506] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:5:0) after 1m0s
[21:54:03.536] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:5:0)
[21:54:03.536] Timer tick, asking Buildbucket for the build status
[21:54:04.013] Build 8885544128155778880: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:54:04.013] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:6:0) after 1m0s
[21:55:04.043] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:6:0)
[21:55:04.043] Timer tick, asking Buildbucket for the build status
[21:55:04.567] Build 8885544128155778880: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:55:04.567] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:7:0) after 1m0s
[21:56:04.617] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:7:0)
[21:56:04.617] Timer tick, asking Buildbucket for the build status
[21:56:05.349] Build 8885544128155778880: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:56:05.349] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:8:0) after 1m0s
[21:57:05.691] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:8:0)
[21:57:05.691] Timer tick, asking Buildbucket for the build status
[21:57:05.934] Build 8885544128155778880: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:57:05.934] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:9:0) after 1m0s
[21:58:06.029] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:9:0)
[21:58:06.029] Timer tick, asking Buildbucket for the build status
[21:58:06.927] Build 8885544128155778880: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:58:06.927] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:10:0) after 1m0s
[21:59:07.194] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:10:0)
[21:59:07.194] Timer tick, asking Buildbucket for the build status
[21:59:07.679] Build 8885544128155778880: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:59:07.679] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:11:0) after 1m0s
[22:00:07.784] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:11:0)
[22:00:07.784] Timer tick, asking Buildbucket for the build status
[22:00:07.928] Build 8885544128155778880: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:00:07.928] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:12:0) after 1m0s
[22:01:08.506] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:12:0)
[22:01:08.506] Timer tick, asking Buildbucket for the build status
[22:01:08.697] Build 8885544128155778880: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:01:08.697] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:13:0) after 1m0s
[22:02:08.832] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:13:0)
[22:02:08.832] Timer tick, asking Buildbucket for the build status
[22:02:10.020] Build 8885544128155778880: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:02:10.020] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:14:0) after 1m0s
[22:03:10.307] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:14:0)
[22:03:10.307] Timer tick, asking Buildbucket for the build status
[22:03:11.814] Build 8885544128155778880: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:03:11.814] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:15:0) after 1m0s
[22:04:11.808] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:15:0)
[22:04:11.808] Timer tick, asking Buildbucket for the build status
[22:04:12.110] Build 8885544128155778880: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:04:12.110] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:16:0) after 1m0s
[22:05:12.199] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:16:0)
[22:05:12.199] Timer tick, asking Buildbucket for the build status
[22:05:13.448] Build 8885544128155778880: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:05:13.448] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:17:0) after 1m0s
[22:06:13.429] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:17:0)
[22:06:13.429] Timer tick, asking Buildbucket for the build status
[22:06:14.671] Build 8885544128155778880: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:06:14.671] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:18:0) after 1m0s
[22:07:14.728] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:18:0)
[22:07:14.728] Timer tick, asking Buildbucket for the build status
[22:07:15.467] Build 8885544128155778880: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:07:15.467] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:19:0) after 1m0s
[22:08:15.579] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:19:0)
[22:08:15.579] Timer tick, asking Buildbucket for the build status
[22:08:16.099] Build 8885544128155778880: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:08:16.099] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:20:0) after 1m0s
[22:09:16.393] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:20:0)
[22:09:16.393] Timer tick, asking Buildbucket for the build status
[22:09:16.713] Build 8885544128155778880: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:09:16.713] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:21:0) after 1m0s
[22:10:17.134] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:21:0)
[22:10:17.134] Timer tick, asking Buildbucket for the build status
[22:10:17.264] Build 8885544128155778880: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:10:17.264] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:22:0) after 1m0s
[22:11:17.303] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:22:0)
[22:11:17.303] Timer tick, asking Buildbucket for the build status
[22:11:17.630] Build 8885544128155778880: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:11:17.630] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:23:0) after 1m0s
[22:12:17.888] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:23:0)
[22:12:17.888] Timer tick, asking Buildbucket for the build status
[22:12:18.064] Build 8885544128155778880: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:12:18.064] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:24:0) after 1m0s
[22:13:18.271] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:24:0)
[22:13:18.271] Timer tick, asking Buildbucket for the build status
[22:13:18.377] Build 8885544128155778880: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:13:18.377] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:25:0) after 1m0s
[22:14:18.662] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:25:0)
[22:14:18.662] Timer tick, asking Buildbucket for the build status
[22:14:19.285] Build 8885544128155778880: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:14:19.285] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:26:0) after 1m0s
[22:15:19.326] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:26:0)
[22:15:19.326] Timer tick, asking Buildbucket for the build status
[22:15:20.218] Build 8885544128155778880: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:15:20.218] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:27:0) after 1m0s
[22:16:20.323] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:27:0)
[22:16:20.323] Timer tick, asking Buildbucket for the build status
[22:16:20.478] Build 8885544128155778880: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:16:20.478] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:28:0) after 1m0s
[22:17:20.585] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:28:0)
[22:17:20.585] Timer tick, asking Buildbucket for the build status
[22:17:21.443] Build 8885544128155778880: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:17:21.443] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:29:0) after 1m0s
[22:18:21.512] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:29:0)
[22:18:21.512] Timer tick, asking Buildbucket for the build status
[22:18:21.966] Build 8885544128155778880: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:18:21.966] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:30:0) after 1m0s
[22:19:22.050] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:30:0)
[22:19:22.050] Timer tick, asking Buildbucket for the build status
[22:19:22.724] Build 8885544128155778880: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:19:22.724] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:31:0) after 1m0s
[22:20:22.786] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:31:0)
[22:20:22.786] Timer tick, asking Buildbucket for the build status
[22:20:23.153] Build 8885544128155778880: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:20:23.153] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:32:0) after 1m0s
[22:21:23.221] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:32:0)
[22:21:23.221] Timer tick, asking Buildbucket for the build status
[22:21:23.506] Build 8885544128155778880: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:21:23.506] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:33:0) after 1m0s
[22:22:23.633] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:33:0)
[22:22:23.633] Timer tick, asking Buildbucket for the build status
[22:22:24.022] Build 8885544128155778880: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:22:24.022] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:34:0) after 1m0s
[22:23:24.041] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:34:0)
[22:23:24.041] Timer tick, asking Buildbucket for the build status
[22:23:24.726] Build 8885544128155778880: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:23:24.726] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:35:0) after 1m0s
[22:24:24.749] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:35:0)
[22:24:24.749] Timer tick, asking Buildbucket for the build status
[22:24:25.896] Build 8885544128155778880: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:24:25.896] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:36:0) after 1m0s
[22:25:25.944] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:36:0)
[22:25:25.944] Timer tick, asking Buildbucket for the build status
[22:25:26.747] Build 8885544128155778880: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:25:26.747] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:37:0) after 1m0s
[22:26:26.874] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:37:0)
[22:26:26.874] Timer tick, asking Buildbucket for the build status
[22:26:27.044] Build 8885544128155778880: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:26:27.044] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:38:0) after 1m0s
[22:27:27.155] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:38:0)
[22:27:27.155] Timer tick, asking Buildbucket for the build status
[22:27:27.367] Build 8885544128155778880: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:27:27.367] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:39:0) after 1m0s
[22:28:27.428] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:39:0)
[22:28:27.428] Timer tick, asking Buildbucket for the build status
[22:28:27.517] Build 8885544128155778880: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:28:27.517] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:40:0) after 1m0s
[22:29:27.577] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:40:0)
[22:29:27.577] Timer tick, asking Buildbucket for the build status
[22:29:28.746] Build 8885544128155778880: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:29:28.746] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:41:0) after 1m0s
[22:30:28.856] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:41:0)
[22:30:28.856] Timer tick, asking Buildbucket for the build status
[22:30:28.987] Build 8885544128155778880: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:30:28.987] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:42:0) after 1m0s
[22:31:29.041] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:42:0)
[22:31:29.041] Timer tick, asking Buildbucket for the build status
[22:31:29.201] Build 8885544128155778880: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:31:29.201] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:43:0) after 1m0s
[22:32:29.267] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:43:0)
[22:32:29.267] Timer tick, asking Buildbucket for the build status
[22:32:29.381] Build 8885544128155778880: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:32:29.381] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:44:0) after 1m0s
[22:33:29.488] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:44:0)
[22:33:29.488] Timer tick, asking Buildbucket for the build status
[22:33:29.686] Build 8885544128155778880: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:33:29.686] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:45:0) after 1m0s
[22:34:29.767] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:45:0)
[22:34:29.767] Timer tick, asking Buildbucket for the build status
[22:34:30.149] Build 8885544128155778880: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:34:30.149] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:46:0) after 1m0s
[22:35:30.185] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:46:0)
[22:35:30.185] Timer tick, asking Buildbucket for the build status
[22:35:30.437] Build 8885544128155778880: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:35:30.437] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:47:0) after 1m0s
[22:36:30.447] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:47:0)
[22:36:30.447] Timer tick, asking Buildbucket for the build status
[22:36:30.555] Build 8885544128155778880: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:36:30.555] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:48:0) after 1m0s
[22:37:30.645] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:48:0)
[22:37:30.645] Timer tick, asking Buildbucket for the build status
[22:37:30.775] Build 8885544128155778880: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:37:30.775] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:49:0) after 1m0s
[22:38:30.748] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:49:0)
[22:38:30.748] Timer tick, asking Buildbucket for the build status
[22:38:30.969] Build 8885544128155778880: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:38:30.969] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:50:0) after 1m0s
[22:39:31.039] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:50:0)
[22:39:31.039] Timer tick, asking Buildbucket for the build status
[22:39:31.946] Build 8885544128155778880: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:39:31.946] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:51:0) after 1m0s
[22:40:32.056] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:51:0)
[22:40:32.056] Timer tick, asking Buildbucket for the build status
[22:40:32.606] Build 8885544128155778880: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:40:32.606] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:52:0) after 1m0s
[22:41:32.625] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:52:0)
[22:41:32.625] Timer tick, asking Buildbucket for the build status
[22:41:33.242] Build 8885544128155778880: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:41:33.242] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:53:0) after 1m0s
[22:42:33.318] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:53:0)
[22:42:33.318] Timer tick, asking Buildbucket for the build status
[22:42:33.488] Build 8885544128155778880: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:42:33.488] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:54:0) after 1m0s
[22:43:33.519] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:54:0)
[22:43:33.519] Timer tick, asking Buildbucket for the build status
[22:43:33.785] Build 8885544128155778880: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:43:33.785] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:55:0) after 1m0s
[22:44:33.826] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:55:0)
[22:44:33.826] Timer tick, asking Buildbucket for the build status
[22:44:35.988] Build 8885544128155778880: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:44:35.988] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:56:0) after 1m0s
[22:45:19.109] Received PubSub notification, asking Buildbucket for the build status
[22:45:19.270] Build 8885544128155778880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:45:36.156] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:56:0)
[22:45:36.156] Timer tick, asking Buildbucket for the build status
[22:45:37.066] Build 8885544128155778880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:45:37.066] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:58:0) after 1m0s
[22:46:37.168] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:58:0)
[22:46:37.168] Timer tick, asking Buildbucket for the build status
[22:46:37.414] Build 8885544128155778880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:46:37.414] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:59:0) after 1m0s
[22:47:37.458] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:59:0)
[22:47:37.458] Timer tick, asking Buildbucket for the build status
[22:47:37.654] Build 8885544128155778880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:47:37.654] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:60:0) after 1m0s
[22:48:37.781] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:60:0)
[22:48:37.781] Timer tick, asking Buildbucket for the build status
[22:48:37.944] Build 8885544128155778880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:48:37.944] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:61:0) after 1m0s
[22:49:38.120] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:61:0)
[22:49:38.120] Timer tick, asking Buildbucket for the build status
[22:49:38.267] Build 8885544128155778880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:49:38.267] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:62:0) after 1m0s
[22:50:38.305] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:62:0)
[22:50:38.305] Timer tick, asking Buildbucket for the build status
[22:50:38.442] Build 8885544128155778880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:50:38.442] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:63:0) after 1m0s
[22:51:38.513] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:63:0)
[22:51:38.513] Timer tick, asking Buildbucket for the build status
[22:51:38.699] Build 8885544128155778880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:51:38.699] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:64:0) after 1m0s
[22:52:38.859] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:64:0)
[22:52:38.859] Timer tick, asking Buildbucket for the build status
[22:52:39.063] Build 8885544128155778880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:52:39.063] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:65:0) after 1m0s
[22:53:39.138] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:65:0)
[22:53:39.138] Timer tick, asking Buildbucket for the build status
[22:53:39.332] Build 8885544128155778880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:53:39.332] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:66:0) after 1m0s
[22:54:39.394] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:66:0)
[22:54:39.394] Timer tick, asking Buildbucket for the build status
[22:54:40.917] Build 8885544128155778880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:54:40.917] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:67:0) after 1m0s
[22:55:41.174] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:67:0)
[22:55:41.174] Timer tick, asking Buildbucket for the build status
[22:55:42.373] Build 8885544128155778880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:55:42.373] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:68:0) after 1m0s
[22:56:42.552] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:68:0)
[22:56:42.552] Timer tick, asking Buildbucket for the build status
[22:56:42.731] Build 8885544128155778880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:56:42.731] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:69:0) after 1m0s
[22:57:42.914] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:69:0)
[22:57:42.914] Timer tick, asking Buildbucket for the build status
[22:57:43.915] Build 8885544128155778880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:57:43.915] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:70:0) after 1m0s
[22:58:43.985] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:70:0)
[22:58:43.985] Timer tick, asking Buildbucket for the build status
[22:58:44.292] Build 8885544128155778880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:58:44.292] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:71:0) after 1m0s
[22:59:44.444] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:71:0)
[22:59:44.445] Timer tick, asking Buildbucket for the build status
[22:59:44.651] Build 8885544128155778880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:59:44.651] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:72:0) after 1m0s
[23:00:44.899] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:72:0)
[23:00:44.899] Timer tick, asking Buildbucket for the build status
[23:00:45.339] Build 8885544128155778880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:00:45.339] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:73:0) after 1m0s
[23:01:45.675] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:73:0)
[23:01:45.675] Timer tick, asking Buildbucket for the build status
[23:01:45.846] Build 8885544128155778880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:01:45.846] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:74:0) after 1m0s
[23:02:46.025] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:74:0)
[23:02:46.025] Timer tick, asking Buildbucket for the build status
[23:02:46.279] Build 8885544128155778880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:02:46.279] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:75:0) after 1m0s
[23:03:46.517] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:75:0)
[23:03:46.517] Timer tick, asking Buildbucket for the build status
[23:03:46.769] Build 8885544128155778880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:03:46.769] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:76:0) after 1m0s
[23:04:46.835] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:76:0)
[23:04:46.835] Timer tick, asking Buildbucket for the build status
[23:04:47.053] Build 8885544128155778880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:04:47.053] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:77:0) after 1m0s
[23:05:47.152] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:77:0)
[23:05:47.152] Timer tick, asking Buildbucket for the build status
[23:05:47.286] Build 8885544128155778880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:05:47.286] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:78:0) after 1m0s
[23:06:47.364] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:78:0)
[23:06:47.364] Timer tick, asking Buildbucket for the build status
[23:06:47.525] Build 8885544128155778880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:06:47.525] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:79:0) after 1m0s
[23:07:47.587] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:79:0)
[23:07:47.587] Timer tick, asking Buildbucket for the build status
[23:07:47.786] Build 8885544128155778880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:07:47.786] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:80:0) after 1m0s
[23:08:47.863] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:80:0)
[23:08:47.863] Timer tick, asking Buildbucket for the build status
[23:08:48.142] Build 8885544128155778880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:08:48.142] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:81:0) after 1m0s
[23:09:48.237] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:81:0)
[23:09:48.237] Timer tick, asking Buildbucket for the build status
[23:09:49.427] Build 8885544128155778880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:09:49.427] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:82:0) after 1m0s
[23:10:49.557] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:82:0)
[23:10:49.557] Timer tick, asking Buildbucket for the build status
[23:10:49.700] Build 8885544128155778880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:10:49.700] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:83:0) after 1m0s
[23:11:49.805] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:83:0)
[23:11:49.805] Timer tick, asking Buildbucket for the build status
[23:11:50.936] Build 8885544128155778880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:11:50.936] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:84:0) after 1m0s
[23:12:51.243] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:84:0)
[23:12:51.243] Timer tick, asking Buildbucket for the build status
[23:12:51.489] Build 8885544128155778880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:12:51.489] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:85:0) after 1m0s
[23:13:51.538] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:85:0)
[23:13:51.538] Timer tick, asking Buildbucket for the build status
[23:13:51.919] Build 8885544128155778880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:13:51.919] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:86:0) after 1m0s
[23:14:52.190] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:86:0)
[23:14:52.190] Timer tick, asking Buildbucket for the build status
[23:14:52.432] Build 8885544128155778880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:14:52.432] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:87:0) after 1m0s
[23:15:52.498] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:87:0)
[23:15:52.498] Timer tick, asking Buildbucket for the build status
[23:15:53.233] Build 8885544128155778880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:15:53.233] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:88:0) after 1m0s
[23:16:53.268] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:88:0)
[23:16:53.268] Timer tick, asking Buildbucket for the build status
[23:16:53.437] Build 8885544128155778880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:16:53.437] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:89:0) after 1m0s
[23:17:53.462] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:89:0)
[23:17:53.462] Timer tick, asking Buildbucket for the build status
[23:17:53.830] Build 8885544128155778880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:17:53.830] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:90:0) after 1m0s
[23:18:53.851] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:90:0)
[23:18:53.851] Timer tick, asking Buildbucket for the build status
[23:18:54.092] Build 8885544128155778880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:18:54.092] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:91:0) after 1m0s
[23:19:54.218] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:91:0)
[23:19:54.218] Timer tick, asking Buildbucket for the build status
[23:19:54.396] Build 8885544128155778880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:19:54.396] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:92:0) after 1m0s
[23:20:54.527] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:92:0)
[23:20:54.527] Timer tick, asking Buildbucket for the build status
[23:20:54.855] Build 8885544128155778880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:20:54.855] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:93:0) after 1m0s
[23:21:54.932] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:93:0)
[23:21:54.932] Timer tick, asking Buildbucket for the build status
[23:21:55.114] Build 8885544128155778880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:21:55.114] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:94:0) after 1m0s
[23:22:55.118] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:94:0)
[23:22:55.118] Timer tick, asking Buildbucket for the build status
[23:22:55.492] Build 8885544128155778880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:22:55.492] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:95:0) after 1m0s
[23:23:55.577] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:95:0)
[23:23:55.577] Timer tick, asking Buildbucket for the build status
[23:23:56.028] Build 8885544128155778880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:23:56.028] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:96:0) after 1m0s
[23:24:56.065] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:96:0)
[23:24:56.065] Timer tick, asking Buildbucket for the build status
[23:24:56.563] Build 8885544128155778880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:24:56.563] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:97:0) after 1m0s
[23:25:56.637] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:97:0)
[23:25:56.637] Timer tick, asking Buildbucket for the build status
[23:25:56.998] Build 8885544128155778880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:25:56.998] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:98:0) after 1m0s
[23:26:57.063] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:98:0)
[23:26:57.063] Timer tick, asking Buildbucket for the build status
[23:26:57.205] Build 8885544128155778880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:26:57.205] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:99:0) after 1m0s
[23:27:57.229] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:99:0)
[23:27:57.229] Timer tick, asking Buildbucket for the build status
[23:27:57.515] Build 8885544128155778880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:27:57.515] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:100:0) after 1m0s
[23:28:57.529] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:100:0)
[23:28:57.529] Timer tick, asking Buildbucket for the build status
[23:28:57.888] Build 8885544128155778880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:28:57.888] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:101:0) after 1m0s
[23:29:57.893] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:101:0)
[23:29:57.893] Timer tick, asking Buildbucket for the build status
[23:29:58.001] Build 8885544128155778880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:29:58.001] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:102:0) after 1m0s
[23:30:58.030] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:102:0)
[23:30:58.030] Timer tick, asking Buildbucket for the build status
[23:30:58.170] Build 8885544128155778880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:30:58.170] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:103:0) after 1m0s
[23:31:58.170] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:103:0)
[23:31:58.170] Timer tick, asking Buildbucket for the build status
[23:31:58.280] Build 8885544128155778880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:31:58.280] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:104:0) after 1m0s
[23:32:58.388] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:104:0)
[23:32:58.388] Timer tick, asking Buildbucket for the build status
[23:32:58.948] Build 8885544128155778880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:32:58.948] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:105:0) after 1m0s
[23:33:59.032] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:105:0)
[23:33:59.032] Timer tick, asking Buildbucket for the build status
[23:33:59.272] Build 8885544128155778880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:33:59.272] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:106:0) after 1m0s
[23:34:59.379] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:106:0)
[23:34:59.380] Timer tick, asking Buildbucket for the build status
[23:34:59.898] Build 8885544128155778880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:34:59.898] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:107:0) after 1m0s
[23:36:00.237] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:107:0)
[23:36:00.237] Timer tick, asking Buildbucket for the build status
[23:36:00.685] Build 8885544128155778880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:36:00.685] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:108:0) after 1m0s
[23:37:00.812] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:108:0)
[23:37:00.812] Timer tick, asking Buildbucket for the build status
[23:37:01.142] Build 8885544128155778880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:37:01.142] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:109:0) after 1m0s
[23:38:01.202] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:109:0)
[23:38:01.202] Timer tick, asking Buildbucket for the build status
[23:38:01.365] Build 8885544128155778880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:38:01.365] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:110:0) after 1m0s
[23:39:01.445] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:110:0)
[23:39:01.445] Timer tick, asking Buildbucket for the build status
[23:39:01.636] Build 8885544128155778880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:39:01.637] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:111:0) after 1m0s
[23:40:01.731] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:111:0)
[23:40:01.731] Timer tick, asking Buildbucket for the build status
[23:40:02.403] Build 8885544128155778880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:40:02.403] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:112:0) after 1m0s
[23:41:02.562] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:112:0)
[23:41:02.562] Timer tick, asking Buildbucket for the build status
[23:41:02.778] Build 8885544128155778880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:41:02.778] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:113:0) after 1m0s
[23:42:02.820] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:113:0)
[23:42:02.820] Timer tick, asking Buildbucket for the build status
[23:42:03.399] Build 8885544128155778880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:42:03.399] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:114:0) after 1m0s
[23:43:03.404] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:114:0)
[23:43:03.404] Timer tick, asking Buildbucket for the build status
[23:43:03.989] Build 8885544128155778880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:43:03.989] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:115:0) after 1m0s
[23:44:04.011] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:115:0)
[23:44:04.011] Timer tick, asking Buildbucket for the build status
[23:44:04.281] Build 8885544128155778880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:44:04.281] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:116:0) after 1m0s
[23:45:04.398] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:116:0)
[23:45:04.398] Timer tick, asking Buildbucket for the build status
[23:45:04.555] Build 8885544128155778880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:45:04.555] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:117:0) after 1m0s
[23:46:04.593] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:117:0)
[23:46:04.593] Timer tick, asking Buildbucket for the build status
[23:46:04.774] Build 8885544128155778880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:46:04.774] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:118:0) after 1m0s
[23:47:04.879] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:118:0)
[23:47:04.879] Timer tick, asking Buildbucket for the build status
[23:47:05.214] Build 8885544128155778880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:47:05.214] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:119:0) after 1m0s
[23:48:05.258] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:119:0)
[23:48:05.258] Timer tick, asking Buildbucket for the build status
[23:48:05.697] Build 8885544128155778880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:48:05.697] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:120:0) after 1m0s
[23:49:05.747] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:120:0)
[23:49:05.747] Timer tick, asking Buildbucket for the build status
[23:49:05.901] Build 8885544128155778880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:49:05.901] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:121:0) after 1m0s
[23:50:05.961] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:121:0)
[23:50:05.961] Timer tick, asking Buildbucket for the build status
[23:50:06.196] Build 8885544128155778880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:50:06.196] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:122:0) after 1m0s
[23:51:06.188] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:122:0)
[23:51:06.188] Timer tick, asking Buildbucket for the build status
[23:51:06.410] Build 8885544128155778880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:51:06.410] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:123:0) after 1m0s
[23:52:06.462] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:123:0)
[23:52:06.462] Timer tick, asking Buildbucket for the build status
[23:52:06.564] Build 8885544128155778880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:52:06.564] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:124:0) after 1m0s
[23:53:06.589] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:124:0)
[23:53:06.589] Timer tick, asking Buildbucket for the build status
[23:53:06.767] Build 8885544128155778880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:53:06.767] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:125:0) after 1m0s
[23:54:06.973] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:125:0)
[23:54:06.973] Timer tick, asking Buildbucket for the build status
[23:54:07.206] Build 8885544128155778880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:54:07.206] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:126:0) after 1m0s
[23:55:07.250] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:126:0)
[23:55:07.250] Timer tick, asking Buildbucket for the build status
[23:55:07.465] Build 8885544128155778880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:55:07.465] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:127:0) after 1m0s
[23:56:07.489] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:127:0)
[23:56:07.489] Timer tick, asking Buildbucket for the build status
[23:56:07.638] Build 8885544128155778880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:56:07.639] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:128:0) after 1m0s
[23:57:07.620] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:128:0)
[23:57:07.620] Timer tick, asking Buildbucket for the build status
[23:57:07.945] Build 8885544128155778880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:57:07.945] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:129:0) after 1m0s
[23:58:08.024] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:129:0)
[23:58:08.024] Timer tick, asking Buildbucket for the build status
[23:58:08.754] Build 8885544128155778880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:58:08.754] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:130:0) after 1m0s
[23:59:08.785] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:130:0)
[23:59:08.785] Timer tick, asking Buildbucket for the build status
[23:59:09.081] Build 8885544128155778880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:59:09.081] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:131:0) after 1m0s
[00:00:09.137] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:131:0)
[00:00:09.137] Timer tick, asking Buildbucket for the build status
[00:00:09.439] Build 8885544128155778880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:00:09.439] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:132:0) after 1m0s
[00:01:09.464] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:132:0)
[00:01:09.464] Timer tick, asking Buildbucket for the build status
[00:01:09.623] Build 8885544128155778880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:01:09.623] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:133:0) after 1m0s
[00:02:09.698] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:133:0)
[00:02:09.698] Timer tick, asking Buildbucket for the build status
[00:02:10.278] Build 8885544128155778880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:02:10.278] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:134:0) after 1m0s
[00:03:10.412] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:134:0)
[00:03:10.412] Timer tick, asking Buildbucket for the build status
[00:03:10.526] Build 8885544128155778880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:03:10.526] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:135:0) after 1m0s
[00:04:10.566] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:135:0)
[00:04:10.566] Timer tick, asking Buildbucket for the build status
[00:04:10.709] Build 8885544128155778880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:04:10.709] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050974190324511680:136:0) after 1m0s
[00:04:48.214] Received PubSub notification, asking Buildbucket for the build status
[00:04:48.312] Build 8885544128155778880: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[00:04:48.312] Buildbucket build:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary_preference": "PROD",
"completed_ts": "1584489887208054",
"created_by": "project:emscripten-releases",
"created_ts": "1584481799891162",
"id": "8885544128155778880",
"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\": \"c6bffe2520cd35f2b42dceb4e75b5bbf4160004f\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@c6bffe2520cd35f2b42dceb4e75b5bbf4160004f\", \"title\": \"c6bffe2520cd35f2b42dceb4e75b5bbf4160004f\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/c6bffe2520cd35f2b42dceb4e75b5bbf4160004f\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"c6bffe2520cd35f2b42dceb4e75b5bbf4160004f\"}}",
"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\": \"c6bffe2520cd35f2b42dceb4e75b5bbf4160004f\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@c6bffe2520cd35f2b42dceb4e75b5bbf4160004f\", \"title\": \"c6bffe2520cd35f2b42dceb4e75b5bbf4160004f\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/c6bffe2520cd35f2b42dceb4e75b5bbf4160004f\"}]}, \"bot_id\": \"luci-emscripten-releases-ci-win10-0-ba7j\", \"branch\": \"refs/heads/master\", \"buildername\": \"win\", \"got_revision\": \"c6bffe2520cd35f2b42dceb4e75b5bbf4160004f\", \"path_config\": \"generic\", \"recipe\": \"emscripten_releases\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"c6bffe2520cd35f2b42dceb4e75b5bbf4160004f\"}, \"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-ba7j\"], \"image\": [\"chrome-win10-1703-19112100-3085293b610\"], \"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\": [\"4874-3598771\"], \"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": "1584485118185656",
"status": "COMPLETED",
"status_changed_ts": "1584489887705999",
"tags": [
"builder:win",
"buildset:commit/git/c6bffe2520cd35f2b42dceb4e75b5bbf4160004f",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/c6bffe2520cd35f2b42dceb4e75b5bbf4160004f",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9050974190324511680",
"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/8885544128155778880/+/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:4b034b38e1973410",
"user_agent:luci-scheduler"
],
"updated_ts": "1584489887778213",
"url": "https://ci.chromium.org/b/8885544128155778880",
"utcnow_ts": "1584489888302310"
}
}
[00:04:48.312] Invocation finished in 2h14m49.873804851s with status SUCCEEDED