[21:22:31.447] New invocation is queued and will start shortly
[21:22:32.605] Starting the invocation (attempt 1)
[21:22:32.698] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[21:22:32.698] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[21:22:32.699] Buildbucket request:
{
"bucket": "luci.emscripten-releases.ci",
"client_operation_id": "9017998621564751056",
"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\":\"45462050397ec9b29a501e3fedbd2f2bc987672a\"},\"id\":\"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@45462050397ec9b29a501e3fedbd2f2bc987672a\",\"title\":\"45462050397ec9b29a501e3fedbd2f2bc987672a\",\"url\":\"https://chromium.googlesource.com/emscripten-releases/+/45462050397ec9b29a501e3fedbd2f2bc987672a\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/emscripten-releases\",\"revision\":\"45462050397ec9b29a501e3fedbd2f2bc987672a\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win",
"scheduler_invocation_id:9017998621564751056",
"scheduler_job_id:emscripten-releases/win",
"user_agent:luci-scheduler",
"buildset:commit/git/45462050397ec9b29a501e3fedbd2f2bc987672a",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/45462050397ec9b29a501e3fedbd2f2bc987672a",
"gitiles_ref:refs/heads/master"
]
}
[21:22:33.220] Buildbucket response:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary": true,
"canary_preference": "CANARY",
"created_by": "project:emscripten-releases",
"created_ts": "1615929752869520",
"id": "8852568559413809648",
"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\": \"45462050397ec9b29a501e3fedbd2f2bc987672a\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@45462050397ec9b29a501e3fedbd2f2bc987672a\", \"title\": \"45462050397ec9b29a501e3fedbd2f2bc987672a\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/45462050397ec9b29a501e3fedbd2f2bc987672a\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"45462050397ec9b29a501e3fedbd2f2bc987672a\"}}",
"project": "emscripten-releases",
"result_details_json": "{\"properties\": {}}",
"service_account": "emscripten-releases-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1615929753099266",
"tags": [
"builder:win",
"buildset:commit/git/45462050397ec9b29a501e3fedbd2f2bc987672a",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/45462050397ec9b29a501e3fedbd2f2bc987672a",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9017998621564751056",
"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/8852568559413809648/+/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": "1615929753099396",
"url": "https://ci.chromium.org/b/8852568559413809648",
"utcnow_ts": "1615929753212987"
}
}
[21:22:33.220] Task URL: https://ci.chromium.org/b/8852568559413809648
[21:22:33.220] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:2:0) after 1m0s
[21:23:33.257] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:2:0)
[21:23:33.257] Timer tick, asking Buildbucket for the build status
[21:23:33.336] Build 8852568559413809648: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:23:33.336] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:3:0) after 1m0s
[21:24:33.378] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:3:0)
[21:24:33.378] Timer tick, asking Buildbucket for the build status
[21:24:33.540] Build 8852568559413809648: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:24:33.540] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:4:0) after 1m0s
[21:25:33.549] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:4:0)
[21:25:33.549] Timer tick, asking Buildbucket for the build status
[21:25:33.722] Build 8852568559413809648: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:25:33.722] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:5:0) after 1m0s
[21:26:33.777] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:5:0)
[21:26:33.777] Timer tick, asking Buildbucket for the build status
[21:26:33.927] Build 8852568559413809648: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:26:33.927] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:6:0) after 1m0s
[21:27:33.895] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:6:0)
[21:27:33.895] Timer tick, asking Buildbucket for the build status
[21:27:34.168] Build 8852568559413809648: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:27:34.168] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:7:0) after 1m0s
[21:28:34.272] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:7:0)
[21:28:34.272] Timer tick, asking Buildbucket for the build status
[21:28:34.365] Build 8852568559413809648: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:28:34.365] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:8:0) after 1m0s
[21:29:34.447] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:8:0)
[21:29:34.447] Timer tick, asking Buildbucket for the build status
[21:29:34.533] Build 8852568559413809648: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:29:34.533] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:9:0) after 1m0s
[21:30:34.506] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:9:0)
[21:30:34.506] Timer tick, asking Buildbucket for the build status
[21:30:34.715] Build 8852568559413809648: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:30:34.715] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:10:0) after 1m0s
[21:31:34.727] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:10:0)
[21:31:34.727] Timer tick, asking Buildbucket for the build status
[21:31:34.847] Build 8852568559413809648: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:31:34.847] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:11:0) after 1m0s
[21:32:34.967] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:11:0)
[21:32:34.967] Timer tick, asking Buildbucket for the build status
[21:32:35.022] Build 8852568559413809648: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:32:35.022] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:12:0) after 1m0s
[21:33:35.115] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:12:0)
[21:33:35.115] Timer tick, asking Buildbucket for the build status
[21:33:35.395] Build 8852568559413809648: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:33:35.395] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:13:0) after 1m0s
[21:34:35.489] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:13:0)
[21:34:35.489] Timer tick, asking Buildbucket for the build status
[21:34:35.854] Build 8852568559413809648: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:34:35.854] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:14:0) after 1m0s
[21:35:35.900] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:14:0)
[21:35:35.900] Timer tick, asking Buildbucket for the build status
[21:35:35.992] Build 8852568559413809648: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:35:35.992] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:15:0) after 1m0s
[21:36:36.030] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:15:0)
[21:36:36.030] Timer tick, asking Buildbucket for the build status
[21:36:36.273] Build 8852568559413809648: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:36:36.273] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:16:0) after 1m0s
[21:37:36.282] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:16:0)
[21:37:36.282] Timer tick, asking Buildbucket for the build status
[21:37:36.358] Build 8852568559413809648: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:37:36.358] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:17:0) after 1m0s
[21:38:36.422] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:17:0)
[21:38:36.422] Timer tick, asking Buildbucket for the build status
[21:38:36.484] Build 8852568559413809648: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:38:36.484] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:18:0) after 1m0s
[21:39:36.500] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:18:0)
[21:39:36.500] Timer tick, asking Buildbucket for the build status
[21:39:36.783] Build 8852568559413809648: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:39:36.783] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:19:0) after 1m0s
[21:40:36.819] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:19:0)
[21:40:36.819] Timer tick, asking Buildbucket for the build status
[21:40:36.954] Build 8852568559413809648: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:40:36.954] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:20:0) after 1m0s
[21:41:36.961] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:20:0)
[21:41:36.961] Timer tick, asking Buildbucket for the build status
[21:41:37.047] Build 8852568559413809648: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:41:37.047] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:21:0) after 1m0s
[21:42:37.050] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:21:0)
[21:42:37.050] Timer tick, asking Buildbucket for the build status
[21:42:37.134] Build 8852568559413809648: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:42:37.134] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:22:0) after 1m0s
[21:43:37.180] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:22:0)
[21:43:37.180] Timer tick, asking Buildbucket for the build status
[21:43:37.361] Build 8852568559413809648: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:43:37.361] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:23:0) after 1m0s
[21:44:37.461] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:23:0)
[21:44:37.461] Timer tick, asking Buildbucket for the build status
[21:44:37.537] Build 8852568559413809648: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:44:37.537] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:24:0) after 1m0s
[21:45:37.557] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:24:0)
[21:45:37.557] Timer tick, asking Buildbucket for the build status
[21:45:37.801] Build 8852568559413809648: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:45:37.801] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:25:0) after 1m0s
[21:46:37.830] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:25:0)
[21:46:37.830] Timer tick, asking Buildbucket for the build status
[21:46:37.915] Build 8852568559413809648: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:46:37.915] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:26:0) after 1m0s
[21:47:37.932] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:26:0)
[21:47:37.932] Timer tick, asking Buildbucket for the build status
[21:47:38.018] Build 8852568559413809648: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:47:38.018] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:27:0) after 1m0s
[21:48:38.024] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:27:0)
[21:48:38.024] Timer tick, asking Buildbucket for the build status
[21:48:38.086] Build 8852568559413809648: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:48:38.087] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:28:0) after 1m0s
[21:49:38.124] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:28:0)
[21:49:38.125] Timer tick, asking Buildbucket for the build status
[21:49:38.181] Build 8852568559413809648: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:49:38.181] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:29:0) after 1m0s
[21:50:38.205] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:29:0)
[21:50:38.205] Timer tick, asking Buildbucket for the build status
[21:50:38.315] Build 8852568559413809648: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:50:38.315] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:30:0) after 1m0s
[21:51:38.322] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:30:0)
[21:51:38.322] Timer tick, asking Buildbucket for the build status
[21:51:38.380] Build 8852568559413809648: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:51:38.380] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:31:0) after 1m0s
[21:52:38.398] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:31:0)
[21:52:38.398] Timer tick, asking Buildbucket for the build status
[21:52:38.498] Build 8852568559413809648: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:52:38.498] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:32:0) after 1m0s
[21:53:38.540] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:32:0)
[21:53:38.540] Timer tick, asking Buildbucket for the build status
[21:53:38.661] Build 8852568559413809648: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:53:38.661] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:33:0) after 1m0s
[21:54:38.749] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:33:0)
[21:54:38.749] Timer tick, asking Buildbucket for the build status
[21:54:38.812] Build 8852568559413809648: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:54:38.812] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:34:0) after 1m0s
[21:55:39.042] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:34:0)
[21:55:39.042] Timer tick, asking Buildbucket for the build status
[21:55:39.214] Build 8852568559413809648: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:55:39.214] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:35:0) after 1m0s
[21:56:39.292] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:35:0)
[21:56:39.292] Timer tick, asking Buildbucket for the build status
[21:56:39.356] Build 8852568559413809648: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:56:39.356] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:36:0) after 1m0s
[21:57:39.491] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:36:0)
[21:57:39.492] Timer tick, asking Buildbucket for the build status
[21:57:39.687] Build 8852568559413809648: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:57:39.687] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:37:0) after 1m0s
[21:58:40.394] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:37:0)
[21:58:40.394] Timer tick, asking Buildbucket for the build status
[21:58:40.460] Build 8852568559413809648: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:58:40.460] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:38:0) after 1m0s
[21:59:40.572] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:38:0)
[21:59:40.572] Timer tick, asking Buildbucket for the build status
[21:59:40.635] Build 8852568559413809648: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:59:40.635] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:39:0) after 1m0s
[22:00:40.716] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:39:0)
[22:00:40.716] Timer tick, asking Buildbucket for the build status
[22:00:40.796] Build 8852568559413809648: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:00:40.796] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:40:0) after 1m0s
[22:01:40.902] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:40:0)
[22:01:40.903] Timer tick, asking Buildbucket for the build status
[22:01:41.011] Build 8852568559413809648: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:01:41.011] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:41:0) after 1m0s
[22:02:41.164] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:41:0)
[22:02:41.164] Timer tick, asking Buildbucket for the build status
[22:02:41.258] Build 8852568559413809648: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:02:41.258] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:42:0) after 1m0s
[22:03:41.348] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:42:0)
[22:03:41.348] Timer tick, asking Buildbucket for the build status
[22:03:41.416] Build 8852568559413809648: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:03:41.416] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:43:0) after 1m0s
[22:04:41.488] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:43:0)
[22:04:41.488] Timer tick, asking Buildbucket for the build status
[22:04:41.572] Build 8852568559413809648: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:04:41.572] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:44:0) after 1m0s
[22:05:41.843] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:44:0)
[22:05:41.843] Timer tick, asking Buildbucket for the build status
[22:05:41.902] Build 8852568559413809648: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:05:41.902] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:45:0) after 1m0s
[22:06:42.078] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:45:0)
[22:06:42.078] Timer tick, asking Buildbucket for the build status
[22:06:42.189] Build 8852568559413809648: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:06:42.189] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:46:0) after 1m0s
[22:07:42.308] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:46:0)
[22:07:42.308] Timer tick, asking Buildbucket for the build status
[22:07:42.391] Build 8852568559413809648: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:07:42.391] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:47:0) after 1m0s
[22:08:42.512] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:47:0)
[22:08:42.512] Timer tick, asking Buildbucket for the build status
[22:08:42.589] Build 8852568559413809648: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:08:42.589] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:48:0) after 1m0s
[22:09:42.659] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:48:0)
[22:09:42.659] Timer tick, asking Buildbucket for the build status
[22:09:42.756] Build 8852568559413809648: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:09:42.756] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:49:0) after 1m0s
[22:10:42.916] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:49:0)
[22:10:42.916] Timer tick, asking Buildbucket for the build status
[22:10:43.111] Build 8852568559413809648: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:10:43.111] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:50:0) after 1m0s
[22:11:43.372] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:50:0)
[22:11:43.372] Timer tick, asking Buildbucket for the build status
[22:11:43.441] Build 8852568559413809648: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:11:43.441] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:51:0) after 1m0s
[22:12:43.574] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:51:0)
[22:12:43.574] Timer tick, asking Buildbucket for the build status
[22:12:43.707] Build 8852568559413809648: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:12:43.707] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:52:0) after 1m0s
[22:13:43.768] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:52:0)
[22:13:43.768] Timer tick, asking Buildbucket for the build status
[22:13:43.832] Build 8852568559413809648: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:13:43.832] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:53:0) after 1m0s
[22:14:43.951] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:53:0)
[22:14:43.951] Timer tick, asking Buildbucket for the build status
[22:14:44.257] Build 8852568559413809648: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:14:44.257] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:54:0) after 1m0s
[22:15:44.373] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:54:0)
[22:15:44.373] Timer tick, asking Buildbucket for the build status
[22:15:44.657] Build 8852568559413809648: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:15:44.657] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:55:0) after 1m0s
[22:16:44.650] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:55:0)
[22:16:44.650] Timer tick, asking Buildbucket for the build status
[22:16:44.714] Build 8852568559413809648: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:16:44.714] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:56:0) after 1m0s
[22:17:44.776] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:56:0)
[22:17:44.776] Timer tick, asking Buildbucket for the build status
[22:17:45.123] Build 8852568559413809648: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:17:45.123] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:57:0) after 1m0s
[22:18:45.216] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:57:0)
[22:18:45.217] Timer tick, asking Buildbucket for the build status
[22:18:45.381] Build 8852568559413809648: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:18:45.381] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:58:0) after 1m0s
[22:19:45.441] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:58:0)
[22:19:45.441] Timer tick, asking Buildbucket for the build status
[22:19:45.501] Build 8852568559413809648: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:19:45.502] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:59:0) after 1m0s
[22:20:45.569] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:59:0)
[22:20:45.569] Timer tick, asking Buildbucket for the build status
[22:20:45.681] Build 8852568559413809648: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:20:45.681] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:60:0) after 1m0s
[22:21:45.675] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:60:0)
[22:21:45.675] Timer tick, asking Buildbucket for the build status
[22:21:45.737] Build 8852568559413809648: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:21:45.737] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:61:0) after 1m0s
[22:22:45.771] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:61:0)
[22:22:45.771] Timer tick, asking Buildbucket for the build status
[22:22:45.956] Build 8852568559413809648: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:22:45.956] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:62:0) after 1m0s
[22:23:46.046] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:62:0)
[22:23:46.046] Timer tick, asking Buildbucket for the build status
[22:23:46.106] Build 8852568559413809648: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:23:46.106] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:63:0) after 1m0s
[22:24:46.125] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:63:0)
[22:24:46.125] Timer tick, asking Buildbucket for the build status
[22:24:46.275] Build 8852568559413809648: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:24:46.275] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:64:0) after 1m0s
[22:25:46.362] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:64:0)
[22:25:46.362] Timer tick, asking Buildbucket for the build status
[22:25:46.448] Build 8852568559413809648: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:25:46.448] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:65:0) after 1m0s
[22:26:35.470] Received PubSub notification, asking Buildbucket for the build status
[22:26:35.717] Build 8852568559413809648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:26:46.566] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:65:0)
[22:26:46.566] Timer tick, asking Buildbucket for the build status
[22:26:46.779] Build 8852568559413809648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:26:46.779] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:67:0) after 1m0s
[22:27:46.829] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:67:0)
[22:27:46.829] Timer tick, asking Buildbucket for the build status
[22:27:46.902] Build 8852568559413809648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:27:46.902] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:68:0) after 1m0s
[22:28:46.923] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:68:0)
[22:28:46.923] Timer tick, asking Buildbucket for the build status
[22:28:47.163] Build 8852568559413809648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:28:47.163] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:69:0) after 1m0s
[22:29:47.242] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:69:0)
[22:29:47.242] Timer tick, asking Buildbucket for the build status
[22:29:47.376] Build 8852568559413809648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:29:47.376] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:70:0) after 1m0s
[22:30:47.418] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:70:0)
[22:30:47.418] Timer tick, asking Buildbucket for the build status
[22:30:47.488] Build 8852568559413809648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:30:47.488] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:71:0) after 1m0s
[22:31:47.521] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:71:0)
[22:31:47.521] Timer tick, asking Buildbucket for the build status
[22:31:47.752] Build 8852568559413809648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:31:47.752] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:72:0) after 1m0s
[22:32:47.797] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:72:0)
[22:32:47.797] Timer tick, asking Buildbucket for the build status
[22:32:48.123] Build 8852568559413809648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:32:48.123] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:73:0) after 1m0s
[22:33:48.142] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:73:0)
[22:33:48.142] Timer tick, asking Buildbucket for the build status
[22:33:48.335] Build 8852568559413809648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:33:48.335] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:74:0) after 1m0s
[22:34:48.396] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:74:0)
[22:34:48.396] Timer tick, asking Buildbucket for the build status
[22:34:48.639] Build 8852568559413809648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:34:48.639] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:75:0) after 1m0s
[22:35:48.624] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:75:0)
[22:35:48.624] Timer tick, asking Buildbucket for the build status
[22:35:48.710] Build 8852568559413809648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:35:48.711] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:76:0) after 1m0s
[22:36:48.775] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:76:0)
[22:36:48.775] Timer tick, asking Buildbucket for the build status
[22:36:48.964] Build 8852568559413809648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:36:48.964] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:77:0) after 1m0s
[22:37:49.066] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:77:0)
[22:37:49.066] Timer tick, asking Buildbucket for the build status
[22:37:49.433] Build 8852568559413809648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:37:49.433] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:78:0) after 1m0s
[22:38:49.504] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:78:0)
[22:38:49.504] Timer tick, asking Buildbucket for the build status
[22:38:49.580] Build 8852568559413809648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:38:49.580] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:79:0) after 1m0s
[22:39:50.004] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:79:0)
[22:39:50.004] Timer tick, asking Buildbucket for the build status
[22:39:50.078] Build 8852568559413809648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:39:50.078] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:80:0) after 1m0s
[22:40:50.260] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:80:0)
[22:40:50.260] Timer tick, asking Buildbucket for the build status
[22:40:50.331] Build 8852568559413809648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:40:50.331] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:81:0) after 1m0s
[22:41:50.340] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:81:0)
[22:41:50.340] Timer tick, asking Buildbucket for the build status
[22:41:50.427] Build 8852568559413809648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:41:50.427] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:82:0) after 1m0s
[22:42:50.826] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:82:0)
[22:42:50.826] Timer tick, asking Buildbucket for the build status
[22:42:50.900] Build 8852568559413809648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:42:50.900] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:83:0) after 1m0s
[22:43:51.004] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:83:0)
[22:43:51.004] Timer tick, asking Buildbucket for the build status
[22:43:51.071] Build 8852568559413809648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:43:51.072] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:84:0) after 1m0s
[22:44:51.212] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:84:0)
[22:44:51.212] Timer tick, asking Buildbucket for the build status
[22:44:51.338] Build 8852568559413809648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:44:51.338] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:85:0) after 1m0s
[22:45:51.384] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:85:0)
[22:45:51.385] Timer tick, asking Buildbucket for the build status
[22:45:51.441] Build 8852568559413809648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:45:51.441] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:86:0) after 1m0s
[22:46:51.431] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:86:0)
[22:46:51.431] Timer tick, asking Buildbucket for the build status
[22:46:51.510] Build 8852568559413809648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:46:51.510] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:87:0) after 1m0s
[22:47:51.528] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:87:0)
[22:47:51.528] Timer tick, asking Buildbucket for the build status
[22:47:51.617] Build 8852568559413809648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:47:51.617] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:88:0) after 1m0s
[22:48:51.660] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:88:0)
[22:48:51.660] Timer tick, asking Buildbucket for the build status
[22:48:51.895] Build 8852568559413809648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:48:51.895] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:89:0) after 1m0s
[22:49:51.904] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:89:0)
[22:49:51.904] Timer tick, asking Buildbucket for the build status
[22:49:51.967] Build 8852568559413809648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:49:51.967] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:90:0) after 1m0s
[22:50:51.990] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:90:0)
[22:50:51.990] Timer tick, asking Buildbucket for the build status
[22:50:52.102] Build 8852568559413809648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:50:52.102] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:91:0) after 1m0s
[22:51:52.161] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:91:0)
[22:51:52.161] Timer tick, asking Buildbucket for the build status
[22:51:52.232] Build 8852568559413809648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:51:52.233] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:92:0) after 1m0s
[22:52:52.231] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:92:0)
[22:52:52.231] Timer tick, asking Buildbucket for the build status
[22:52:52.602] Build 8852568559413809648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:52:52.602] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:93:0) after 1m0s
[22:53:52.627] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:93:0)
[22:53:52.627] Timer tick, asking Buildbucket for the build status
[22:53:52.695] Build 8852568559413809648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:53:52.695] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:94:0) after 1m0s
[22:54:52.730] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:94:0)
[22:54:52.730] Timer tick, asking Buildbucket for the build status
[22:54:53.037] Build 8852568559413809648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:54:53.037] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:95:0) after 1m0s
[22:55:53.090] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:95:0)
[22:55:53.090] Timer tick, asking Buildbucket for the build status
[22:55:53.169] Build 8852568559413809648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:55:53.169] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:96:0) after 1m0s
[22:56:53.212] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:96:0)
[22:56:53.212] Timer tick, asking Buildbucket for the build status
[22:56:53.274] Build 8852568559413809648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:56:53.274] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:97:0) after 1m0s
[22:57:53.305] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:97:0)
[22:57:53.305] Timer tick, asking Buildbucket for the build status
[22:57:53.433] Build 8852568559413809648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:57:53.433] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:98:0) after 1m0s
[22:58:53.483] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:98:0)
[22:58:53.483] Timer tick, asking Buildbucket for the build status
[22:58:53.550] Build 8852568559413809648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:58:53.550] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:99:0) after 1m0s
[22:59:53.677] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:99:0)
[22:59:53.677] Timer tick, asking Buildbucket for the build status
[22:59:53.772] Build 8852568559413809648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:59:53.772] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:100:0) after 1m0s
[23:00:53.833] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:100:0)
[23:00:53.833] Timer tick, asking Buildbucket for the build status
[23:00:55.109] Build 8852568559413809648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:00:55.109] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:101:0) after 1m0s
[23:01:55.176] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:101:0)
[23:01:55.176] Timer tick, asking Buildbucket for the build status
[23:01:55.305] Build 8852568559413809648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:01:55.305] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:102:0) after 1m0s
[23:02:55.565] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:102:0)
[23:02:55.565] Timer tick, asking Buildbucket for the build status
[23:02:55.641] Build 8852568559413809648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:02:55.641] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:103:0) after 1m0s
[23:03:55.641] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:103:0)
[23:03:55.641] Timer tick, asking Buildbucket for the build status
[23:03:55.721] Build 8852568559413809648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:03:55.721] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:104:0) after 1m0s
[23:04:55.768] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:104:0)
[23:04:55.768] Timer tick, asking Buildbucket for the build status
[23:04:55.864] Build 8852568559413809648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:04:55.864] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:105:0) after 1m0s
[23:05:55.985] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:105:0)
[23:05:55.985] Timer tick, asking Buildbucket for the build status
[23:05:56.063] Build 8852568559413809648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:05:56.064] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:106:0) after 1m0s
[23:06:56.134] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:106:0)
[23:06:56.134] Timer tick, asking Buildbucket for the build status
[23:06:56.267] Build 8852568559413809648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:06:56.267] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:107:0) after 1m0s
[23:07:56.336] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:107:0)
[23:07:56.336] Timer tick, asking Buildbucket for the build status
[23:07:56.706] Build 8852568559413809648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:07:56.706] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:108:0) after 1m0s
[23:08:56.783] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:108:0)
[23:08:56.783] Timer tick, asking Buildbucket for the build status
[23:08:56.874] Build 8852568559413809648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:08:56.874] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:109:0) after 1m0s
[23:09:57.044] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:109:0)
[23:09:57.044] Timer tick, asking Buildbucket for the build status
[23:09:57.134] Build 8852568559413809648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:09:57.134] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:110:0) after 1m0s
[23:10:57.282] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:110:0)
[23:10:57.282] Timer tick, asking Buildbucket for the build status
[23:10:57.474] Build 8852568559413809648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:10:57.474] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:111:0) after 1m0s
[23:11:57.610] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:111:0)
[23:11:57.610] Timer tick, asking Buildbucket for the build status
[23:11:57.816] Build 8852568559413809648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:11:57.816] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:112:0) after 1m0s
[23:12:57.835] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:112:0)
[23:12:57.835] Timer tick, asking Buildbucket for the build status
[23:12:57.922] Build 8852568559413809648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:12:57.922] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:113:0) after 1m0s
[23:13:57.969] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:113:0)
[23:13:57.969] Timer tick, asking Buildbucket for the build status
[23:13:58.184] Build 8852568559413809648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:13:58.184] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:114:0) after 1m0s
[23:14:58.211] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:114:0)
[23:14:58.211] Timer tick, asking Buildbucket for the build status
[23:14:58.307] Build 8852568559413809648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:14:58.307] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:115:0) after 1m0s
[23:15:58.380] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:115:0)
[23:15:58.380] Timer tick, asking Buildbucket for the build status
[23:15:58.454] Build 8852568559413809648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:15:58.454] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:116:0) after 1m0s
[23:16:58.499] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:116:0)
[23:16:58.499] Timer tick, asking Buildbucket for the build status
[23:16:58.586] Build 8852568559413809648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:16:58.586] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9017998621564751056:117:0) after 1m0s
[23:17:53.678] Received PubSub notification, asking Buildbucket for the build status
[23:17:53.747] Build 8852568559413809648: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[23:17:53.747] Buildbucket build:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary": true,
"canary_preference": "CANARY",
"completed_ts": "1615936673174958",
"created_by": "project:emscripten-releases",
"created_ts": "1615929752869520",
"id": "8852568559413809648",
"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\": \"45462050397ec9b29a501e3fedbd2f2bc987672a\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@45462050397ec9b29a501e3fedbd2f2bc987672a\", \"title\": \"45462050397ec9b29a501e3fedbd2f2bc987672a\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/45462050397ec9b29a501e3fedbd2f2bc987672a\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"45462050397ec9b29a501e3fedbd2f2bc987672a\"}}",
"project": "emscripten-releases",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/goma\": {\"enable_ats\": true, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\", \"use_luci_auth\": true}, \"$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\": \"45462050397ec9b29a501e3fedbd2f2bc987672a\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@45462050397ec9b29a501e3fedbd2f2bc987672a\", \"title\": \"45462050397ec9b29a501e3fedbd2f2bc987672a\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/45462050397ec9b29a501e3fedbd2f2bc987672a\"}]}, \"bot_id\": \"luci-emscripten-releases-ci-win10-0-ofap\", \"branch\": \"refs/heads/master\", \"buildername\": \"win\", \"got_revision\": \"45462050397ec9b29a501e3fedbd2f2bc987672a\", \"path_config\": \"generic\", \"recipe\": \"emscripten_releases\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"45462050397ec9b29a501e3fedbd2f2bc987672a\"}, \"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-ofap\"], \"image\": [\"chrome-win10-1703-20111600-a937a23cefb\"], \"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\": [\"5754-8c4f760\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-b\"]}}}",
"service_account": "emscripten-releases-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1615933594762326",
"status": "COMPLETED",
"status_changed_ts": "1615936673404147",
"tags": [
"builder:win",
"buildset:commit/git/45462050397ec9b29a501e3fedbd2f2bc987672a",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/45462050397ec9b29a501e3fedbd2f2bc987672a",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9017998621564751056",
"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/8852568559413809648/+/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:5255bcca27007810",
"user_agent:luci-scheduler"
],
"updated_ts": "1615936673416572",
"url": "https://ci.chromium.org/b/8852568559413809648",
"utcnow_ts": "1615936673738415"
}
}
[23:17:53.747] Invocation finished in 1h55m22.313359343s with status SUCCEEDED