[23:03:04.532] New invocation is queued and will start shortly
[23:03:05.643] Starting the invocation (attempt 1)
[23:03:05.685] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[23:03:05.685] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[23:03:05.685] Buildbucket request:
{
"bucket": "luci.emscripten-releases.ci",
"client_operation_id": "9050426009415472112",
"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\":\"ae9ace10d05b2365f8369405a92427abd845d0e4\"},\"id\":\"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@ae9ace10d05b2365f8369405a92427abd845d0e4\",\"title\":\"ae9ace10d05b2365f8369405a92427abd845d0e4\",\"url\":\"https://chromium.googlesource.com/emscripten-releases/+/ae9ace10d05b2365f8369405a92427abd845d0e4\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/emscripten-releases\",\"revision\":\"ae9ace10d05b2365f8369405a92427abd845d0e4\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win",
"scheduler_invocation_id:9050426009415472112",
"scheduler_job_id:emscripten-releases/win",
"user_agent:luci-scheduler",
"buildset:commit/git/ae9ace10d05b2365f8369405a92427abd845d0e4",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/ae9ace10d05b2365f8369405a92427abd845d0e4",
"gitiles_ref:refs/heads/master"
]
}
[23:03:06.487] Buildbucket response:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary_preference": "PROD",
"created_by": "project:emscripten-releases",
"created_ts": "1585004585816331",
"id": "8884995947381804816",
"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\": \"ae9ace10d05b2365f8369405a92427abd845d0e4\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@ae9ace10d05b2365f8369405a92427abd845d0e4\", \"title\": \"ae9ace10d05b2365f8369405a92427abd845d0e4\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/ae9ace10d05b2365f8369405a92427abd845d0e4\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"ae9ace10d05b2365f8369405a92427abd845d0e4\"}}",
"project": "emscripten-releases",
"result_details_json": "{\"properties\": {}}",
"service_account": "emscripten-releases-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1585004586242213",
"tags": [
"builder:win",
"buildset:commit/git/ae9ace10d05b2365f8369405a92427abd845d0e4",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/ae9ace10d05b2365f8369405a92427abd845d0e4",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9050426009415472112",
"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/8884995947381804816/+/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": "1585004586242394",
"url": "https://ci.chromium.org/b/8884995947381804816",
"utcnow_ts": "1585004586473282"
}
}
[23:03:06.487] Task URL: https://ci.chromium.org/b/8884995947381804816
[23:03:06.487] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:2:0) after 1m0s
[23:04:06.674] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:2:0)
[23:04:06.674] Timer tick, asking Buildbucket for the build status
[23:04:07.602] Build 8884995947381804816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:04:07.602] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:3:0) after 1m0s
[23:05:07.619] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:3:0)
[23:05:07.619] Timer tick, asking Buildbucket for the build status
[23:05:07.754] Build 8884995947381804816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:05:07.754] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:4:0) after 1m0s
[23:06:07.907] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:4:0)
[23:06:07.907] Timer tick, asking Buildbucket for the build status
[23:06:09.656] Build 8884995947381804816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:06:09.656] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:5:0) after 1m0s
[23:07:09.722] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:5:0)
[23:07:09.722] Timer tick, asking Buildbucket for the build status
[23:07:09.981] Build 8884995947381804816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:07:09.982] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:6:0) after 1m0s
[23:08:10.059] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:6:0)
[23:08:10.059] Timer tick, asking Buildbucket for the build status
[23:08:10.293] Build 8884995947381804816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:08:10.293] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:7:0) after 1m0s
[23:09:10.323] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:7:0)
[23:09:10.323] Timer tick, asking Buildbucket for the build status
[23:09:10.564] Build 8884995947381804816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:09:10.564] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:8:0) after 1m0s
[23:10:10.590] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:8:0)
[23:10:10.590] Timer tick, asking Buildbucket for the build status
[23:10:10.705] Build 8884995947381804816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:10:10.705] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:9:0) after 1m0s
[23:11:10.791] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:9:0)
[23:11:10.791] Timer tick, asking Buildbucket for the build status
[23:11:10.931] Build 8884995947381804816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:11:10.931] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:10:0) after 1m0s
[23:12:10.923] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:10:0)
[23:12:10.923] Timer tick, asking Buildbucket for the build status
[23:12:11.085] Build 8884995947381804816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:12:11.085] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:11:0) after 1m0s
[23:13:11.109] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:11:0)
[23:13:11.109] Timer tick, asking Buildbucket for the build status
[23:13:11.258] Build 8884995947381804816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:13:11.258] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:12:0) after 1m0s
[23:14:11.371] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:12:0)
[23:14:11.371] Timer tick, asking Buildbucket for the build status
[23:14:11.530] Build 8884995947381804816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:14:11.530] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:13:0) after 1m0s
[23:15:11.647] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:13:0)
[23:15:11.647] Timer tick, asking Buildbucket for the build status
[23:15:11.764] Build 8884995947381804816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:15:11.764] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:14:0) after 1m0s
[23:16:11.789] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:14:0)
[23:16:11.789] Timer tick, asking Buildbucket for the build status
[23:16:11.960] Build 8884995947381804816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:16:11.960] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:15:0) after 1m0s
[23:17:12.148] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:15:0)
[23:17:12.148] Timer tick, asking Buildbucket for the build status
[23:17:12.279] Build 8884995947381804816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:17:12.279] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:16:0) after 1m0s
[23:18:12.336] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:16:0)
[23:18:12.336] Timer tick, asking Buildbucket for the build status
[23:18:12.440] Build 8884995947381804816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:18:12.440] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:17:0) after 1m0s
[23:19:12.581] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:17:0)
[23:19:12.581] Timer tick, asking Buildbucket for the build status
[23:19:12.698] Build 8884995947381804816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:19:12.698] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:18:0) after 1m0s
[23:20:12.839] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:18:0)
[23:20:12.839] Timer tick, asking Buildbucket for the build status
[23:20:13.168] Build 8884995947381804816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:20:13.168] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:19:0) after 1m0s
[23:21:13.192] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:19:0)
[23:21:13.192] Timer tick, asking Buildbucket for the build status
[23:21:13.310] Build 8884995947381804816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:21:13.310] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:20:0) after 1m0s
[23:22:13.369] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:20:0)
[23:22:13.369] Timer tick, asking Buildbucket for the build status
[23:22:13.536] Build 8884995947381804816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:22:13.536] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:21:0) after 1m0s
[23:23:13.611] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:21:0)
[23:23:13.611] Timer tick, asking Buildbucket for the build status
[23:23:13.824] Build 8884995947381804816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:23:13.824] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:22:0) after 1m0s
[23:24:13.829] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:22:0)
[23:24:13.829] Timer tick, asking Buildbucket for the build status
[23:24:14.093] Build 8884995947381804816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:24:14.093] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:23:0) after 1m0s
[23:25:14.134] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:23:0)
[23:25:14.134] Timer tick, asking Buildbucket for the build status
[23:25:14.325] Build 8884995947381804816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:25:14.325] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:24:0) after 1m0s
[23:26:14.343] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:24:0)
[23:26:14.343] Timer tick, asking Buildbucket for the build status
[23:26:14.495] Build 8884995947381804816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:26:14.495] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:25:0) after 1m0s
[23:27:14.528] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:25:0)
[23:27:14.528] Timer tick, asking Buildbucket for the build status
[23:27:14.701] Build 8884995947381804816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:27:14.701] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:26:0) after 1m0s
[23:28:14.755] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:26:0)
[23:28:14.755] Timer tick, asking Buildbucket for the build status
[23:28:14.900] Build 8884995947381804816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:28:14.900] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:27:0) after 1m0s
[23:29:14.956] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:27:0)
[23:29:14.956] Timer tick, asking Buildbucket for the build status
[23:29:15.146] Build 8884995947381804816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:29:15.146] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:28:0) after 1m0s
[23:30:15.239] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:28:0)
[23:30:15.239] Timer tick, asking Buildbucket for the build status
[23:30:15.414] Build 8884995947381804816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:30:15.414] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:29:0) after 1m0s
[23:31:15.462] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:29:0)
[23:31:15.462] Timer tick, asking Buildbucket for the build status
[23:31:15.746] Build 8884995947381804816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:31:15.746] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:30:0) after 1m0s
[23:32:16.565] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:30:0)
[23:32:16.565] Timer tick, asking Buildbucket for the build status
[23:32:16.808] Build 8884995947381804816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:32:16.808] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:31:0) after 1m0s
[23:33:16.829] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:31:0)
[23:33:16.829] Timer tick, asking Buildbucket for the build status
[23:33:16.968] Build 8884995947381804816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:33:16.968] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:32:0) after 1m0s
[23:34:17.045] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:32:0)
[23:34:17.045] Timer tick, asking Buildbucket for the build status
[23:34:17.207] Build 8884995947381804816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:34:17.207] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:33:0) after 1m0s
[23:35:17.249] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:33:0)
[23:35:17.249] Timer tick, asking Buildbucket for the build status
[23:35:17.758] Build 8884995947381804816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:35:17.758] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:34:0) after 1m0s
[23:36:17.792] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:34:0)
[23:36:17.792] Timer tick, asking Buildbucket for the build status
[23:36:18.286] Build 8884995947381804816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:36:18.286] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:35:0) after 1m0s
[23:37:18.358] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:35:0)
[23:37:18.358] Timer tick, asking Buildbucket for the build status
[23:37:18.429] Build 8884995947381804816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:37:18.429] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:36:0) after 1m0s
[23:38:18.462] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:36:0)
[23:38:18.462] Timer tick, asking Buildbucket for the build status
[23:38:18.698] Build 8884995947381804816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:38:18.698] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:37:0) after 1m0s
[23:39:18.718] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:37:0)
[23:39:18.718] Timer tick, asking Buildbucket for the build status
[23:39:18.829] Build 8884995947381804816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:39:18.829] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:38:0) after 1m0s
[23:40:18.959] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:38:0)
[23:40:18.959] Timer tick, asking Buildbucket for the build status
[23:40:19.104] Build 8884995947381804816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:40:19.104] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:39:0) after 1m0s
[23:41:19.251] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:39:0)
[23:41:19.251] Timer tick, asking Buildbucket for the build status
[23:41:19.469] Build 8884995947381804816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:41:19.469] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:40:0) after 1m0s
[23:42:19.520] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:40:0)
[23:42:19.520] Timer tick, asking Buildbucket for the build status
[23:42:19.759] Build 8884995947381804816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:42:19.759] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:41:0) after 1m0s
[23:43:19.869] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:41:0)
[23:43:19.869] Timer tick, asking Buildbucket for the build status
[23:43:20.100] Build 8884995947381804816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:43:20.100] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:42:0) after 1m0s
[23:44:20.147] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:42:0)
[23:44:20.150] Timer tick, asking Buildbucket for the build status
[23:44:20.331] Build 8884995947381804816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:44:20.331] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:43:0) after 1m0s
[23:45:20.551] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:43:0)
[23:45:20.551] Timer tick, asking Buildbucket for the build status
[23:45:20.709] Build 8884995947381804816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:45:20.709] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:44:0) after 1m0s
[23:46:20.774] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:44:0)
[23:46:20.774] Timer tick, asking Buildbucket for the build status
[23:46:20.905] Build 8884995947381804816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:46:20.905] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:45:0) after 1m0s
[23:47:21.010] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:45:0)
[23:47:21.010] Timer tick, asking Buildbucket for the build status
[23:47:21.387] Build 8884995947381804816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:47:21.387] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:46:0) after 1m0s
[23:48:21.448] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:46:0)
[23:48:21.448] Timer tick, asking Buildbucket for the build status
[23:48:21.593] Build 8884995947381804816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:48:21.593] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:47:0) after 1m0s
[23:49:21.702] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:47:0)
[23:49:21.702] Timer tick, asking Buildbucket for the build status
[23:49:22.440] Build 8884995947381804816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:49:22.440] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:48:0) after 1m0s
[23:50:22.489] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:48:0)
[23:50:22.489] Timer tick, asking Buildbucket for the build status
[23:50:22.646] Build 8884995947381804816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:50:22.646] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:49:0) after 1m0s
[23:51:22.698] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:49:0)
[23:51:22.698] Timer tick, asking Buildbucket for the build status
[23:51:22.904] Build 8884995947381804816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:51:22.904] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:50:0) after 1m0s
[23:52:22.931] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:50:0)
[23:52:22.931] Timer tick, asking Buildbucket for the build status
[23:52:23.052] Build 8884995947381804816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:52:23.052] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:51:0) after 1m0s
[23:53:23.115] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:51:0)
[23:53:23.115] Timer tick, asking Buildbucket for the build status
[23:53:23.578] Build 8884995947381804816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:53:23.578] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:52:0) after 1m0s
[23:54:23.582] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:52:0)
[23:54:23.582] Timer tick, asking Buildbucket for the build status
[23:54:23.706] Build 8884995947381804816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:54:23.706] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:53:0) after 1m0s
[23:55:23.785] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:53:0)
[23:55:23.785] Timer tick, asking Buildbucket for the build status
[23:55:23.907] Build 8884995947381804816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:55:23.907] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:54:0) after 1m0s
[23:56:23.924] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:54:0)
[23:56:23.924] Timer tick, asking Buildbucket for the build status
[23:56:24.016] Build 8884995947381804816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:56:24.016] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:55:0) after 1m0s
[23:57:24.045] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:55:0)
[23:57:24.045] Timer tick, asking Buildbucket for the build status
[23:57:24.133] Build 8884995947381804816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:57:24.133] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:56:0) after 1m0s
[23:58:24.245] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:56:0)
[23:58:24.245] Timer tick, asking Buildbucket for the build status
[23:58:24.377] Build 8884995947381804816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:58:24.377] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:57:0) after 1m0s
[23:59:24.497] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:57:0)
[23:59:24.497] Timer tick, asking Buildbucket for the build status
[23:59:24.637] Build 8884995947381804816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:59:24.637] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:58:0) after 1m0s
[00:00:24.704] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:58:0)
[00:00:24.704] Timer tick, asking Buildbucket for the build status
[00:00:24.834] Build 8884995947381804816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:00:24.834] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:59:0) after 1m0s
[00:01:24.865] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:59:0)
[00:01:24.865] Timer tick, asking Buildbucket for the build status
[00:01:24.989] Build 8884995947381804816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:01:24.989] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:60:0) after 1m0s
[00:02:25.172] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:60:0)
[00:02:25.172] Timer tick, asking Buildbucket for the build status
[00:02:25.301] Build 8884995947381804816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:02:25.301] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:61:0) after 1m0s
[00:03:25.357] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:61:0)
[00:03:25.357] Timer tick, asking Buildbucket for the build status
[00:03:25.506] Build 8884995947381804816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:03:25.506] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:62:0) after 1m0s
[00:04:25.661] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:62:0)
[00:04:25.663] Timer tick, asking Buildbucket for the build status
[00:04:25.796] Build 8884995947381804816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:04:25.796] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:63:0) after 1m0s
[00:05:25.961] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:63:0)
[00:05:25.961] Timer tick, asking Buildbucket for the build status
[00:05:26.139] Build 8884995947381804816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:05:26.139] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:64:0) after 1m0s
[00:06:26.294] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:64:0)
[00:06:26.294] Timer tick, asking Buildbucket for the build status
[00:06:26.480] Build 8884995947381804816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:06:26.480] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:65:0) after 1m0s
[00:06:53.036] Received PubSub notification, asking Buildbucket for the build status
[00:06:53.156] Build 8884995947381804816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:07:26.505] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:65:0)
[00:07:26.505] Timer tick, asking Buildbucket for the build status
[00:07:26.628] Build 8884995947381804816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:07:26.628] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:67:0) after 1m0s
[00:08:26.755] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:67:0)
[00:08:26.755] Timer tick, asking Buildbucket for the build status
[00:08:26.867] Build 8884995947381804816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:08:26.867] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:68:0) after 1m0s
[00:09:27.121] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:68:0)
[00:09:27.121] Timer tick, asking Buildbucket for the build status
[00:09:27.778] Build 8884995947381804816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:09:27.778] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:69:0) after 1m0s
[00:10:28.281] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:69:0)
[00:10:28.281] Timer tick, asking Buildbucket for the build status
[00:10:28.525] Build 8884995947381804816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:10:28.525] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:70:0) after 1m0s
[00:11:28.690] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:70:0)
[00:11:28.690] Timer tick, asking Buildbucket for the build status
[00:11:28.810] Build 8884995947381804816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:11:28.810] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:71:0) after 1m0s
[00:12:28.847] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:71:0)
[00:12:28.847] Timer tick, asking Buildbucket for the build status
[00:12:28.974] Build 8884995947381804816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:12:28.974] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:72:0) after 1m0s
[00:13:29.011] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:72:0)
[00:13:29.011] Timer tick, asking Buildbucket for the build status
[00:13:29.397] Build 8884995947381804816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:13:29.397] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:73:0) after 1m0s
[00:14:29.452] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:73:0)
[00:14:29.452] Timer tick, asking Buildbucket for the build status
[00:14:29.662] Build 8884995947381804816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:14:29.662] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:74:0) after 1m0s
[00:15:29.643] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:74:0)
[00:15:29.643] Timer tick, asking Buildbucket for the build status
[00:15:29.809] Build 8884995947381804816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:15:29.809] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:75:0) after 1m0s
[00:16:29.865] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:75:0)
[00:16:29.865] Timer tick, asking Buildbucket for the build status
[00:16:30.089] Build 8884995947381804816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:16:30.089] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:76:0) after 1m0s
[00:17:30.086] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:76:0)
[00:17:30.087] Timer tick, asking Buildbucket for the build status
[00:17:30.221] Build 8884995947381804816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:17:30.221] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:77:0) after 1m0s
[00:18:30.248] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:77:0)
[00:18:30.249] Timer tick, asking Buildbucket for the build status
[00:18:30.366] Build 8884995947381804816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:18:30.366] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:78:0) after 1m0s
[00:19:30.419] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:78:0)
[00:19:30.419] Timer tick, asking Buildbucket for the build status
[00:19:30.914] Build 8884995947381804816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:19:30.915] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:79:0) after 1m0s
[00:20:31.006] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:79:0)
[00:20:31.006] Timer tick, asking Buildbucket for the build status
[00:20:31.140] Build 8884995947381804816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:20:31.140] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:80:0) after 1m0s
[00:21:31.330] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:80:0)
[00:21:31.330] Timer tick, asking Buildbucket for the build status
[00:21:31.496] Build 8884995947381804816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:21:31.496] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:81:0) after 1m0s
[00:22:31.869] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:81:0)
[00:22:31.869] Timer tick, asking Buildbucket for the build status
[00:22:31.983] Build 8884995947381804816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:22:31.983] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:82:0) after 1m0s
[00:23:32.090] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:82:0)
[00:23:32.090] Timer tick, asking Buildbucket for the build status
[00:23:32.479] Build 8884995947381804816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:23:32.479] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:83:0) after 1m0s
[00:24:32.609] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:83:0)
[00:24:32.609] Timer tick, asking Buildbucket for the build status
[00:24:32.881] Build 8884995947381804816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:24:32.881] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:84:0) after 1m0s
[00:25:32.880] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:84:0)
[00:25:32.880] Timer tick, asking Buildbucket for the build status
[00:25:33.426] Build 8884995947381804816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:25:33.426] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:85:0) after 1m0s
[00:26:33.448] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:85:0)
[00:26:33.448] Timer tick, asking Buildbucket for the build status
[00:26:33.558] Build 8884995947381804816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:26:33.558] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:86:0) after 1m0s
[00:27:33.568] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:86:0)
[00:27:33.568] Timer tick, asking Buildbucket for the build status
[00:27:33.681] Build 8884995947381804816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:27:33.681] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:87:0) after 1m0s
[00:28:33.745] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:87:0)
[00:28:33.745] Timer tick, asking Buildbucket for the build status
[00:28:34.133] Build 8884995947381804816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:28:34.133] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:88:0) after 1m0s
[00:29:34.123] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:88:0)
[00:29:34.123] Timer tick, asking Buildbucket for the build status
[00:29:35.546] Build 8884995947381804816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:29:35.546] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:89:0) after 1m0s
[00:30:35.526] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:89:0)
[00:30:35.526] Timer tick, asking Buildbucket for the build status
[00:30:35.678] Build 8884995947381804816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:30:35.678] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:90:0) after 1m0s
[00:31:35.701] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:90:0)
[00:31:35.701] Timer tick, asking Buildbucket for the build status
[00:31:35.817] Build 8884995947381804816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:31:35.817] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:91:0) after 1m0s
[00:32:35.844] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:91:0)
[00:32:35.844] Timer tick, asking Buildbucket for the build status
[00:32:35.915] Build 8884995947381804816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:32:35.915] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:92:0) after 1m0s
[00:33:35.969] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:92:0)
[00:33:35.969] Timer tick, asking Buildbucket for the build status
[00:33:36.081] Build 8884995947381804816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:33:36.081] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:93:0) after 1m0s
[00:34:36.152] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:93:0)
[00:34:36.152] Timer tick, asking Buildbucket for the build status
[00:34:36.660] Build 8884995947381804816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:34:36.660] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:94:0) after 1m0s
[00:35:36.699] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:94:0)
[00:35:36.699] Timer tick, asking Buildbucket for the build status
[00:35:36.872] Build 8884995947381804816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:35:36.872] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:95:0) after 1m0s
[00:36:36.961] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:95:0)
[00:36:36.961] Timer tick, asking Buildbucket for the build status
[00:36:37.171] Build 8884995947381804816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:36:37.171] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:96:0) after 1m0s
[00:37:37.220] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:96:0)
[00:37:37.220] Timer tick, asking Buildbucket for the build status
[00:37:37.373] Build 8884995947381804816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:37:37.373] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:97:0) after 1m0s
[00:38:37.405] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:97:0)
[00:38:37.405] Timer tick, asking Buildbucket for the build status
[00:38:37.964] Build 8884995947381804816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:38:37.964] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:98:0) after 1m0s
[00:39:37.985] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:98:0)
[00:39:37.985] Timer tick, asking Buildbucket for the build status
[00:39:38.194] Build 8884995947381804816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:39:38.194] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:99:0) after 1m0s
[00:40:38.303] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:99:0)
[00:40:38.303] Timer tick, asking Buildbucket for the build status
[00:40:38.427] Build 8884995947381804816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:40:38.427] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:100:0) after 1m0s
[00:41:38.531] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:100:0)
[00:41:38.531] Timer tick, asking Buildbucket for the build status
[00:41:38.687] Build 8884995947381804816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:41:38.687] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:101:0) after 1m0s
[00:42:38.834] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:101:0)
[00:42:38.834] Timer tick, asking Buildbucket for the build status
[00:42:39.167] Build 8884995947381804816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:42:39.167] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:102:0) after 1m0s
[00:43:39.268] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:102:0)
[00:43:39.268] Timer tick, asking Buildbucket for the build status
[00:43:39.369] Build 8884995947381804816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:43:39.369] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:103:0) after 1m0s
[00:44:39.404] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:103:0)
[00:44:39.404] Timer tick, asking Buildbucket for the build status
[00:44:39.585] Build 8884995947381804816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:44:39.585] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:104:0) after 1m0s
[00:45:39.688] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:104:0)
[00:45:39.688] Timer tick, asking Buildbucket for the build status
[00:45:39.790] Build 8884995947381804816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:45:39.790] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:105:0) after 1m0s
[00:46:39.846] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:105:0)
[00:46:39.846] Timer tick, asking Buildbucket for the build status
[00:46:39.957] Build 8884995947381804816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:46:39.957] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:106:0) after 1m0s
[00:47:40.076] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:106:0)
[00:47:40.076] Timer tick, asking Buildbucket for the build status
[00:47:40.174] Build 8884995947381804816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:47:40.174] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:107:0) after 1m0s
[00:48:40.319] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:107:0)
[00:48:40.319] Timer tick, asking Buildbucket for the build status
[00:48:40.762] Build 8884995947381804816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:48:40.762] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:108:0) after 1m0s
[00:49:40.746] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:108:0)
[00:49:40.746] Timer tick, asking Buildbucket for the build status
[00:49:41.025] Build 8884995947381804816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:49:41.025] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:109:0) after 1m0s
[00:50:41.128] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:109:0)
[00:50:41.129] Timer tick, asking Buildbucket for the build status
[00:50:41.298] Build 8884995947381804816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:50:41.298] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:110:0) after 1m0s
[00:51:41.308] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:110:0)
[00:51:41.308] Timer tick, asking Buildbucket for the build status
[00:51:41.459] Build 8884995947381804816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:51:41.459] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:111:0) after 1m0s
[00:52:41.485] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:111:0)
[00:52:41.485] Timer tick, asking Buildbucket for the build status
[00:52:41.630] Build 8884995947381804816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:52:41.630] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:112:0) after 1m0s
[00:53:41.706] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:112:0)
[00:53:41.706] Timer tick, asking Buildbucket for the build status
[00:53:41.904] Build 8884995947381804816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:53:41.904] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:113:0) after 1m0s
[00:54:41.966] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:113:0)
[00:54:41.966] Timer tick, asking Buildbucket for the build status
[00:54:42.247] Build 8884995947381804816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:54:42.247] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:114:0) after 1m0s
[00:55:42.307] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:114:0)
[00:55:42.307] Timer tick, asking Buildbucket for the build status
[00:55:42.374] Build 8884995947381804816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:55:42.374] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:115:0) after 1m0s
[00:56:42.398] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:115:0)
[00:56:42.398] Timer tick, asking Buildbucket for the build status
[00:56:42.533] Build 8884995947381804816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:56:42.533] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:116:0) after 1m0s
[00:57:42.594] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:116:0)
[00:57:42.594] Timer tick, asking Buildbucket for the build status
[00:57:42.697] Build 8884995947381804816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:57:42.697] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:117:0) after 1m0s
[00:58:42.873] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:117:0)
[00:58:42.873] Timer tick, asking Buildbucket for the build status
[00:58:43.018] Build 8884995947381804816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:58:43.018] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:118:0) after 1m0s
[00:59:43.067] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:118:0)
[00:59:43.067] Timer tick, asking Buildbucket for the build status
[00:59:43.247] Build 8884995947381804816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:59:43.247] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:119:0) after 1m0s
[01:00:43.287] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:119:0)
[01:00:43.287] Timer tick, asking Buildbucket for the build status
[01:00:43.369] Build 8884995947381804816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:00:43.369] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:120:0) after 1m0s
[01:01:43.528] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:120:0)
[01:01:43.528] Timer tick, asking Buildbucket for the build status
[01:01:43.659] Build 8884995947381804816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:01:43.659] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:121:0) after 1m0s
[01:02:43.820] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:121:0)
[01:02:43.820] Timer tick, asking Buildbucket for the build status
[01:02:43.969] Build 8884995947381804816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:02:43.969] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:122:0) after 1m0s
[01:03:44.099] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:122:0)
[01:03:44.099] Timer tick, asking Buildbucket for the build status
[01:03:44.392] Build 8884995947381804816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:03:44.392] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:123:0) after 1m0s
[01:04:44.599] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:123:0)
[01:04:44.599] Timer tick, asking Buildbucket for the build status
[01:04:44.733] Build 8884995947381804816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:04:44.733] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:124:0) after 1m0s
[01:05:44.776] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:124:0)
[01:05:44.776] Timer tick, asking Buildbucket for the build status
[01:05:44.829] Build 8884995947381804816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:05:44.829] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:125:0) after 1m0s
[01:06:45.012] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:125:0)
[01:06:45.012] Timer tick, asking Buildbucket for the build status
[01:06:45.117] Build 8884995947381804816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:06:45.118] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:126:0) after 1m0s
[01:07:45.185] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:126:0)
[01:07:45.185] Timer tick, asking Buildbucket for the build status
[01:07:45.327] Build 8884995947381804816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:07:45.327] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:127:0) after 1m0s
[01:08:45.382] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:127:0)
[01:08:45.382] Timer tick, asking Buildbucket for the build status
[01:08:45.631] Build 8884995947381804816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:08:45.631] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:128:0) after 1m0s
[01:09:45.656] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:128:0)
[01:09:45.656] Timer tick, asking Buildbucket for the build status
[01:09:46.945] Build 8884995947381804816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:09:46.945] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:129:0) after 1m0s
[01:10:47.092] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:129:0)
[01:10:47.092] Timer tick, asking Buildbucket for the build status
[01:10:47.190] Build 8884995947381804816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:10:47.190] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:130:0) after 1m0s
[01:11:47.256] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:130:0)
[01:11:47.256] Timer tick, asking Buildbucket for the build status
[01:11:47.418] Build 8884995947381804816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:11:47.418] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:131:0) after 1m0s
[01:12:47.441] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:131:0)
[01:12:47.441] Timer tick, asking Buildbucket for the build status
[01:12:47.832] Build 8884995947381804816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:12:47.832] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:132:0) after 1m0s
[01:13:47.885] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:132:0)
[01:13:47.885] Timer tick, asking Buildbucket for the build status
[01:13:48.128] Build 8884995947381804816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:13:48.128] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:133:0) after 1m0s
[01:14:48.189] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:133:0)
[01:14:48.189] Timer tick, asking Buildbucket for the build status
[01:14:50.678] Build 8884995947381804816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:14:50.678] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:134:0) after 1m0s
[01:15:51.063] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:134:0)
[01:15:51.063] Timer tick, asking Buildbucket for the build status
[01:15:51.184] Build 8884995947381804816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:15:51.184] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:135:0) after 1m0s
[01:16:51.348] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:135:0)
[01:16:51.348] Timer tick, asking Buildbucket for the build status
[01:16:51.505] Build 8884995947381804816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:16:51.505] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:136:0) after 1m0s
[01:17:51.720] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:136:0)
[01:17:51.720] Timer tick, asking Buildbucket for the build status
[01:17:51.872] Build 8884995947381804816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:17:51.872] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050426009415472112:137:0) after 1m0s
[01:18:25.790] Received PubSub notification, asking Buildbucket for the build status
[01:18:25.909] Build 8884995947381804816: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[01:18:25.909] Buildbucket build:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary_preference": "PROD",
"completed_ts": "1585012704088184",
"created_by": "project:emscripten-releases",
"created_ts": "1585004585816331",
"id": "8884995947381804816",
"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\": \"ae9ace10d05b2365f8369405a92427abd845d0e4\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@ae9ace10d05b2365f8369405a92427abd845d0e4\", \"title\": \"ae9ace10d05b2365f8369405a92427abd845d0e4\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/ae9ace10d05b2365f8369405a92427abd845d0e4\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"ae9ace10d05b2365f8369405a92427abd845d0e4\"}}",
"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\": \"ae9ace10d05b2365f8369405a92427abd845d0e4\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@ae9ace10d05b2365f8369405a92427abd845d0e4\", \"title\": \"ae9ace10d05b2365f8369405a92427abd845d0e4\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/ae9ace10d05b2365f8369405a92427abd845d0e4\"}]}, \"bot_id\": \"luci-emscripten-releases-ci-win10-0-41vw\", \"branch\": \"refs/heads/master\", \"buildername\": \"win\", \"got_revision\": \"ae9ace10d05b2365f8369405a92427abd845d0e4\", \"path_config\": \"generic\", \"recipe\": \"emscripten_releases\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"ae9ace10d05b2365f8369405a92427abd845d0e4\"}, \"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-41vw\"], \"image\": [\"chrome-win10-1703-19112100-3085293b610\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"n1-standard-8\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.emscripten-releases.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"4874-3598771\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-b\"]}}}",
"service_account": "emscripten-releases-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1585008412236013",
"status": "COMPLETED",
"status_changed_ts": "1585012704855730",
"tags": [
"builder:win",
"buildset:commit/git/ae9ace10d05b2365f8369405a92427abd845d0e4",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/ae9ace10d05b2365f8369405a92427abd845d0e4",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9050426009415472112",
"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/8884995947381804816/+/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:4b227463d2752c10",
"user_agent:luci-scheduler"
],
"updated_ts": "1585012705165898",
"url": "https://ci.chromium.org/b/8884995947381804816",
"utcnow_ts": "1585012705900905"
}
}
[01:18:25.909] Invocation finished in 2h15m21.415155696s with status SUCCEEDED