[16:10:21.108] New invocation is queued and will start shortly
[16:10:22.195] Starting the invocation (attempt 1)
[16:10:22.234] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[16:10:22.237] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[16:10:22.237] Buildbucket request:
{
"bucket": "luci.emscripten-releases.ci",
"client_operation_id": "9049998990929200128",
"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\":\"12cad001fc0d6c50547068ac4bef736527d87462\"},\"id\":\"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@12cad001fc0d6c50547068ac4bef736527d87462\",\"title\":\"12cad001fc0d6c50547068ac4bef736527d87462\",\"url\":\"https://chromium.googlesource.com/emscripten-releases/+/12cad001fc0d6c50547068ac4bef736527d87462\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/emscripten-releases\",\"revision\":\"12cad001fc0d6c50547068ac4bef736527d87462\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win",
"scheduler_invocation_id:9049998990929200128",
"scheduler_job_id:emscripten-releases/win",
"user_agent:luci-scheduler",
"buildset:commit/git/12cad001fc0d6c50547068ac4bef736527d87462",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/12cad001fc0d6c50547068ac4bef736527d87462",
"gitiles_ref:refs/heads/master"
]
}
[16:10:23.446] Buildbucket response:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary_preference": "PROD",
"created_by": "project:emscripten-releases",
"created_ts": "1585411822417466",
"id": "8884568928855765760",
"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\": \"12cad001fc0d6c50547068ac4bef736527d87462\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@12cad001fc0d6c50547068ac4bef736527d87462\", \"title\": \"12cad001fc0d6c50547068ac4bef736527d87462\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/12cad001fc0d6c50547068ac4bef736527d87462\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"12cad001fc0d6c50547068ac4bef736527d87462\"}}",
"project": "emscripten-releases",
"result_details_json": "{\"properties\": {}}",
"service_account": "emscripten-releases-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1585411823188382",
"tags": [
"builder:win",
"buildset:commit/git/12cad001fc0d6c50547068ac4bef736527d87462",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/12cad001fc0d6c50547068ac4bef736527d87462",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9049998990929200128",
"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/8884568928855765760/+/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": "1585411823188603",
"url": "https://ci.chromium.org/b/8884568928855765760",
"utcnow_ts": "1585411823435139"
}
}
[16:10:23.446] Task URL: https://ci.chromium.org/b/8884568928855765760
[16:10:23.446] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:2:0) after 1m0s
[16:11:23.426] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:2:0)
[16:11:23.426] Timer tick, asking Buildbucket for the build status
[16:11:23.966] Build 8884568928855765760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:11:23.966] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:3:0) after 1m0s
[16:12:23.970] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:3:0)
[16:12:23.970] Timer tick, asking Buildbucket for the build status
[16:12:24.143] Build 8884568928855765760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:12:24.143] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:4:0) after 1m0s
[16:13:24.197] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:4:0)
[16:13:24.197] Timer tick, asking Buildbucket for the build status
[16:13:24.625] Build 8884568928855765760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:13:24.625] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:5:0) after 1m0s
[16:14:24.717] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:5:0)
[16:14:24.717] Timer tick, asking Buildbucket for the build status
[16:14:24.841] Build 8884568928855765760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:14:24.841] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:6:0) after 1m0s
[16:15:24.901] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:6:0)
[16:15:24.901] Timer tick, asking Buildbucket for the build status
[16:15:25.053] Build 8884568928855765760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:15:25.053] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:7:0) after 1m0s
[16:16:25.075] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:7:0)
[16:16:25.075] Timer tick, asking Buildbucket for the build status
[16:16:25.217] Build 8884568928855765760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:16:25.217] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:8:0) after 1m0s
[16:17:25.287] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:8:0)
[16:17:25.287] Timer tick, asking Buildbucket for the build status
[16:17:25.503] Build 8884568928855765760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:17:25.503] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:9:0) after 1m0s
[16:18:25.503] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:9:0)
[16:18:25.503] Timer tick, asking Buildbucket for the build status
[16:18:26.028] Build 8884568928855765760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:18:26.028] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:10:0) after 1m0s
[16:19:26.052] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:10:0)
[16:19:26.052] Timer tick, asking Buildbucket for the build status
[16:19:26.253] Build 8884568928855765760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:19:26.253] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:11:0) after 1m0s
[16:20:26.280] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:11:0)
[16:20:26.280] Timer tick, asking Buildbucket for the build status
[16:20:26.413] Build 8884568928855765760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:20:26.413] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:12:0) after 1m0s
[16:21:26.534] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:12:0)
[16:21:26.534] Timer tick, asking Buildbucket for the build status
[16:21:29.071] Build 8884568928855765760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:21:29.071] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:13:0) after 1m0s
[16:22:29.155] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:13:0)
[16:22:29.155] Timer tick, asking Buildbucket for the build status
[16:22:29.297] Build 8884568928855765760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:22:29.297] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:14:0) after 1m0s
[16:23:29.368] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:14:0)
[16:23:29.368] Timer tick, asking Buildbucket for the build status
[16:23:32.027] Build 8884568928855765760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:23:32.027] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:15:0) after 1m0s
[16:24:32.115] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:15:0)
[16:24:32.115] Timer tick, asking Buildbucket for the build status
[16:24:32.241] Build 8884568928855765760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:24:32.241] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:16:0) after 1m0s
[16:25:32.225] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:16:0)
[16:25:32.225] Timer tick, asking Buildbucket for the build status
[16:25:32.396] Build 8884568928855765760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:25:32.397] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:17:0) after 1m0s
[16:26:32.441] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:17:0)
[16:26:32.441] Timer tick, asking Buildbucket for the build status
[16:26:32.562] Build 8884568928855765760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:26:32.562] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:18:0) after 1m0s
[16:27:32.595] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:18:0)
[16:27:32.595] Timer tick, asking Buildbucket for the build status
[16:27:32.742] Build 8884568928855765760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:27:32.742] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:19:0) after 1m0s
[16:28:32.764] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:19:0)
[16:28:32.764] Timer tick, asking Buildbucket for the build status
[16:28:32.887] Build 8884568928855765760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:28:32.887] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:20:0) after 1m0s
[16:29:32.909] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:20:0)
[16:29:32.909] Timer tick, asking Buildbucket for the build status
[16:29:33.021] Build 8884568928855765760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:29:33.021] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:21:0) after 1m0s
[16:30:33.062] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:21:0)
[16:30:33.062] Timer tick, asking Buildbucket for the build status
[16:30:33.195] Build 8884568928855765760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:30:33.195] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:22:0) after 1m0s
[16:31:33.272] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:22:0)
[16:31:33.272] Timer tick, asking Buildbucket for the build status
[16:31:33.442] Build 8884568928855765760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:31:33.442] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:23:0) after 1m0s
[16:32:33.569] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:23:0)
[16:32:33.569] Timer tick, asking Buildbucket for the build status
[16:32:33.691] Build 8884568928855765760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:32:33.691] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:24:0) after 1m0s
[16:33:33.742] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:24:0)
[16:33:33.742] Timer tick, asking Buildbucket for the build status
[16:33:35.403] Build 8884568928855765760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:33:35.403] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:25:0) after 1m0s
[16:34:35.442] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:25:0)
[16:34:35.442] Timer tick, asking Buildbucket for the build status
[16:34:35.680] Build 8884568928855765760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:34:35.680] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:26:0) after 1m0s
[16:35:35.681] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:26:0)
[16:35:35.681] Timer tick, asking Buildbucket for the build status
[16:35:35.983] Build 8884568928855765760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:35:35.983] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:27:0) after 1m0s
[16:36:36.006] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:27:0)
[16:36:36.006] Timer tick, asking Buildbucket for the build status
[16:36:36.158] Build 8884568928855765760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:36:36.158] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:28:0) after 1m0s
[16:37:36.187] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:28:0)
[16:37:36.187] Timer tick, asking Buildbucket for the build status
[16:37:36.403] Build 8884568928855765760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:37:36.403] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:29:0) after 1m0s
[16:38:36.432] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:29:0)
[16:38:36.432] Timer tick, asking Buildbucket for the build status
[16:38:38.198] Build 8884568928855765760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:38:38.198] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:30:0) after 1m0s
[16:39:38.217] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:30:0)
[16:39:38.217] Timer tick, asking Buildbucket for the build status
[16:39:38.345] Build 8884568928855765760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:39:38.345] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:31:0) after 1m0s
[16:40:38.404] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:31:0)
[16:40:38.404] Timer tick, asking Buildbucket for the build status
[16:40:38.808] Build 8884568928855765760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:40:38.808] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:32:0) after 1m0s
[16:41:38.820] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:32:0)
[16:41:38.820] Timer tick, asking Buildbucket for the build status
[16:41:39.008] Build 8884568928855765760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:41:39.008] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:33:0) after 1m0s
[16:42:39.086] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:33:0)
[16:42:39.086] Timer tick, asking Buildbucket for the build status
[16:42:39.194] Build 8884568928855765760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:42:39.194] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:34:0) after 1m0s
[16:43:39.214] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:34:0)
[16:43:39.214] Timer tick, asking Buildbucket for the build status
[16:43:39.959] Build 8884568928855765760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:43:39.960] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:35:0) after 1m0s
[16:44:39.979] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:35:0)
[16:44:39.979] Timer tick, asking Buildbucket for the build status
[16:44:40.091] Build 8884568928855765760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:44:40.091] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:36:0) after 1m0s
[16:45:40.075] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:36:0)
[16:45:40.075] Timer tick, asking Buildbucket for the build status
[16:45:40.185] Build 8884568928855765760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:45:40.185] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:37:0) after 1m0s
[16:46:40.160] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:37:0)
[16:46:40.160] Timer tick, asking Buildbucket for the build status
[16:46:40.289] Build 8884568928855765760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:46:40.289] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:38:0) after 1m0s
[16:47:40.372] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:38:0)
[16:47:40.372] Timer tick, asking Buildbucket for the build status
[16:47:40.477] Build 8884568928855765760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:47:40.477] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:39:0) after 1m0s
[16:48:40.558] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:39:0)
[16:48:40.558] Timer tick, asking Buildbucket for the build status
[16:48:40.819] Build 8884568928855765760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:48:40.819] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:40:0) after 1m0s
[16:49:40.847] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:40:0)
[16:49:40.847] Timer tick, asking Buildbucket for the build status
[16:49:41.100] Build 8884568928855765760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:49:41.100] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:41:0) after 1m0s
[16:50:41.149] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:41:0)
[16:50:41.149] Timer tick, asking Buildbucket for the build status
[16:50:41.271] Build 8884568928855765760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:50:41.271] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:42:0) after 1m0s
[16:51:41.383] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:42:0)
[16:51:41.383] Timer tick, asking Buildbucket for the build status
[16:51:41.537] Build 8884568928855765760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:51:41.537] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:43:0) after 1m0s
[16:52:41.660] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:43:0)
[16:52:41.660] Timer tick, asking Buildbucket for the build status
[16:52:41.811] Build 8884568928855765760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:52:41.811] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:44:0) after 1m0s
[16:53:41.863] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:44:0)
[16:53:41.863] Timer tick, asking Buildbucket for the build status
[16:53:43.463] Build 8884568928855765760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:53:43.463] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:45:0) after 1m0s
[16:54:44.035] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:45:0)
[16:54:44.035] Timer tick, asking Buildbucket for the build status
[16:54:44.172] Build 8884568928855765760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:54:44.173] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:46:0) after 1m0s
[16:55:44.238] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:46:0)
[16:55:44.238] Timer tick, asking Buildbucket for the build status
[16:55:44.407] Build 8884568928855765760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:55:44.407] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:47:0) after 1m0s
[16:56:44.508] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:47:0)
[16:56:44.508] Timer tick, asking Buildbucket for the build status
[16:56:44.651] Build 8884568928855765760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:56:44.651] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:48:0) after 1m0s
[16:57:44.792] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:48:0)
[16:57:44.792] Timer tick, asking Buildbucket for the build status
[16:57:44.897] Build 8884568928855765760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:57:44.897] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:49:0) after 1m0s
[16:58:45.065] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:49:0)
[16:58:45.065] Timer tick, asking Buildbucket for the build status
[16:58:45.229] Build 8884568928855765760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:58:45.229] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:50:0) after 1m0s
[16:59:45.663] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:50:0)
[16:59:45.663] Timer tick, asking Buildbucket for the build status
[16:59:46.026] Build 8884568928855765760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:59:46.026] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:51:0) after 1m0s
[17:00:46.277] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:51:0)
[17:00:46.277] Timer tick, asking Buildbucket for the build status
[17:00:46.444] Build 8884568928855765760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:00:46.444] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:52:0) after 1m0s
[17:01:46.634] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:52:0)
[17:01:46.634] Timer tick, asking Buildbucket for the build status
[17:01:46.899] Build 8884568928855765760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:01:46.899] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:53:0) after 1m0s
[17:02:47.087] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:53:0)
[17:02:47.087] Timer tick, asking Buildbucket for the build status
[17:02:47.235] Build 8884568928855765760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:02:47.235] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:54:0) after 1m0s
[17:03:47.537] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:54:0)
[17:03:47.537] Timer tick, asking Buildbucket for the build status
[17:03:48.121] Build 8884568928855765760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:03:48.121] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:55:0) after 1m0s
[17:04:48.671] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:55:0)
[17:04:48.671] Timer tick, asking Buildbucket for the build status
[17:04:48.819] Build 8884568928855765760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:04:48.820] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:56:0) after 1m0s
[17:05:49.271] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:56:0)
[17:05:49.271] Timer tick, asking Buildbucket for the build status
[17:05:49.565] Build 8884568928855765760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:05:49.565] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:57:0) after 1m0s
[17:06:49.811] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:57:0)
[17:06:49.811] Timer tick, asking Buildbucket for the build status
[17:06:50.116] Build 8884568928855765760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:06:50.116] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:58:0) after 1m0s
[17:07:50.143] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:58:0)
[17:07:50.143] Timer tick, asking Buildbucket for the build status
[17:07:50.319] Build 8884568928855765760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:07:50.319] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:59:0) after 1m0s
[17:08:50.464] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:59:0)
[17:08:50.464] Timer tick, asking Buildbucket for the build status
[17:08:50.616] Build 8884568928855765760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:08:50.616] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:60:0) after 1m0s
[17:09:50.649] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:60:0)
[17:09:50.649] Timer tick, asking Buildbucket for the build status
[17:09:50.761] Build 8884568928855765760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:09:50.761] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:61:0) after 1m0s
[17:10:50.828] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:61:0)
[17:10:50.828] Timer tick, asking Buildbucket for the build status
[17:10:50.933] Build 8884568928855765760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:10:50.933] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:62:0) after 1m0s
[17:11:50.985] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:62:0)
[17:11:50.985] Timer tick, asking Buildbucket for the build status
[17:11:51.263] Build 8884568928855765760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:11:51.263] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:63:0) after 1m0s
[17:12:51.288] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:63:0)
[17:12:51.288] Timer tick, asking Buildbucket for the build status
[17:12:51.430] Build 8884568928855765760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:12:51.430] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:64:0) after 1m0s
[17:13:51.504] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:64:0)
[17:13:51.504] Timer tick, asking Buildbucket for the build status
[17:13:51.679] Build 8884568928855765760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:13:51.680] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:65:0) after 1m0s
[17:14:14.930] Received PubSub notification, asking Buildbucket for the build status
[17:14:15.052] Build 8884568928855765760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:14:51.822] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:65:0)
[17:14:51.822] Timer tick, asking Buildbucket for the build status
[17:14:51.950] Build 8884568928855765760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:14:51.950] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:67:0) after 1m0s
[17:15:52.026] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:67:0)
[17:15:52.026] Timer tick, asking Buildbucket for the build status
[17:15:52.344] Build 8884568928855765760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:15:52.344] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:68:0) after 1m0s
[17:16:52.402] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:68:0)
[17:16:52.402] Timer tick, asking Buildbucket for the build status
[17:16:52.670] Build 8884568928855765760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:16:52.670] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:69:0) after 1m0s
[17:17:52.699] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:69:0)
[17:17:52.699] Timer tick, asking Buildbucket for the build status
[17:17:52.999] Build 8884568928855765760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:17:52.999] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:70:0) after 1m0s
[17:18:53.033] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:70:0)
[17:18:53.033] Timer tick, asking Buildbucket for the build status
[17:18:53.331] Build 8884568928855765760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:18:53.331] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:71:0) after 1m0s
[17:19:53.429] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:71:0)
[17:19:53.429] Timer tick, asking Buildbucket for the build status
[17:19:53.575] Build 8884568928855765760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:19:53.575] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:72:0) after 1m0s
[17:20:53.630] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:72:0)
[17:20:53.630] Timer tick, asking Buildbucket for the build status
[17:20:53.744] Build 8884568928855765760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:20:53.744] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:73:0) after 1m0s
[17:21:53.858] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:73:0)
[17:21:53.858] Timer tick, asking Buildbucket for the build status
[17:21:53.960] Build 8884568928855765760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:21:53.960] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:74:0) after 1m0s
[17:22:54.010] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:74:0)
[17:22:54.010] Timer tick, asking Buildbucket for the build status
[17:22:54.153] Build 8884568928855765760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:22:54.153] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:75:0) after 1m0s
[17:23:54.220] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:75:0)
[17:23:54.220] Timer tick, asking Buildbucket for the build status
[17:23:54.351] Build 8884568928855765760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:23:54.351] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:76:0) after 1m0s
[17:24:54.431] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:76:0)
[17:24:54.431] Timer tick, asking Buildbucket for the build status
[17:24:54.575] Build 8884568928855765760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:24:54.576] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:77:0) after 1m0s
[17:25:54.613] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:77:0)
[17:25:54.613] Timer tick, asking Buildbucket for the build status
[17:25:54.743] Build 8884568928855765760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:25:54.743] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:78:0) after 1m0s
[17:26:54.781] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:78:0)
[17:26:54.781] Timer tick, asking Buildbucket for the build status
[17:26:54.954] Build 8884568928855765760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:26:54.954] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:79:0) after 1m0s
[17:27:55.005] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:79:0)
[17:27:55.005] Timer tick, asking Buildbucket for the build status
[17:27:55.123] Build 8884568928855765760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:27:55.123] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:80:0) after 1m0s
[17:28:55.192] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:80:0)
[17:28:55.192] Timer tick, asking Buildbucket for the build status
[17:28:55.506] Build 8884568928855765760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:28:55.506] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:81:0) after 1m0s
[17:29:55.646] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:81:0)
[17:29:55.646] Timer tick, asking Buildbucket for the build status
[17:29:56.146] Build 8884568928855765760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:29:56.146] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:82:0) after 1m0s
[17:30:56.266] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:82:0)
[17:30:56.266] Timer tick, asking Buildbucket for the build status
[17:30:56.400] Build 8884568928855765760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:30:56.400] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:83:0) after 1m0s
[17:31:56.626] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:83:0)
[17:31:56.626] Timer tick, asking Buildbucket for the build status
[17:31:56.750] Build 8884568928855765760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:31:56.750] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:84:0) after 1m0s
[17:32:56.812] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:84:0)
[17:32:56.812] Timer tick, asking Buildbucket for the build status
[17:32:56.914] Build 8884568928855765760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:32:56.914] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:85:0) after 1m0s
[17:33:57.016] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:85:0)
[17:33:57.016] Timer tick, asking Buildbucket for the build status
[17:33:57.223] Build 8884568928855765760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:33:57.224] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:86:0) after 1m0s
[17:34:57.412] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:86:0)
[17:34:57.412] Timer tick, asking Buildbucket for the build status
[17:34:57.637] Build 8884568928855765760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:34:57.637] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:87:0) after 1m0s
[17:35:57.834] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:87:0)
[17:35:57.834] Timer tick, asking Buildbucket for the build status
[17:35:57.939] Build 8884568928855765760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:35:57.939] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:88:0) after 1m0s
[17:36:58.627] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:88:0)
[17:36:58.627] Timer tick, asking Buildbucket for the build status
[17:36:58.791] Build 8884568928855765760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:36:58.791] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:89:0) after 1m0s
[17:37:58.860] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:89:0)
[17:37:58.861] Timer tick, asking Buildbucket for the build status
[17:37:59.183] Build 8884568928855765760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:37:59.184] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:90:0) after 1m0s
[17:38:59.351] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:90:0)
[17:38:59.351] Timer tick, asking Buildbucket for the build status
[17:38:59.799] Build 8884568928855765760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:38:59.799] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:91:0) after 1m0s
[17:40:00.064] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:91:0)
[17:40:00.065] Timer tick, asking Buildbucket for the build status
[17:40:00.299] Build 8884568928855765760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:40:00.299] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:92:0) after 1m0s
[17:41:00.350] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:92:0)
[17:41:00.350] Timer tick, asking Buildbucket for the build status
[17:41:00.634] Build 8884568928855765760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:41:00.634] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:93:0) after 1m0s
[17:42:00.752] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:93:0)
[17:42:00.752] Timer tick, asking Buildbucket for the build status
[17:42:00.869] Build 8884568928855765760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:42:00.869] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:94:0) after 1m0s
[17:43:00.897] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:94:0)
[17:43:00.897] Timer tick, asking Buildbucket for the build status
[17:43:01.158] Build 8884568928855765760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:43:01.158] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:95:0) after 1m0s
[17:44:01.184] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:95:0)
[17:44:01.184] Timer tick, asking Buildbucket for the build status
[17:44:01.389] Build 8884568928855765760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:44:01.389] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:96:0) after 1m0s
[17:45:01.493] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:96:0)
[17:45:01.493] Timer tick, asking Buildbucket for the build status
[17:45:01.609] Build 8884568928855765760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:45:01.609] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:97:0) after 1m0s
[17:46:01.667] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:97:0)
[17:46:01.667] Timer tick, asking Buildbucket for the build status
[17:46:02.980] Build 8884568928855765760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:46:02.980] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:98:0) after 1m0s
[17:47:03.087] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:98:0)
[17:47:03.087] Timer tick, asking Buildbucket for the build status
[17:47:03.331] Build 8884568928855765760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:47:03.331] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:99:0) after 1m0s
[17:48:03.313] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:99:0)
[17:48:03.313] Timer tick, asking Buildbucket for the build status
[17:48:03.473] Build 8884568928855765760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:48:03.473] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:100:0) after 1m0s
[17:49:03.507] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:100:0)
[17:49:03.507] Timer tick, asking Buildbucket for the build status
[17:49:03.957] Build 8884568928855765760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:49:03.957] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:101:0) after 1m0s
[17:50:03.979] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:101:0)
[17:50:03.979] Timer tick, asking Buildbucket for the build status
[17:50:04.208] Build 8884568928855765760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:50:04.208] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:102:0) after 1m0s
[17:51:04.543] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:102:0)
[17:51:04.543] Timer tick, asking Buildbucket for the build status
[17:51:04.744] Build 8884568928855765760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:51:04.744] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:103:0) after 1m0s
[17:52:04.925] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:103:0)
[17:52:04.925] Timer tick, asking Buildbucket for the build status
[17:52:05.049] Build 8884568928855765760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:52:05.049] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:104:0) after 1m0s
[17:53:05.094] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:104:0)
[17:53:05.094] Timer tick, asking Buildbucket for the build status
[17:53:05.471] Build 8884568928855765760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:53:05.471] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:105:0) after 1m0s
[17:54:05.523] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:105:0)
[17:54:05.523] Timer tick, asking Buildbucket for the build status
[17:54:05.911] Build 8884568928855765760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:54:05.911] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:106:0) after 1m0s
[17:55:05.944] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:106:0)
[17:55:05.944] Timer tick, asking Buildbucket for the build status
[17:55:06.136] Build 8884568928855765760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:55:06.136] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:107:0) after 1m0s
[17:56:06.224] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:107:0)
[17:56:06.224] Timer tick, asking Buildbucket for the build status
[17:56:06.386] Build 8884568928855765760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:56:06.386] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:108:0) after 1m0s
[17:57:06.435] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:108:0)
[17:57:06.435] Timer tick, asking Buildbucket for the build status
[17:57:06.621] Build 8884568928855765760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:57:06.621] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:109:0) after 1m0s
[17:58:06.660] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:109:0)
[17:58:06.660] Timer tick, asking Buildbucket for the build status
[17:58:06.986] Build 8884568928855765760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:58:06.986] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:110:0) after 1m0s
[17:59:07.048] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:110:0)
[17:59:07.048] Timer tick, asking Buildbucket for the build status
[17:59:07.381] Build 8884568928855765760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:59:07.381] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:111:0) after 1m0s
[18:00:07.411] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:111:0)
[18:00:07.411] Timer tick, asking Buildbucket for the build status
[18:00:07.613] Build 8884568928855765760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:00:07.613] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:112:0) after 1m0s
[18:01:08.014] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:112:0)
[18:01:08.014] Timer tick, asking Buildbucket for the build status
[18:01:08.174] Build 8884568928855765760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:01:08.174] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:113:0) after 1m0s
[18:02:08.336] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:113:0)
[18:02:08.336] Timer tick, asking Buildbucket for the build status
[18:02:08.582] Build 8884568928855765760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:02:08.582] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:114:0) after 1m0s
[18:03:08.657] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:114:0)
[18:03:08.657] Timer tick, asking Buildbucket for the build status
[18:03:08.872] Build 8884568928855765760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:03:08.872] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:115:0) after 1m0s
[18:04:08.995] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:115:0)
[18:04:08.995] Timer tick, asking Buildbucket for the build status
[18:04:09.186] Build 8884568928855765760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:04:09.186] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:116:0) after 1m0s
[18:05:09.251] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:116:0)
[18:05:09.251] Timer tick, asking Buildbucket for the build status
[18:05:09.392] Build 8884568928855765760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:05:09.392] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:117:0) after 1m0s
[18:06:09.505] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:117:0)
[18:06:09.505] Timer tick, asking Buildbucket for the build status
[18:06:09.789] Build 8884568928855765760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:06:09.789] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:118:0) after 1m0s
[18:07:09.862] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:118:0)
[18:07:09.862] Timer tick, asking Buildbucket for the build status
[18:07:10.554] Build 8884568928855765760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:07:10.555] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:119:0) after 1m0s
[18:08:10.696] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:119:0)
[18:08:10.696] Timer tick, asking Buildbucket for the build status
[18:08:11.191] Build 8884568928855765760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:08:11.191] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:120:0) after 1m0s
[18:09:11.291] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:120:0)
[18:09:11.291] Timer tick, asking Buildbucket for the build status
[18:09:11.523] Build 8884568928855765760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:09:11.523] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:121:0) after 1m0s
[18:10:11.531] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:121:0)
[18:10:11.531] Timer tick, asking Buildbucket for the build status
[18:10:11.661] Build 8884568928855765760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:10:11.662] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:122:0) after 1m0s
[18:11:11.650] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:122:0)
[18:11:11.650] Timer tick, asking Buildbucket for the build status
[18:11:12.006] Build 8884568928855765760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:11:12.006] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:123:0) after 1m0s
[18:12:12.165] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:123:0)
[18:12:12.165] Timer tick, asking Buildbucket for the build status
[18:12:12.387] Build 8884568928855765760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:12:12.387] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:124:0) after 1m0s
[18:13:12.468] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:124:0)
[18:13:12.468] Timer tick, asking Buildbucket for the build status
[18:13:12.669] Build 8884568928855765760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:13:12.669] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:125:0) after 1m0s
[18:14:12.702] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:125:0)
[18:14:12.702] Timer tick, asking Buildbucket for the build status
[18:14:12.878] Build 8884568928855765760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:14:12.878] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:126:0) after 1m0s
[18:15:12.903] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:126:0)
[18:15:12.903] Timer tick, asking Buildbucket for the build status
[18:15:13.062] Build 8884568928855765760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:15:13.062] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:127:0) after 1m0s
[18:16:13.246] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:127:0)
[18:16:13.246] Timer tick, asking Buildbucket for the build status
[18:16:13.366] Build 8884568928855765760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:16:13.366] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:128:0) after 1m0s
[18:17:13.514] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:128:0)
[18:17:13.514] Timer tick, asking Buildbucket for the build status
[18:17:13.985] Build 8884568928855765760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:17:13.985] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:129:0) after 1m0s
[18:18:14.078] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:129:0)
[18:18:14.078] Timer tick, asking Buildbucket for the build status
[18:18:14.388] Build 8884568928855765760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:18:14.388] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:130:0) after 1m0s
[18:19:14.521] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:130:0)
[18:19:14.521] Timer tick, asking Buildbucket for the build status
[18:19:14.702] Build 8884568928855765760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:19:14.702] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:131:0) after 1m0s
[18:20:14.872] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:131:0)
[18:20:14.872] Timer tick, asking Buildbucket for the build status
[18:20:15.023] Build 8884568928855765760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:20:15.023] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:132:0) after 1m0s
[18:21:15.089] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:132:0)
[18:21:15.089] Timer tick, asking Buildbucket for the build status
[18:21:15.498] Build 8884568928855765760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:21:15.498] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:133:0) after 1m0s
[18:22:15.521] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:133:0)
[18:22:15.521] Timer tick, asking Buildbucket for the build status
[18:22:15.638] Build 8884568928855765760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:22:15.638] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:134:0) after 1m0s
[18:23:15.750] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:134:0)
[18:23:15.750] Timer tick, asking Buildbucket for the build status
[18:23:16.267] Build 8884568928855765760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:23:16.267] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:135:0) after 1m0s
[18:24:16.513] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:135:0)
[18:24:16.513] Timer tick, asking Buildbucket for the build status
[18:24:16.943] Build 8884568928855765760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:24:16.943] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:136:0) after 1m0s
[18:25:16.995] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:136:0)
[18:25:16.995] Timer tick, asking Buildbucket for the build status
[18:25:17.209] Build 8884568928855765760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:25:17.209] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:137:0) after 1m0s
[18:26:17.245] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:137:0)
[18:26:17.245] Timer tick, asking Buildbucket for the build status
[18:26:17.366] Build 8884568928855765760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:26:17.366] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:138:0) after 1m0s
[18:27:17.440] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:138:0)
[18:27:17.440] Timer tick, asking Buildbucket for the build status
[18:27:17.537] Build 8884568928855765760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:27:17.537] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9049998990929200128:139:0) after 1m0s
[18:27:58.830] Received PubSub notification, asking Buildbucket for the build status
[18:27:58.986] Build 8884568928855765760: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[18:27:58.986] Buildbucket build:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary_preference": "PROD",
"completed_ts": "1585420077889770",
"created_by": "project:emscripten-releases",
"created_ts": "1585411822417466",
"id": "8884568928855765760",
"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\": \"12cad001fc0d6c50547068ac4bef736527d87462\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@12cad001fc0d6c50547068ac4bef736527d87462\", \"title\": \"12cad001fc0d6c50547068ac4bef736527d87462\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/12cad001fc0d6c50547068ac4bef736527d87462\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"12cad001fc0d6c50547068ac4bef736527d87462\"}}",
"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\": \"12cad001fc0d6c50547068ac4bef736527d87462\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@12cad001fc0d6c50547068ac4bef736527d87462\", \"title\": \"12cad001fc0d6c50547068ac4bef736527d87462\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/12cad001fc0d6c50547068ac4bef736527d87462\"}]}, \"bot_id\": \"luci-emscripten-releases-ci-win10-0-azbh\", \"branch\": \"refs/heads/master\", \"buildername\": \"win\", \"got_revision\": \"12cad001fc0d6c50547068ac4bef736527d87462\", \"path_config\": \"generic\", \"recipe\": \"emscripten_releases\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"12cad001fc0d6c50547068ac4bef736527d87462\"}, \"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-azbh\"], \"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\": [\"4901-91f024a\"], \"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": "1585415653801418",
"status": "COMPLETED",
"status_changed_ts": "1585420078183053",
"tags": [
"builder:win",
"buildset:commit/git/12cad001fc0d6c50547068ac4bef736527d87462",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/12cad001fc0d6c50547068ac4bef736527d87462",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9049998990929200128",
"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/8884568928855765760/+/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:4b3aba2048254b10",
"user_agent:luci-scheduler"
],
"updated_ts": "1585420078193993",
"url": "https://ci.chromium.org/b/8884568928855765760",
"utcnow_ts": "1585420078965541"
}
}
[18:27:58.986] Invocation finished in 2h17m37.929853943s with status SUCCEEDED