[22:09:31.536] New invocation is queued and will start shortly
[22:09:32.716] Starting the invocation (attempt 1)
[22:09:32.754] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[22:09:32.754] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[22:09:32.754] Buildbucket request:
{
"bucket": "luci.emscripten-releases.ci",
"client_operation_id": "9032672373048655872",
"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\":\"060a73f3ce619979a47e937579043d126cf9b287\"},\"id\":\"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@060a73f3ce619979a47e937579043d126cf9b287\",\"title\":\"060a73f3ce619979a47e937579043d126cf9b287\",\"url\":\"https://chromium.googlesource.com/emscripten-releases/+/060a73f3ce619979a47e937579043d126cf9b287\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/emscripten-releases\",\"revision\":\"060a73f3ce619979a47e937579043d126cf9b287\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win",
"scheduler_invocation_id:9032672373048655872",
"scheduler_job_id:emscripten-releases/win",
"user_agent:luci-scheduler",
"buildset:commit/git/060a73f3ce619979a47e937579043d126cf9b287",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/060a73f3ce619979a47e937579043d126cf9b287",
"gitiles_ref:refs/heads/master"
]
}
[22:09:33.350] Buildbucket response:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary_preference": "PROD",
"created_by": "project:emscripten-releases",
"created_ts": "1601935772868397",
"id": "8867242310987270160",
"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\": \"060a73f3ce619979a47e937579043d126cf9b287\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@060a73f3ce619979a47e937579043d126cf9b287\", \"title\": \"060a73f3ce619979a47e937579043d126cf9b287\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/060a73f3ce619979a47e937579043d126cf9b287\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"060a73f3ce619979a47e937579043d126cf9b287\"}}",
"project": "emscripten-releases",
"result_details_json": "{\"properties\": {}}",
"service_account": "emscripten-releases-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1601935773204478",
"tags": [
"builder:win",
"buildset:commit/git/060a73f3ce619979a47e937579043d126cf9b287",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/060a73f3ce619979a47e937579043d126cf9b287",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9032672373048655872",
"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/8867242310987270160/+/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": "1601935773204588",
"url": "https://ci.chromium.org/b/8867242310987270160",
"utcnow_ts": "1601935773341013"
}
}
[22:09:33.350] Task URL: https://ci.chromium.org/b/8867242310987270160
[22:09:33.350] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:2:0) after 1m0s
[22:10:33.337] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:2:0)
[22:10:33.337] Timer tick, asking Buildbucket for the build status
[22:10:33.564] Build 8867242310987270160: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:10:33.564] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:3:0) after 1m0s
[22:11:33.617] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:3:0)
[22:11:33.617] Timer tick, asking Buildbucket for the build status
[22:11:33.896] Build 8867242310987270160: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:11:33.896] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:4:0) after 1m0s
[22:12:33.938] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:4:0)
[22:12:33.938] Timer tick, asking Buildbucket for the build status
[22:12:35.825] Build 8867242310987270160: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:12:35.825] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:5:0) after 1m0s
[22:13:35.843] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:5:0)
[22:13:35.843] Timer tick, asking Buildbucket for the build status
[22:13:35.953] Build 8867242310987270160: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:13:35.953] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:6:0) after 1m0s
[22:14:35.971] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:6:0)
[22:14:35.971] Timer tick, asking Buildbucket for the build status
[22:14:36.300] Build 8867242310987270160: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:14:36.300] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:7:0) after 1m0s
[22:15:36.341] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:7:0)
[22:15:36.341] Timer tick, asking Buildbucket for the build status
[22:15:36.453] Build 8867242310987270160: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:15:36.453] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:8:0) after 1m0s
[22:16:36.487] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:8:0)
[22:16:36.487] Timer tick, asking Buildbucket for the build status
[22:16:36.703] Build 8867242310987270160: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:16:36.703] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:9:0) after 1m0s
[22:17:36.716] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:9:0)
[22:17:36.716] Timer tick, asking Buildbucket for the build status
[22:17:36.822] Build 8867242310987270160: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:17:36.822] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:10:0) after 1m0s
[22:18:36.819] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:10:0)
[22:18:36.819] Timer tick, asking Buildbucket for the build status
[22:18:36.926] Build 8867242310987270160: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:18:36.926] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:11:0) after 1m0s
[22:19:36.963] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:11:0)
[22:19:36.963] Timer tick, asking Buildbucket for the build status
[22:19:37.091] Build 8867242310987270160: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:19:37.091] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:12:0) after 1m0s
[22:20:37.285] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:12:0)
[22:20:37.286] Timer tick, asking Buildbucket for the build status
[22:20:38.870] Build 8867242310987270160: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:20:38.870] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:13:0) after 1m0s
[22:21:38.899] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:13:0)
[22:21:38.899] Timer tick, asking Buildbucket for the build status
[22:21:39.071] Build 8867242310987270160: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:21:39.071] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:14:0) after 1m0s
[22:22:39.131] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:14:0)
[22:22:39.131] Timer tick, asking Buildbucket for the build status
[22:22:39.249] Build 8867242310987270160: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:22:39.249] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:15:0) after 1m0s
[22:23:39.302] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:15:0)
[22:23:39.302] Timer tick, asking Buildbucket for the build status
[22:23:39.437] Build 8867242310987270160: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:23:39.437] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:16:0) after 1m0s
[22:24:39.493] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:16:0)
[22:24:39.493] Timer tick, asking Buildbucket for the build status
[22:24:39.635] Build 8867242310987270160: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:24:39.635] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:17:0) after 1m0s
[22:25:39.635] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:17:0)
[22:25:39.635] Timer tick, asking Buildbucket for the build status
[22:25:39.738] Build 8867242310987270160: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:25:39.738] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:18:0) after 1m0s
[22:26:40.297] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:18:0)
[22:26:40.298] Timer tick, asking Buildbucket for the build status
[22:26:40.449] Build 8867242310987270160: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:26:40.449] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:19:0) after 1m0s
[22:27:40.919] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:19:0)
[22:27:40.919] Timer tick, asking Buildbucket for the build status
[22:27:41.109] Build 8867242310987270160: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:27:41.110] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:20:0) after 1m0s
[22:28:41.288] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:20:0)
[22:28:41.288] Timer tick, asking Buildbucket for the build status
[22:28:42.070] Build 8867242310987270160: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:28:42.071] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:21:0) after 1m0s
[22:29:42.316] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:21:0)
[22:29:42.316] Timer tick, asking Buildbucket for the build status
[22:29:42.431] Build 8867242310987270160: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:29:42.431] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:22:0) after 1m0s
[22:30:42.549] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:22:0)
[22:30:42.549] Timer tick, asking Buildbucket for the build status
[22:30:42.646] Build 8867242310987270160: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:30:42.646] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:23:0) after 1m0s
[22:31:42.902] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:23:0)
[22:31:42.902] Timer tick, asking Buildbucket for the build status
[22:31:43.012] Build 8867242310987270160: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:31:43.012] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:24:0) after 1m0s
[22:32:43.125] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:24:0)
[22:32:43.125] Timer tick, asking Buildbucket for the build status
[22:32:43.210] Build 8867242310987270160: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:32:43.210] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:25:0) after 1m0s
[22:33:43.307] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:25:0)
[22:33:43.307] Timer tick, asking Buildbucket for the build status
[22:33:44.360] Build 8867242310987270160: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:33:44.360] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:26:0) after 1m0s
[22:34:44.462] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:26:0)
[22:34:44.462] Timer tick, asking Buildbucket for the build status
[22:34:44.755] Build 8867242310987270160: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:34:44.755] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:27:0) after 1m0s
[22:35:44.762] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:27:0)
[22:35:44.762] Timer tick, asking Buildbucket for the build status
[22:35:45.071] Build 8867242310987270160: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:35:45.071] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:28:0) after 1m0s
[22:36:45.083] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:28:0)
[22:36:45.083] Timer tick, asking Buildbucket for the build status
[22:36:45.184] Build 8867242310987270160: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:36:45.184] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:29:0) after 1m0s
[22:37:45.279] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:29:0)
[22:37:45.279] Timer tick, asking Buildbucket for the build status
[22:37:45.414] Build 8867242310987270160: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:37:45.414] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:30:0) after 1m0s
[22:38:45.503] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:30:0)
[22:38:45.503] Timer tick, asking Buildbucket for the build status
[22:38:45.696] Build 8867242310987270160: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:38:45.696] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:31:0) after 1m0s
[22:39:45.759] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:31:0)
[22:39:45.759] Timer tick, asking Buildbucket for the build status
[22:39:45.891] Build 8867242310987270160: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:39:45.891] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:32:0) after 1m0s
[22:40:46.004] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:32:0)
[22:40:46.004] Timer tick, asking Buildbucket for the build status
[22:40:46.116] Build 8867242310987270160: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:40:46.116] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:33:0) after 1m0s
[22:41:46.181] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:33:0)
[22:41:46.181] Timer tick, asking Buildbucket for the build status
[22:41:46.545] Build 8867242310987270160: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:41:46.545] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:34:0) after 1m0s
[22:42:46.602] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:34:0)
[22:42:46.602] Timer tick, asking Buildbucket for the build status
[22:42:46.698] Build 8867242310987270160: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:42:46.698] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:35:0) after 1m0s
[22:43:46.750] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:35:0)
[22:43:46.750] Timer tick, asking Buildbucket for the build status
[22:43:46.831] Build 8867242310987270160: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:43:46.831] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:36:0) after 1m0s
[22:44:46.964] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:36:0)
[22:44:46.964] Timer tick, asking Buildbucket for the build status
[22:44:47.156] Build 8867242310987270160: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:44:47.156] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:37:0) after 1m0s
[22:45:47.200] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:37:0)
[22:45:47.200] Timer tick, asking Buildbucket for the build status
[22:45:47.322] Build 8867242310987270160: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:45:47.322] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:38:0) after 1m0s
[22:46:47.307] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:38:0)
[22:46:47.307] Timer tick, asking Buildbucket for the build status
[22:46:47.800] Build 8867242310987270160: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:46:47.800] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:39:0) after 1m0s
[22:47:47.934] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:39:0)
[22:47:47.934] Timer tick, asking Buildbucket for the build status
[22:47:48.310] Build 8867242310987270160: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:47:48.310] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:40:0) after 1m0s
[22:48:48.420] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:40:0)
[22:48:48.420] Timer tick, asking Buildbucket for the build status
[22:48:48.502] Build 8867242310987270160: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:48:48.502] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:41:0) after 1m0s
[22:49:48.544] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:41:0)
[22:49:48.544] Timer tick, asking Buildbucket for the build status
[22:49:48.652] Build 8867242310987270160: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:49:48.652] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:42:0) after 1m0s
[22:50:48.722] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:42:0)
[22:50:48.722] Timer tick, asking Buildbucket for the build status
[22:50:48.800] Build 8867242310987270160: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:50:48.800] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:43:0) after 1m0s
[22:51:48.806] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:43:0)
[22:51:48.806] Timer tick, asking Buildbucket for the build status
[22:51:48.890] Build 8867242310987270160: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:51:48.890] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:44:0) after 1m0s
[22:52:48.941] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:44:0)
[22:52:48.941] Timer tick, asking Buildbucket for the build status
[22:52:49.346] Build 8867242310987270160: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:52:49.346] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:45:0) after 1m0s
[22:53:49.384] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:45:0)
[22:53:49.384] Timer tick, asking Buildbucket for the build status
[22:53:49.946] Build 8867242310987270160: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:53:49.946] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:46:0) after 1m0s
[22:54:49.952] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:46:0)
[22:54:49.952] Timer tick, asking Buildbucket for the build status
[22:54:50.109] Build 8867242310987270160: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:54:50.109] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:47:0) after 1m0s
[22:55:26.056] Received PubSub notification, asking Buildbucket for the build status
[22:55:26.142] Build 8867242310987270160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:55:50.157] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:47:0)
[22:55:50.157] Timer tick, asking Buildbucket for the build status
[22:55:50.246] Build 8867242310987270160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:55:50.246] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:49:0) after 1m0s
[22:56:50.295] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:49:0)
[22:56:50.295] Timer tick, asking Buildbucket for the build status
[22:56:50.474] Build 8867242310987270160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:56:50.474] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:50:0) after 1m0s
[22:57:50.497] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:50:0)
[22:57:50.497] Timer tick, asking Buildbucket for the build status
[22:57:50.628] Build 8867242310987270160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:57:50.628] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:51:0) after 1m0s
[22:58:50.649] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:51:0)
[22:58:50.649] Timer tick, asking Buildbucket for the build status
[22:58:50.799] Build 8867242310987270160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:58:50.799] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:52:0) after 1m0s
[22:59:50.930] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:52:0)
[22:59:50.930] Timer tick, asking Buildbucket for the build status
[22:59:51.093] Build 8867242310987270160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:59:51.093] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:53:0) after 1m0s
[23:00:51.146] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:53:0)
[23:00:51.146] Timer tick, asking Buildbucket for the build status
[23:00:51.259] Build 8867242310987270160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:00:51.259] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:54:0) after 1m0s
[23:01:51.257] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:54:0)
[23:01:51.257] Timer tick, asking Buildbucket for the build status
[23:01:51.346] Build 8867242310987270160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:01:51.346] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:55:0) after 1m0s
[23:02:51.474] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:55:0)
[23:02:51.474] Timer tick, asking Buildbucket for the build status
[23:02:51.565] Build 8867242310987270160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:02:51.565] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:56:0) after 1m0s
[23:03:51.632] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:56:0)
[23:03:51.632] Timer tick, asking Buildbucket for the build status
[23:03:51.703] Build 8867242310987270160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:03:51.703] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:57:0) after 1m0s
[23:04:51.802] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:57:0)
[23:04:51.802] Timer tick, asking Buildbucket for the build status
[23:04:51.912] Build 8867242310987270160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:04:51.912] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:58:0) after 1m0s
[23:05:52.038] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:58:0)
[23:05:52.038] Timer tick, asking Buildbucket for the build status
[23:05:52.112] Build 8867242310987270160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:05:52.112] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:59:0) after 1m0s
[23:06:52.202] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:59:0)
[23:06:52.202] Timer tick, asking Buildbucket for the build status
[23:06:52.283] Build 8867242310987270160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:06:52.283] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:60:0) after 1m0s
[23:07:52.373] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:60:0)
[23:07:52.374] Timer tick, asking Buildbucket for the build status
[23:07:52.543] Build 8867242310987270160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:07:52.543] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:61:0) after 1m0s
[23:08:52.560] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:61:0)
[23:08:52.560] Timer tick, asking Buildbucket for the build status
[23:08:52.711] Build 8867242310987270160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:08:52.711] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:62:0) after 1m0s
[23:09:52.709] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:62:0)
[23:09:52.709] Timer tick, asking Buildbucket for the build status
[23:09:52.836] Build 8867242310987270160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:09:52.836] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:63:0) after 1m0s
[23:10:52.916] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:63:0)
[23:10:52.916] Timer tick, asking Buildbucket for the build status
[23:10:54.777] Build 8867242310987270160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:10:54.777] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:64:0) after 1m0s
[23:11:54.780] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:64:0)
[23:11:54.780] Timer tick, asking Buildbucket for the build status
[23:11:54.858] Build 8867242310987270160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:11:54.858] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:65:0) after 1m0s
[23:12:54.900] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:65:0)
[23:12:54.900] Timer tick, asking Buildbucket for the build status
[23:12:55.218] Build 8867242310987270160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:12:55.218] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:66:0) after 1m0s
[23:13:55.245] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:66:0)
[23:13:55.245] Timer tick, asking Buildbucket for the build status
[23:13:55.313] Build 8867242310987270160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:13:55.313] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:67:0) after 1m0s
[23:14:55.336] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:67:0)
[23:14:55.336] Timer tick, asking Buildbucket for the build status
[23:14:55.479] Build 8867242310987270160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:14:55.479] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:68:0) after 1m0s
[23:15:55.590] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:68:0)
[23:15:55.590] Timer tick, asking Buildbucket for the build status
[23:15:55.668] Build 8867242310987270160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:15:55.668] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:69:0) after 1m0s
[23:16:55.715] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:69:0)
[23:16:55.715] Timer tick, asking Buildbucket for the build status
[23:16:55.868] Build 8867242310987270160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:16:55.868] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:70:0) after 1m0s
[23:17:55.903] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:70:0)
[23:17:55.903] Timer tick, asking Buildbucket for the build status
[23:17:56.102] Build 8867242310987270160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:17:56.102] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:71:0) after 1m0s
[23:18:56.218] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:71:0)
[23:18:56.218] Timer tick, asking Buildbucket for the build status
[23:18:56.388] Build 8867242310987270160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:18:56.388] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:72:0) after 1m0s
[23:19:56.593] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:72:0)
[23:19:56.593] Timer tick, asking Buildbucket for the build status
[23:19:56.710] Build 8867242310987270160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:19:56.710] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:73:0) after 1m0s
[23:20:56.844] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:73:0)
[23:20:56.844] Timer tick, asking Buildbucket for the build status
[23:20:57.940] Build 8867242310987270160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:20:57.940] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:74:0) after 1m0s
[23:21:58.016] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:74:0)
[23:21:58.016] Timer tick, asking Buildbucket for the build status
[23:21:58.111] Build 8867242310987270160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:21:58.111] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:75:0) after 1m0s
[23:22:58.238] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:75:0)
[23:22:58.238] Timer tick, asking Buildbucket for the build status
[23:22:58.362] Build 8867242310987270160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:22:58.362] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:76:0) after 1m0s
[23:23:58.549] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:76:0)
[23:23:58.549] Timer tick, asking Buildbucket for the build status
[23:23:58.614] Build 8867242310987270160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:23:58.614] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:77:0) after 1m0s
[23:24:58.635] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:77:0)
[23:24:58.635] Timer tick, asking Buildbucket for the build status
[23:24:58.735] Build 8867242310987270160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:24:58.735] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:78:0) after 1m0s
[23:25:58.782] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:78:0)
[23:25:58.782] Timer tick, asking Buildbucket for the build status
[23:25:58.863] Build 8867242310987270160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:25:58.863] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:79:0) after 1m0s
[23:26:58.858] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:79:0)
[23:26:58.858] Timer tick, asking Buildbucket for the build status
[23:26:59.066] Build 8867242310987270160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:26:59.066] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:80:0) after 1m0s
[23:27:59.194] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:80:0)
[23:27:59.194] Timer tick, asking Buildbucket for the build status
[23:27:59.522] Build 8867242310987270160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:27:59.522] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:81:0) after 1m0s
[23:28:59.645] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:81:0)
[23:28:59.645] Timer tick, asking Buildbucket for the build status
[23:28:59.994] Build 8867242310987270160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:28:59.994] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:82:0) after 1m0s
[23:30:00.027] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:82:0)
[23:30:00.027] Timer tick, asking Buildbucket for the build status
[23:30:00.325] Build 8867242310987270160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:30:00.325] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:83:0) after 1m0s
[23:31:01.008] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:83:0)
[23:31:01.008] Timer tick, asking Buildbucket for the build status
[23:31:01.091] Build 8867242310987270160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:31:01.091] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:84:0) after 1m0s
[23:32:02.481] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:84:0)
[23:32:02.481] Timer tick, asking Buildbucket for the build status
[23:32:02.679] Build 8867242310987270160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:32:02.679] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:85:0) after 1m0s
[23:33:02.701] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:85:0)
[23:33:02.701] Timer tick, asking Buildbucket for the build status
[23:33:03.203] Build 8867242310987270160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:33:03.203] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:86:0) after 1m0s
[23:34:03.248] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:86:0)
[23:34:03.248] Timer tick, asking Buildbucket for the build status
[23:34:03.521] Build 8867242310987270160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:34:03.521] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:87:0) after 1m0s
[23:35:03.660] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:87:0)
[23:35:03.660] Timer tick, asking Buildbucket for the build status
[23:35:03.896] Build 8867242310987270160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:35:03.896] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:88:0) after 1m0s
[23:36:04.461] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:88:0)
[23:36:04.461] Timer tick, asking Buildbucket for the build status
[23:36:04.539] Build 8867242310987270160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:36:04.539] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:89:0) after 1m0s
[23:37:04.568] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:89:0)
[23:37:04.568] Timer tick, asking Buildbucket for the build status
[23:37:04.879] Build 8867242310987270160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:37:04.879] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:90:0) after 1m0s
[23:38:04.980] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:90:0)
[23:38:04.980] Timer tick, asking Buildbucket for the build status
[23:38:05.068] Build 8867242310987270160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:38:05.068] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:91:0) after 1m0s
[23:39:05.135] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:91:0)
[23:39:05.135] Timer tick, asking Buildbucket for the build status
[23:39:05.217] Build 8867242310987270160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:39:05.217] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:92:0) after 1m0s
[23:40:05.569] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:92:0)
[23:40:05.569] Timer tick, asking Buildbucket for the build status
[23:40:05.850] Build 8867242310987270160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:40:05.850] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:93:0) after 1m0s
[23:41:05.925] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:93:0)
[23:41:05.925] Timer tick, asking Buildbucket for the build status
[23:41:05.993] Build 8867242310987270160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:41:05.993] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:94:0) after 1m0s
[23:42:06.114] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:94:0)
[23:42:06.114] Timer tick, asking Buildbucket for the build status
[23:42:06.257] Build 8867242310987270160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:42:06.257] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:95:0) after 1m0s
[23:43:06.318] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:95:0)
[23:43:06.318] Timer tick, asking Buildbucket for the build status
[23:43:06.648] Build 8867242310987270160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:43:06.648] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:96:0) after 1m0s
[23:44:06.645] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:96:0)
[23:44:06.645] Timer tick, asking Buildbucket for the build status
[23:44:06.745] Build 8867242310987270160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:44:06.745] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:97:0) after 1m0s
[23:45:06.784] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:97:0)
[23:45:06.784] Timer tick, asking Buildbucket for the build status
[23:45:06.935] Build 8867242310987270160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:45:06.935] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:98:0) after 1m0s
[23:46:06.994] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:98:0)
[23:46:06.994] Timer tick, asking Buildbucket for the build status
[23:46:07.299] Build 8867242310987270160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:46:07.299] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:99:0) after 1m0s
[23:47:07.433] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:99:0)
[23:47:07.433] Timer tick, asking Buildbucket for the build status
[23:47:07.544] Build 8867242310987270160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:47:07.544] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:100:0) after 1m0s
[23:48:07.602] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:100:0)
[23:48:07.602] Timer tick, asking Buildbucket for the build status
[23:48:07.817] Build 8867242310987270160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:48:07.817] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:101:0) after 1m0s
[23:49:07.832] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:101:0)
[23:49:07.832] Timer tick, asking Buildbucket for the build status
[23:49:08.127] Build 8867242310987270160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:49:08.127] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:102:0) after 1m0s
[23:50:08.153] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:102:0)
[23:50:08.153] Timer tick, asking Buildbucket for the build status
[23:50:08.345] Build 8867242310987270160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:50:08.345] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:103:0) after 1m0s
[23:51:08.401] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:103:0)
[23:51:08.401] Timer tick, asking Buildbucket for the build status
[23:51:08.478] Build 8867242310987270160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:51:08.478] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:104:0) after 1m0s
[23:52:08.508] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:104:0)
[23:52:08.508] Timer tick, asking Buildbucket for the build status
[23:52:08.619] Build 8867242310987270160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:52:08.619] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:105:0) after 1m0s
[23:53:08.890] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:105:0)
[23:53:08.890] Timer tick, asking Buildbucket for the build status
[23:53:09.063] Build 8867242310987270160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:53:09.063] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:106:0) after 1m0s
[23:54:09.190] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:106:0)
[23:54:09.190] Timer tick, asking Buildbucket for the build status
[23:54:09.264] Build 8867242310987270160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:54:09.264] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:107:0) after 1m0s
[23:55:09.339] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:107:0)
[23:55:09.339] Timer tick, asking Buildbucket for the build status
[23:55:09.523] Build 8867242310987270160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:55:09.523] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:108:0) after 1m0s
[23:56:09.575] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:108:0)
[23:56:09.575] Timer tick, asking Buildbucket for the build status
[23:56:09.646] Build 8867242310987270160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:56:09.646] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:109:0) after 1m0s
[23:57:09.672] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:109:0)
[23:57:09.672] Timer tick, asking Buildbucket for the build status
[23:57:09.808] Build 8867242310987270160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:57:09.811] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:110:0) after 1m0s
[23:58:09.843] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:110:0)
[23:58:09.843] Timer tick, asking Buildbucket for the build status
[23:58:12.122] Build 8867242310987270160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:58:12.122] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:111:0) after 1m0s
[23:59:12.170] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:111:0)
[23:59:12.170] Timer tick, asking Buildbucket for the build status
[23:59:12.309] Build 8867242310987270160: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:59:12.309] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9032672373048655872:112:0) after 1m0s
[23:59:39.350] Received PubSub notification, asking Buildbucket for the build status
[23:59:39.448] Build 8867242310987270160: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[23:59:39.448] Buildbucket build:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary_preference": "PROD",
"completed_ts": "1601942378307849",
"created_by": "project:emscripten-releases",
"created_ts": "1601935772868397",
"id": "8867242310987270160",
"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\": \"060a73f3ce619979a47e937579043d126cf9b287\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@060a73f3ce619979a47e937579043d126cf9b287\", \"title\": \"060a73f3ce619979a47e937579043d126cf9b287\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/060a73f3ce619979a47e937579043d126cf9b287\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"060a73f3ce619979a47e937579043d126cf9b287\"}}",
"project": "emscripten-releases",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/goma\": {\"enable_ats\": true, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\"}, \"$recipe_engine/path\": {\"cache_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\cache\", \"temp_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\tmp\\\\rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"060a73f3ce619979a47e937579043d126cf9b287\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@060a73f3ce619979a47e937579043d126cf9b287\", \"title\": \"060a73f3ce619979a47e937579043d126cf9b287\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/060a73f3ce619979a47e937579043d126cf9b287\"}]}, \"bot_id\": \"luci-emscripten-releases-ci-win10-0-asxh\", \"branch\": \"refs/heads/master\", \"buildername\": \"win\", \"got_revision\": \"060a73f3ce619979a47e937579043d126cf9b287\", \"path_config\": \"generic\", \"recipe\": \"emscripten_releases\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"060a73f3ce619979a47e937579043d126cf9b287\"}, \"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-asxh\"], \"image\": [\"chrome-win10-1703-20092300-22ba5320847\"], \"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\": [\"5564-e41b668\"], \"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": "1601938525139984",
"status": "COMPLETED",
"status_changed_ts": "1601942378740707",
"tags": [
"builder:win",
"buildset:commit/git/060a73f3ce619979a47e937579043d126cf9b287",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/060a73f3ce619979a47e937579043d126cf9b287",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9032672373048655872",
"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/8867242310987270160/+/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:4f13a19722080210",
"user_agent:luci-scheduler"
],
"updated_ts": "1601942378765958",
"url": "https://ci.chromium.org/b/8867242310987270160",
"utcnow_ts": "1601942379438044"
}
}
[23:59:39.448] Invocation finished in 1h50m7.928623391s with status SUCCEEDED