[20:40:50.231] New invocation is queued and will start shortly
[20:40:51.336] Starting the invocation (attempt 1)
[20:40:51.383] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[20:40:51.383] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[20:40:51.383] Buildbucket request:
{
"bucket": "luci.emscripten-releases.ci",
"client_operation_id": "9063118533553881664",
"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\":\"b626b7a57eaf7025011cd569471736a8d29b097b\"},\"id\":\"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@b626b7a57eaf7025011cd569471736a8d29b097b\",\"title\":\"b626b7a57eaf7025011cd569471736a8d29b097b\",\"url\":\"https://chromium.googlesource.com/emscripten-releases/+/b626b7a57eaf7025011cd569471736a8d29b097b\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/emscripten-releases\",\"revision\":\"b626b7a57eaf7025011cd569471736a8d29b097b\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win",
"scheduler_invocation_id:9063118533553881664",
"scheduler_job_id:emscripten-releases/win",
"user_agent:luci-scheduler",
"buildset:commit/git/b626b7a57eaf7025011cd569471736a8d29b097b",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/b626b7a57eaf7025011cd569471736a8d29b097b",
"gitiles_ref:refs/heads/master"
]
}
[20:40:52.138] Buildbucket response:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary": true,
"canary_preference": "CANARY",
"created_by": "project:emscripten-releases",
"created_ts": "1572900051484934",
"id": "8897688471573559952",
"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\": \"b626b7a57eaf7025011cd569471736a8d29b097b\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@b626b7a57eaf7025011cd569471736a8d29b097b\", \"title\": \"b626b7a57eaf7025011cd569471736a8d29b097b\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/b626b7a57eaf7025011cd569471736a8d29b097b\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"b626b7a57eaf7025011cd569471736a8d29b097b\"}}",
"project": "emscripten-releases",
"result_details_json": "{\"properties\": {}}",
"service_account": "emscripten-releases-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1572900051832490",
"tags": [
"builder:win",
"buildset:commit/git/b626b7a57eaf7025011cd569471736a8d29b097b",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/b626b7a57eaf7025011cd569471736a8d29b097b",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9063118533553881664",
"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/8897688471573559952/+/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": "1572900051832619",
"url": "https://ci.chromium.org/b/8897688471573559952",
"utcnow_ts": "1572900052130936"
}
}
[20:40:52.138] Task URL: https://ci.chromium.org/b/8897688471573559952
[20:40:52.138] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:2:0) after 1m0s
[20:41:52.163] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:2:0)
[20:41:52.163] Timer tick, asking Buildbucket for the build status
[20:41:52.297] Build 8897688471573559952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:41:52.297] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:3:0) after 1m0s
[20:42:52.319] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:3:0)
[20:42:52.319] Timer tick, asking Buildbucket for the build status
[20:42:52.426] Build 8897688471573559952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:42:52.426] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:4:0) after 1m0s
[20:43:52.508] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:4:0)
[20:43:52.508] Timer tick, asking Buildbucket for the build status
[20:43:53.023] Build 8897688471573559952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:43:53.023] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:5:0) after 1m0s
[20:44:53.007] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:5:0)
[20:44:53.007] Timer tick, asking Buildbucket for the build status
[20:44:53.558] Build 8897688471573559952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:44:53.558] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:6:0) after 1m0s
[20:45:53.659] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:6:0)
[20:45:53.659] Timer tick, asking Buildbucket for the build status
[20:45:53.803] Build 8897688471573559952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:45:53.803] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:7:0) after 1m0s
[20:46:53.969] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:7:0)
[20:46:53.969] Timer tick, asking Buildbucket for the build status
[20:46:54.112] Build 8897688471573559952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:46:54.112] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:8:0) after 1m0s
[20:47:54.137] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:8:0)
[20:47:54.137] Timer tick, asking Buildbucket for the build status
[20:47:55.165] Build 8897688471573559952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:47:55.165] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:9:0) after 1m0s
[20:48:55.312] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:9:0)
[20:48:55.312] Timer tick, asking Buildbucket for the build status
[20:48:55.659] Build 8897688471573559952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:48:55.659] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:10:0) after 1m0s
[20:49:55.767] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:10:0)
[20:49:55.767] Timer tick, asking Buildbucket for the build status
[20:49:56.065] Build 8897688471573559952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:49:56.065] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:11:0) after 1m0s
[20:50:56.169] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:11:0)
[20:50:56.169] Timer tick, asking Buildbucket for the build status
[20:50:57.844] Build 8897688471573559952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:50:57.844] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:12:0) after 1m0s
[20:51:57.865] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:12:0)
[20:51:57.865] Timer tick, asking Buildbucket for the build status
[20:51:57.975] Build 8897688471573559952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:51:57.975] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:13:0) after 1m0s
[20:52:58.052] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:13:0)
[20:52:58.052] Timer tick, asking Buildbucket for the build status
[20:52:58.381] Build 8897688471573559952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:52:58.381] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:14:0) after 1m0s
[20:53:58.499] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:14:0)
[20:53:58.499] Timer tick, asking Buildbucket for the build status
[20:53:58.907] Build 8897688471573559952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:53:58.907] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:15:0) after 1m0s
[20:54:59.030] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:15:0)
[20:54:59.030] Timer tick, asking Buildbucket for the build status
[20:54:59.680] Build 8897688471573559952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:54:59.680] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:16:0) after 1m0s
[20:55:59.687] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:16:0)
[20:55:59.687] Timer tick, asking Buildbucket for the build status
[20:55:59.798] Build 8897688471573559952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:55:59.798] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:17:0) after 1m0s
[20:57:00.019] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:17:0)
[20:57:00.019] Timer tick, asking Buildbucket for the build status
[20:57:00.918] Build 8897688471573559952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:57:00.918] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:18:0) after 1m0s
[20:58:00.974] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:18:0)
[20:58:00.974] Timer tick, asking Buildbucket for the build status
[20:58:01.279] Build 8897688471573559952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:58:01.279] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:19:0) after 1m0s
[20:59:01.371] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:19:0)
[20:59:01.371] Timer tick, asking Buildbucket for the build status
[20:59:01.600] Build 8897688471573559952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:59:01.600] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:20:0) after 1m0s
[21:00:01.706] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:20:0)
[21:00:01.706] Timer tick, asking Buildbucket for the build status
[21:00:02.184] Build 8897688471573559952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:00:02.184] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:21:0) after 1m0s
[21:01:02.309] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:21:0)
[21:01:02.309] Timer tick, asking Buildbucket for the build status
[21:01:02.868] Build 8897688471573559952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:01:02.868] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:22:0) after 1m0s
[21:02:02.935] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:22:0)
[21:02:02.935] Timer tick, asking Buildbucket for the build status
[21:02:03.089] Build 8897688471573559952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:02:03.089] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:23:0) after 1m0s
[21:03:03.212] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:23:0)
[21:03:03.212] Timer tick, asking Buildbucket for the build status
[21:03:03.335] Build 8897688471573559952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:03:03.335] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:24:0) after 1m0s
[21:04:03.357] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:24:0)
[21:04:03.357] Timer tick, asking Buildbucket for the build status
[21:04:03.457] Build 8897688471573559952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:04:03.457] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:25:0) after 1m0s
[21:05:03.490] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:25:0)
[21:05:03.490] Timer tick, asking Buildbucket for the build status
[21:05:03.878] Build 8897688471573559952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:05:03.878] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:26:0) after 1m0s
[21:06:03.902] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:26:0)
[21:06:03.902] Timer tick, asking Buildbucket for the build status
[21:06:04.424] Build 8897688471573559952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:06:04.424] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:27:0) after 1m0s
[21:07:04.450] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:27:0)
[21:07:04.450] Timer tick, asking Buildbucket for the build status
[21:07:04.563] Build 8897688471573559952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:07:04.563] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:28:0) after 1m0s
[21:08:04.653] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:28:0)
[21:08:04.653] Timer tick, asking Buildbucket for the build status
[21:08:05.069] Build 8897688471573559952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:08:05.069] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:29:0) after 1m0s
[21:09:05.124] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:29:0)
[21:09:05.124] Timer tick, asking Buildbucket for the build status
[21:09:05.411] Build 8897688471573559952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:09:05.411] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:30:0) after 1m0s
[21:10:05.414] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:30:0)
[21:10:05.414] Timer tick, asking Buildbucket for the build status
[21:10:05.631] Build 8897688471573559952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:10:05.631] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:31:0) after 1m0s
[21:11:05.733] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:31:0)
[21:11:05.733] Timer tick, asking Buildbucket for the build status
[21:11:05.962] Build 8897688471573559952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:11:05.963] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:32:0) after 1m0s
[21:12:06.077] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:32:0)
[21:12:06.077] Timer tick, asking Buildbucket for the build status
[21:12:06.408] Build 8897688471573559952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:12:06.408] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:33:0) after 1m0s
[21:13:06.515] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:33:0)
[21:13:06.515] Timer tick, asking Buildbucket for the build status
[21:13:06.624] Build 8897688471573559952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:13:06.624] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:34:0) after 1m0s
[21:14:06.822] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:34:0)
[21:14:06.822] Timer tick, asking Buildbucket for the build status
[21:14:06.947] Build 8897688471573559952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:14:06.947] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:35:0) after 1m0s
[21:15:07.102] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:35:0)
[21:15:07.102] Timer tick, asking Buildbucket for the build status
[21:15:07.415] Build 8897688471573559952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:15:07.415] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:36:0) after 1m0s
[21:16:07.494] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:36:0)
[21:16:07.494] Timer tick, asking Buildbucket for the build status
[21:16:08.556] Build 8897688471573559952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:16:08.556] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:37:0) after 1m0s
[21:17:08.580] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:37:0)
[21:17:08.580] Timer tick, asking Buildbucket for the build status
[21:17:08.857] Build 8897688471573559952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:17:08.857] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:38:0) after 1m0s
[21:18:08.945] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:38:0)
[21:18:08.945] Timer tick, asking Buildbucket for the build status
[21:18:09.092] Build 8897688471573559952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:18:09.093] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:39:0) after 1m0s
[21:19:09.151] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:39:0)
[21:19:09.151] Timer tick, asking Buildbucket for the build status
[21:19:09.272] Build 8897688471573559952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:19:09.272] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:40:0) after 1m0s
[21:20:09.431] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:40:0)
[21:20:09.431] Timer tick, asking Buildbucket for the build status
[21:20:10.038] Build 8897688471573559952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:20:10.038] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:41:0) after 1m0s
[21:21:10.148] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:41:0)
[21:21:10.148] Timer tick, asking Buildbucket for the build status
[21:21:10.727] Build 8897688471573559952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:21:10.727] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:42:0) after 1m0s
[21:22:10.777] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:42:0)
[21:22:10.777] Timer tick, asking Buildbucket for the build status
[21:22:11.282] Build 8897688471573559952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:22:11.282] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:43:0) after 1m0s
[21:23:11.301] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:43:0)
[21:23:11.301] Timer tick, asking Buildbucket for the build status
[21:23:12.905] Build 8897688471573559952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:23:12.906] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:44:0) after 1m0s
[21:24:13.087] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:44:0)
[21:24:13.087] Timer tick, asking Buildbucket for the build status
[21:24:13.283] Build 8897688471573559952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:24:13.283] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:45:0) after 1m0s
[21:25:13.814] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:45:0)
[21:25:13.814] Timer tick, asking Buildbucket for the build status
[21:25:14.092] Build 8897688471573559952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:25:14.092] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:46:0) after 1m0s
[21:26:14.224] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:46:0)
[21:26:14.224] Timer tick, asking Buildbucket for the build status
[21:26:14.364] Build 8897688471573559952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:26:14.364] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:47:0) after 1m0s
[21:27:14.542] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:47:0)
[21:27:14.542] Timer tick, asking Buildbucket for the build status
[21:27:15.366] Build 8897688471573559952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:27:15.366] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:48:0) after 1m0s
[21:28:15.411] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:48:0)
[21:28:15.411] Timer tick, asking Buildbucket for the build status
[21:28:15.589] Build 8897688471573559952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:28:15.589] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:49:0) after 1m0s
[21:29:15.631] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:49:0)
[21:29:15.631] Timer tick, asking Buildbucket for the build status
[21:29:15.899] Build 8897688471573559952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:29:15.899] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:50:0) after 1m0s
[21:30:15.996] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:50:0)
[21:30:15.996] Timer tick, asking Buildbucket for the build status
[21:30:16.351] Build 8897688471573559952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:30:16.351] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:51:0) after 1m0s
[21:31:16.375] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:51:0)
[21:31:16.375] Timer tick, asking Buildbucket for the build status
[21:31:16.470] Build 8897688471573559952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:31:16.470] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:52:0) after 1m0s
[21:32:16.495] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:52:0)
[21:32:16.495] Timer tick, asking Buildbucket for the build status
[21:32:16.697] Build 8897688471573559952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:32:16.697] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:53:0) after 1m0s
[21:33:16.762] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:53:0)
[21:33:16.762] Timer tick, asking Buildbucket for the build status
[21:33:16.877] Build 8897688471573559952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:33:16.877] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:54:0) after 1m0s
[21:34:16.993] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:54:0)
[21:34:16.993] Timer tick, asking Buildbucket for the build status
[21:34:17.116] Build 8897688471573559952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:34:17.116] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:55:0) after 1m0s
[21:35:17.152] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:55:0)
[21:35:17.152] Timer tick, asking Buildbucket for the build status
[21:35:17.384] Build 8897688471573559952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:35:17.384] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:56:0) after 1m0s
[21:36:17.514] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:56:0)
[21:36:17.514] Timer tick, asking Buildbucket for the build status
[21:36:17.890] Build 8897688471573559952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:36:17.890] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:57:0) after 1m0s
[21:37:17.950] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:57:0)
[21:37:17.950] Timer tick, asking Buildbucket for the build status
[21:37:18.274] Build 8897688471573559952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:37:18.274] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:58:0) after 1m0s
[21:38:18.322] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:58:0)
[21:38:18.322] Timer tick, asking Buildbucket for the build status
[21:38:18.470] Build 8897688471573559952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:38:18.470] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:59:0) after 1m0s
[21:39:18.648] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:59:0)
[21:39:18.648] Timer tick, asking Buildbucket for the build status
[21:39:19.492] Build 8897688471573559952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:39:19.492] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:60:0) after 1m0s
[21:40:19.577] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:60:0)
[21:40:19.577] Timer tick, asking Buildbucket for the build status
[21:40:19.699] Build 8897688471573559952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:40:19.699] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:61:0) after 1m0s
[21:41:19.712] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:61:0)
[21:41:19.712] Timer tick, asking Buildbucket for the build status
[21:41:20.117] Build 8897688471573559952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:41:20.117] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:62:0) after 1m0s
[21:42:20.256] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:62:0)
[21:42:20.256] Timer tick, asking Buildbucket for the build status
[21:42:20.549] Build 8897688471573559952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:42:20.549] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:63:0) after 1m0s
[21:43:20.667] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:63:0)
[21:43:20.667] Timer tick, asking Buildbucket for the build status
[21:43:20.851] Build 8897688471573559952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:43:20.851] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:64:0) after 1m0s
[21:44:20.892] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:64:0)
[21:44:20.892] Timer tick, asking Buildbucket for the build status
[21:44:21.319] Build 8897688471573559952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:44:21.319] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:65:0) after 1m0s
[21:45:21.386] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:65:0)
[21:45:21.386] Timer tick, asking Buildbucket for the build status
[21:45:21.532] Build 8897688471573559952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:45:21.532] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:66:0) after 1m0s
[21:46:21.617] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:66:0)
[21:46:21.617] Timer tick, asking Buildbucket for the build status
[21:46:21.745] Build 8897688471573559952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:46:21.745] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:67:0) after 1m0s
[21:47:21.778] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:67:0)
[21:47:21.778] Timer tick, asking Buildbucket for the build status
[21:47:22.750] Build 8897688471573559952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:47:22.750] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:68:0) after 1m0s
[21:48:22.738] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:68:0)
[21:48:22.738] Timer tick, asking Buildbucket for the build status
[21:48:23.948] Build 8897688471573559952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:48:23.948] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:69:0) after 1m0s
[21:49:24.004] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:69:0)
[21:49:24.004] Timer tick, asking Buildbucket for the build status
[21:49:24.268] Build 8897688471573559952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:49:24.268] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:70:0) after 1m0s
[21:50:24.332] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:70:0)
[21:50:24.332] Timer tick, asking Buildbucket for the build status
[21:50:24.582] Build 8897688471573559952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:50:24.582] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:71:0) after 1m0s
[21:51:24.571] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:71:0)
[21:51:24.571] Timer tick, asking Buildbucket for the build status
[21:51:27.695] Build 8897688471573559952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:51:27.695] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:72:0) after 1m0s
[21:52:27.784] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:72:0)
[21:52:27.784] Timer tick, asking Buildbucket for the build status
[21:52:28.104] Build 8897688471573559952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:52:28.104] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:73:0) after 1m0s
[21:53:28.169] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:73:0)
[21:53:28.169] Timer tick, asking Buildbucket for the build status
[21:53:28.281] Build 8897688471573559952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:53:28.281] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:74:0) after 1m0s
[21:54:28.321] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:74:0)
[21:54:28.321] Timer tick, asking Buildbucket for the build status
[21:54:28.487] Build 8897688471573559952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:54:28.487] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:75:0) after 1m0s
[21:55:28.570] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:75:0)
[21:55:28.570] Timer tick, asking Buildbucket for the build status
[21:55:28.842] Build 8897688471573559952: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:55:28.842] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:76:0) after 1m0s
[21:55:59.453] Received PubSub notification, asking Buildbucket for the build status
[21:55:59.550] Build 8897688471573559952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:56:28.843] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:76:0)
[21:56:28.843] Timer tick, asking Buildbucket for the build status
[21:56:28.956] Build 8897688471573559952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:56:28.956] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:78:0) after 1m0s
[21:57:28.983] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:78:0)
[21:57:28.983] Timer tick, asking Buildbucket for the build status
[21:57:29.456] Build 8897688471573559952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:57:29.456] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:79:0) after 1m0s
[21:58:29.497] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:79:0)
[21:58:29.497] Timer tick, asking Buildbucket for the build status
[21:58:30.138] Build 8897688471573559952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:58:30.138] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:80:0) after 1m0s
[21:59:30.128] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:80:0)
[21:59:30.128] Timer tick, asking Buildbucket for the build status
[21:59:31.538] Build 8897688471573559952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:59:31.538] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:81:0) after 1m0s
[22:00:31.578] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:81:0)
[22:00:31.578] Timer tick, asking Buildbucket for the build status
[22:00:31.918] Build 8897688471573559952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:00:31.918] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:82:0) after 1m0s
[22:01:31.982] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:82:0)
[22:01:31.982] Timer tick, asking Buildbucket for the build status
[22:01:32.230] Build 8897688471573559952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:01:32.230] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:83:0) after 1m0s
[22:02:32.255] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:83:0)
[22:02:32.255] Timer tick, asking Buildbucket for the build status
[22:02:32.599] Build 8897688471573559952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:02:32.599] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:84:0) after 1m0s
[22:03:32.663] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:84:0)
[22:03:32.663] Timer tick, asking Buildbucket for the build status
[22:03:32.743] Build 8897688471573559952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:03:32.743] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:85:0) after 1m0s
[22:04:32.778] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:85:0)
[22:04:32.778] Timer tick, asking Buildbucket for the build status
[22:04:33.939] Build 8897688471573559952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:04:33.939] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:86:0) after 1m0s
[22:05:33.987] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:86:0)
[22:05:33.987] Timer tick, asking Buildbucket for the build status
[22:05:34.496] Build 8897688471573559952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:05:34.496] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:87:0) after 1m0s
[22:06:34.567] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:87:0)
[22:06:34.567] Timer tick, asking Buildbucket for the build status
[22:06:34.664] Build 8897688471573559952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:06:34.664] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:88:0) after 1m0s
[22:07:34.726] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:88:0)
[22:07:34.726] Timer tick, asking Buildbucket for the build status
[22:07:35.147] Build 8897688471573559952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:07:35.147] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:89:0) after 1m0s
[22:08:35.172] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:89:0)
[22:08:35.172] Timer tick, asking Buildbucket for the build status
[22:08:35.348] Build 8897688471573559952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:08:35.348] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:90:0) after 1m0s
[22:09:35.439] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:90:0)
[22:09:35.439] Timer tick, asking Buildbucket for the build status
[22:09:35.562] Build 8897688471573559952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:09:35.562] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:91:0) after 1m0s
[22:10:35.649] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:91:0)
[22:10:35.649] Timer tick, asking Buildbucket for the build status
[22:10:35.757] Build 8897688471573559952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:10:35.757] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:92:0) after 1m0s
[22:11:35.911] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:92:0)
[22:11:35.911] Timer tick, asking Buildbucket for the build status
[22:11:36.114] Build 8897688471573559952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:11:36.114] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:93:0) after 1m0s
[22:12:36.139] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:93:0)
[22:12:36.139] Timer tick, asking Buildbucket for the build status
[22:12:36.263] Build 8897688471573559952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:12:36.263] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:94:0) after 1m0s
[22:13:36.307] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:94:0)
[22:13:36.307] Timer tick, asking Buildbucket for the build status
[22:13:36.538] Build 8897688471573559952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:13:36.538] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:95:0) after 1m0s
[22:14:36.643] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:95:0)
[22:14:36.643] Timer tick, asking Buildbucket for the build status
[22:14:36.797] Build 8897688471573559952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:14:36.797] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:96:0) after 1m0s
[22:15:36.953] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:96:0)
[22:15:36.953] Timer tick, asking Buildbucket for the build status
[22:15:37.726] Build 8897688471573559952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:15:37.726] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:97:0) after 1m0s
[22:16:37.749] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:97:0)
[22:16:37.749] Timer tick, asking Buildbucket for the build status
[22:16:37.845] Build 8897688471573559952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:16:37.845] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:98:0) after 1m0s
[22:17:37.961] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:98:0)
[22:17:37.961] Timer tick, asking Buildbucket for the build status
[22:17:38.093] Build 8897688471573559952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:17:38.093] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:99:0) after 1m0s
[22:18:38.270] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:99:0)
[22:18:38.270] Timer tick, asking Buildbucket for the build status
[22:18:38.669] Build 8897688471573559952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:18:38.669] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:100:0) after 1m0s
[22:19:38.777] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:100:0)
[22:19:38.777] Timer tick, asking Buildbucket for the build status
[22:19:38.959] Build 8897688471573559952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:19:38.959] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:101:0) after 1m0s
[22:20:38.973] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:101:0)
[22:20:38.973] Timer tick, asking Buildbucket for the build status
[22:20:39.355] Build 8897688471573559952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:20:39.355] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:102:0) after 1m0s
[22:21:39.415] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:102:0)
[22:21:39.415] Timer tick, asking Buildbucket for the build status
[22:21:39.477] Build 8897688471573559952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:21:39.477] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:103:0) after 1m0s
[22:22:39.665] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:103:0)
[22:22:39.665] Timer tick, asking Buildbucket for the build status
[22:22:39.794] Build 8897688471573559952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:22:39.794] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:104:0) after 1m0s
[22:23:39.912] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:104:0)
[22:23:39.912] Timer tick, asking Buildbucket for the build status
[22:23:40.347] Build 8897688471573559952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:23:40.347] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:105:0) after 1m0s
[22:24:40.372] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:105:0)
[22:24:40.372] Timer tick, asking Buildbucket for the build status
[22:24:40.627] Build 8897688471573559952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:24:40.627] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:106:0) after 1m0s
[22:25:40.677] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:106:0)
[22:25:40.677] Timer tick, asking Buildbucket for the build status
[22:25:40.857] Build 8897688471573559952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:25:40.857] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:107:0) after 1m0s
[22:26:40.965] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:107:0)
[22:26:40.965] Timer tick, asking Buildbucket for the build status
[22:26:41.087] Build 8897688471573559952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:26:41.087] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:108:0) after 1m0s
[22:27:41.197] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:108:0)
[22:27:41.197] Timer tick, asking Buildbucket for the build status
[22:27:41.310] Build 8897688471573559952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:27:41.310] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:109:0) after 1m0s
[22:28:41.336] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:109:0)
[22:28:41.336] Timer tick, asking Buildbucket for the build status
[22:28:41.663] Build 8897688471573559952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:28:41.663] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:110:0) after 1m0s
[22:29:41.888] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:110:0)
[22:29:41.888] Timer tick, asking Buildbucket for the build status
[22:29:41.964] Build 8897688471573559952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:29:41.964] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:111:0) after 1m0s
[22:30:42.057] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:111:0)
[22:30:42.057] Timer tick, asking Buildbucket for the build status
[22:30:42.188] Build 8897688471573559952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:30:42.188] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:112:0) after 1m0s
[22:31:42.348] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:112:0)
[22:31:42.348] Timer tick, asking Buildbucket for the build status
[22:31:42.891] Build 8897688471573559952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:31:42.891] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:113:0) after 1m0s
[22:32:42.969] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:113:0)
[22:32:42.969] Timer tick, asking Buildbucket for the build status
[22:32:43.163] Build 8897688471573559952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:32:43.174] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:114:0) after 1m0s
[22:33:43.302] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:114:0)
[22:33:43.302] Timer tick, asking Buildbucket for the build status
[22:33:43.621] Build 8897688471573559952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:33:43.621] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:115:0) after 1m0s
[22:34:43.705] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:115:0)
[22:34:43.705] Timer tick, asking Buildbucket for the build status
[22:34:43.866] Build 8897688471573559952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:34:43.866] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:116:0) after 1m0s
[22:35:43.946] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:116:0)
[22:35:43.946] Timer tick, asking Buildbucket for the build status
[22:35:44.380] Build 8897688471573559952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:35:44.380] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:117:0) after 1m0s
[22:36:44.406] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:117:0)
[22:36:44.406] Timer tick, asking Buildbucket for the build status
[22:36:44.551] Build 8897688471573559952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:36:44.552] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:118:0) after 1m0s
[22:37:44.656] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:118:0)
[22:37:44.656] Timer tick, asking Buildbucket for the build status
[22:37:44.904] Build 8897688471573559952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:37:44.904] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:119:0) after 1m0s
[22:38:45.105] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:119:0)
[22:38:45.105] Timer tick, asking Buildbucket for the build status
[22:38:45.282] Build 8897688471573559952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:38:45.282] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:120:0) after 1m0s
[22:39:45.379] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:120:0)
[22:39:45.379] Timer tick, asking Buildbucket for the build status
[22:39:45.816] Build 8897688471573559952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:39:45.816] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:121:0) after 1m0s
[22:40:45.908] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:121:0)
[22:40:45.908] Timer tick, asking Buildbucket for the build status
[22:40:46.005] Build 8897688471573559952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:40:46.005] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:122:0) after 1m0s
[22:41:46.079] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:122:0)
[22:41:46.079] Timer tick, asking Buildbucket for the build status
[22:41:46.284] Build 8897688471573559952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:41:46.284] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:123:0) after 1m0s
[22:42:46.344] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:123:0)
[22:42:46.344] Timer tick, asking Buildbucket for the build status
[22:42:46.644] Build 8897688471573559952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:42:46.645] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:124:0) after 1m0s
[22:43:46.734] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:124:0)
[22:43:46.734] Timer tick, asking Buildbucket for the build status
[22:43:46.913] Build 8897688471573559952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:43:46.913] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:125:0) after 1m0s
[22:44:46.980] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:125:0)
[22:44:46.980] Timer tick, asking Buildbucket for the build status
[22:44:47.273] Build 8897688471573559952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:44:47.273] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:126:0) after 1m0s
[22:45:47.251] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:126:0)
[22:45:47.251] Timer tick, asking Buildbucket for the build status
[22:45:47.386] Build 8897688471573559952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:45:47.386] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:127:0) after 1m0s
[22:46:47.419] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:127:0)
[22:46:47.419] Timer tick, asking Buildbucket for the build status
[22:46:47.497] Build 8897688471573559952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:46:47.497] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:128:0) after 1m0s
[22:47:47.556] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:128:0)
[22:47:47.556] Timer tick, asking Buildbucket for the build status
[22:47:47.708] Build 8897688471573559952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:47:47.708] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:129:0) after 1m0s
[22:48:47.757] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:129:0)
[22:48:47.757] Timer tick, asking Buildbucket for the build status
[22:48:47.883] Build 8897688471573559952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:48:47.883] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:130:0) after 1m0s
[22:49:47.976] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:130:0)
[22:49:47.976] Timer tick, asking Buildbucket for the build status
[22:49:48.198] Build 8897688471573559952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:49:48.198] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:131:0) after 1m0s
[22:50:48.189] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:131:0)
[22:50:48.189] Timer tick, asking Buildbucket for the build status
[22:50:48.323] Build 8897688471573559952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:50:48.323] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:132:0) after 1m0s
[22:51:48.438] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:132:0)
[22:51:48.438] Timer tick, asking Buildbucket for the build status
[22:51:48.772] Build 8897688471573559952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:51:48.772] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:133:0) after 1m0s
[22:52:48.902] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:133:0)
[22:52:48.902] Timer tick, asking Buildbucket for the build status
[22:52:48.976] Build 8897688471573559952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:52:48.976] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:134:0) after 1m0s
[22:53:48.960] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:134:0)
[22:53:48.960] Timer tick, asking Buildbucket for the build status
[22:53:49.543] Build 8897688471573559952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:53:49.543] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:135:0) after 1m0s
[22:54:49.655] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:135:0)
[22:54:49.655] Timer tick, asking Buildbucket for the build status
[22:54:49.780] Build 8897688471573559952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:54:49.780] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:136:0) after 1m0s
[22:55:49.837] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:136:0)
[22:55:49.837] Timer tick, asking Buildbucket for the build status
[22:55:49.941] Build 8897688471573559952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:55:49.941] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:137:0) after 1m0s
[22:56:49.982] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:137:0)
[22:56:49.982] Timer tick, asking Buildbucket for the build status
[22:56:50.442] Build 8897688471573559952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:56:50.442] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:138:0) after 1m0s
[22:57:50.790] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:138:0)
[22:57:50.790] Timer tick, asking Buildbucket for the build status
[22:57:50.927] Build 8897688471573559952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:57:50.927] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:139:0) after 1m0s
[22:58:51.027] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:139:0)
[22:58:51.027] Timer tick, asking Buildbucket for the build status
[22:58:51.307] Build 8897688471573559952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:58:51.307] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:140:0) after 1m0s
[22:59:51.453] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:140:0)
[22:59:51.453] Timer tick, asking Buildbucket for the build status
[22:59:51.546] Build 8897688471573559952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:59:51.546] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:141:0) after 1m0s
[23:00:51.610] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:141:0)
[23:00:51.610] Timer tick, asking Buildbucket for the build status
[23:00:51.803] Build 8897688471573559952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:00:51.803] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063118533553881664:142:0) after 1m0s
[23:00:56.599] Received PubSub notification, asking Buildbucket for the build status
[23:00:56.769] Build 8897688471573559952: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[23:00:56.769] Buildbucket build:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary": true,
"canary_preference": "CANARY",
"completed_ts": "1572908455725788",
"created_by": "project:emscripten-releases",
"created_ts": "1572900051484934",
"id": "8897688471573559952",
"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\": \"b626b7a57eaf7025011cd569471736a8d29b097b\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@b626b7a57eaf7025011cd569471736a8d29b097b\", \"title\": \"b626b7a57eaf7025011cd569471736a8d29b097b\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/b626b7a57eaf7025011cd569471736a8d29b097b\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"b626b7a57eaf7025011cd569471736a8d29b097b\"}}",
"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\": \"b626b7a57eaf7025011cd569471736a8d29b097b\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@b626b7a57eaf7025011cd569471736a8d29b097b\", \"title\": \"b626b7a57eaf7025011cd569471736a8d29b097b\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/b626b7a57eaf7025011cd569471736a8d29b097b\"}]}, \"bot_id\": \"luci-emscripten-releases-ci-win10-0-hk60\", \"branch\": \"refs/heads/master\", \"buildername\": \"win\", \"got_revision\": \"b626b7a57eaf7025011cd569471736a8d29b097b\", \"path_config\": \"generic\", \"recipe\": \"emscripten_releases\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"b626b7a57eaf7025011cd569471736a8d29b097b\"}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"git\", \"goma_v2\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"host_class\": [\"default\"], \"id\": [\"luci-emscripten-releases-ci-win10-0-hk60\"], \"image\": [\"chrome-win10-1703-19102400-d35fedf5a0d\"], \"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\": [\"4621-f8a5b1c\"], \"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": "1572904557463878",
"status": "COMPLETED",
"status_changed_ts": "1572908456061632",
"tags": [
"builder:win",
"buildset:commit/git/b626b7a57eaf7025011cd569471736a8d29b097b",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/b626b7a57eaf7025011cd569471736a8d29b097b",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9063118533553881664",
"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/8897688471573559952/+/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:4850f84f352ef810",
"user_agent:luci-scheduler"
],
"updated_ts": "1572908456079708",
"url": "https://ci.chromium.org/b/8897688471573559952",
"utcnow_ts": "1572908456754715"
}
}
[23:00:56.769] Invocation finished in 2h20m6.556839388s with status SUCCEEDED