[02:22:52.525] New invocation is queued and will start shortly
[02:22:53.636] Starting the invocation (attempt 1)
[02:22:53.671] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[02:22:53.671] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[02:22:53.671] Buildbucket request:
{
"bucket": "luci.emscripten-releases.ci",
"client_operation_id": "9018342112962266960",
"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\":\"adb95b804d80e1c4342f096071ccc9a0174b62f1\"},\"id\":\"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@adb95b804d80e1c4342f096071ccc9a0174b62f1\",\"title\":\"adb95b804d80e1c4342f096071ccc9a0174b62f1\",\"url\":\"https://chromium.googlesource.com/emscripten-releases/+/adb95b804d80e1c4342f096071ccc9a0174b62f1\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/emscripten-releases\",\"revision\":\"adb95b804d80e1c4342f096071ccc9a0174b62f1\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win",
"scheduler_invocation_id:9018342112962266960",
"scheduler_job_id:emscripten-releases/win",
"user_agent:luci-scheduler",
"buildset:commit/git/adb95b804d80e1c4342f096071ccc9a0174b62f1",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/adb95b804d80e1c4342f096071ccc9a0174b62f1",
"gitiles_ref:refs/heads/master"
]
}
[02:22:54.110] Buildbucket response:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary_preference": "PROD",
"created_by": "project:emscripten-releases",
"created_ts": "1615602173753010",
"id": "8852912051013014672",
"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\": \"adb95b804d80e1c4342f096071ccc9a0174b62f1\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@adb95b804d80e1c4342f096071ccc9a0174b62f1\", \"title\": \"adb95b804d80e1c4342f096071ccc9a0174b62f1\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/adb95b804d80e1c4342f096071ccc9a0174b62f1\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"adb95b804d80e1c4342f096071ccc9a0174b62f1\"}}",
"project": "emscripten-releases",
"result_details_json": "{\"properties\": {}}",
"service_account": "emscripten-releases-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1615602173979458",
"tags": [
"builder:win",
"buildset:commit/git/adb95b804d80e1c4342f096071ccc9a0174b62f1",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/adb95b804d80e1c4342f096071ccc9a0174b62f1",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9018342112962266960",
"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/8852912051013014672/+/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": "1615602173979575",
"url": "https://ci.chromium.org/b/8852912051013014672",
"utcnow_ts": "1615602174103371"
}
}
[02:22:54.110] Task URL: https://ci.chromium.org/b/8852912051013014672
[02:22:54.110] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:2:0) after 1m0s
[02:23:54.192] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:2:0)
[02:23:54.192] Timer tick, asking Buildbucket for the build status
[02:23:54.278] Build 8852912051013014672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:23:54.278] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:3:0) after 1m0s
[02:24:54.419] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:3:0)
[02:24:54.419] Timer tick, asking Buildbucket for the build status
[02:24:54.506] Build 8852912051013014672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:24:54.506] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:4:0) after 1m0s
[02:25:54.672] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:4:0)
[02:25:54.672] Timer tick, asking Buildbucket for the build status
[02:25:54.841] Build 8852912051013014672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:25:54.841] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:5:0) after 1m0s
[02:26:54.946] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:5:0)
[02:26:54.946] Timer tick, asking Buildbucket for the build status
[02:26:55.032] Build 8852912051013014672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:26:55.032] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:6:0) after 1m0s
[02:27:55.158] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:6:0)
[02:27:55.158] Timer tick, asking Buildbucket for the build status
[02:27:55.222] Build 8852912051013014672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:27:55.222] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:7:0) after 1m0s
[02:28:55.279] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:7:0)
[02:28:55.279] Timer tick, asking Buildbucket for the build status
[02:28:55.358] Build 8852912051013014672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:28:55.358] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:8:0) after 1m0s
[02:29:55.352] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:8:0)
[02:29:55.352] Timer tick, asking Buildbucket for the build status
[02:29:55.420] Build 8852912051013014672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:29:55.420] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:9:0) after 1m0s
[02:30:55.517] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:9:0)
[02:30:55.517] Timer tick, asking Buildbucket for the build status
[02:30:55.588] Build 8852912051013014672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:30:55.588] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:10:0) after 1m0s
[02:31:55.576] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:10:0)
[02:31:55.576] Timer tick, asking Buildbucket for the build status
[02:31:55.725] Build 8852912051013014672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:31:55.725] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:11:0) after 1m0s
[02:32:55.916] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:11:0)
[02:32:55.916] Timer tick, asking Buildbucket for the build status
[02:32:55.993] Build 8852912051013014672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:32:55.993] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:12:0) after 1m0s
[02:33:56.037] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:12:0)
[02:33:56.037] Timer tick, asking Buildbucket for the build status
[02:33:56.104] Build 8852912051013014672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:33:56.104] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:13:0) after 1m0s
[02:34:56.314] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:13:0)
[02:34:56.314] Timer tick, asking Buildbucket for the build status
[02:34:56.651] Build 8852912051013014672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:34:56.651] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:14:0) after 1m0s
[02:35:56.626] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:14:0)
[02:35:56.626] Timer tick, asking Buildbucket for the build status
[02:35:56.709] Build 8852912051013014672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:35:56.709] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:15:0) after 1m0s
[02:36:56.797] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:15:0)
[02:36:56.797] Timer tick, asking Buildbucket for the build status
[02:36:56.858] Build 8852912051013014672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:36:56.858] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:16:0) after 1m0s
[02:37:56.860] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:16:0)
[02:37:56.860] Timer tick, asking Buildbucket for the build status
[02:37:56.964] Build 8852912051013014672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:37:56.964] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:17:0) after 1m0s
[02:38:56.946] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:17:0)
[02:38:56.946] Timer tick, asking Buildbucket for the build status
[02:38:57.084] Build 8852912051013014672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:38:57.084] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:18:0) after 1m0s
[02:39:57.187] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:18:0)
[02:39:57.187] Timer tick, asking Buildbucket for the build status
[02:39:57.312] Build 8852912051013014672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:39:57.312] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:19:0) after 1m0s
[02:40:57.374] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:19:0)
[02:40:57.374] Timer tick, asking Buildbucket for the build status
[02:40:57.451] Build 8852912051013014672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:40:57.451] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:20:0) after 1m0s
[02:41:57.465] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:20:0)
[02:41:57.465] Timer tick, asking Buildbucket for the build status
[02:41:57.523] Build 8852912051013014672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:41:57.523] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:21:0) after 1m0s
[02:42:57.600] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:21:0)
[02:42:57.600] Timer tick, asking Buildbucket for the build status
[02:42:57.673] Build 8852912051013014672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:42:57.673] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:22:0) after 1m0s
[02:43:57.848] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:22:0)
[02:43:57.848] Timer tick, asking Buildbucket for the build status
[02:43:58.048] Build 8852912051013014672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:43:58.048] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:23:0) after 1m0s
[02:44:58.129] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:23:0)
[02:44:58.129] Timer tick, asking Buildbucket for the build status
[02:44:58.188] Build 8852912051013014672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:44:58.188] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:24:0) after 1m0s
[02:45:58.278] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:24:0)
[02:45:58.278] Timer tick, asking Buildbucket for the build status
[02:45:58.422] Build 8852912051013014672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:45:58.422] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:25:0) after 1m0s
[02:46:58.557] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:25:0)
[02:46:58.557] Timer tick, asking Buildbucket for the build status
[02:46:58.652] Build 8852912051013014672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:46:58.652] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:26:0) after 1m0s
[02:47:58.875] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:26:0)
[02:47:58.875] Timer tick, asking Buildbucket for the build status
[02:47:58.956] Build 8852912051013014672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:47:58.956] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:27:0) after 1m0s
[02:48:59.092] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:27:0)
[02:48:59.092] Timer tick, asking Buildbucket for the build status
[02:48:59.227] Build 8852912051013014672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:48:59.227] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:28:0) after 1m0s
[02:49:59.207] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:28:0)
[02:49:59.207] Timer tick, asking Buildbucket for the build status
[02:49:59.267] Build 8852912051013014672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:49:59.267] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:29:0) after 1m0s
[02:50:59.321] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:29:0)
[02:50:59.321] Timer tick, asking Buildbucket for the build status
[02:50:59.376] Build 8852912051013014672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:50:59.376] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:30:0) after 1m0s
[02:51:59.492] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:30:0)
[02:51:59.492] Timer tick, asking Buildbucket for the build status
[02:51:59.689] Build 8852912051013014672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:51:59.689] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:31:0) after 1m0s
[02:52:59.728] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:31:0)
[02:52:59.728] Timer tick, asking Buildbucket for the build status
[02:53:00.084] Build 8852912051013014672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:53:00.084] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:32:0) after 1m0s
[02:54:00.104] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:32:0)
[02:54:00.104] Timer tick, asking Buildbucket for the build status
[02:54:00.342] Build 8852912051013014672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:54:00.342] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:33:0) after 1m0s
[02:55:00.360] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:33:0)
[02:55:00.360] Timer tick, asking Buildbucket for the build status
[02:55:00.706] Build 8852912051013014672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:55:00.706] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:34:0) after 1m0s
[02:56:01.479] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:34:0)
[02:56:01.479] Timer tick, asking Buildbucket for the build status
[02:56:01.550] Build 8852912051013014672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:56:01.550] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:35:0) after 1m0s
[02:57:01.568] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:35:0)
[02:57:01.568] Timer tick, asking Buildbucket for the build status
[02:57:01.962] Build 8852912051013014672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:57:01.962] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:36:0) after 1m0s
[02:58:02.032] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:36:0)
[02:58:02.032] Timer tick, asking Buildbucket for the build status
[02:58:02.304] Build 8852912051013014672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:58:02.304] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:37:0) after 1m0s
[02:59:02.385] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:37:0)
[02:59:02.385] Timer tick, asking Buildbucket for the build status
[02:59:02.461] Build 8852912051013014672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:59:02.461] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:38:0) after 1m0s
[03:00:02.480] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:38:0)
[03:00:02.480] Timer tick, asking Buildbucket for the build status
[03:00:02.574] Build 8852912051013014672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:00:02.574] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:39:0) after 1m0s
[03:01:02.611] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:39:0)
[03:01:02.611] Timer tick, asking Buildbucket for the build status
[03:01:02.769] Build 8852912051013014672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:01:02.769] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:40:0) after 1m0s
[03:02:02.824] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:40:0)
[03:02:02.824] Timer tick, asking Buildbucket for the build status
[03:02:02.925] Build 8852912051013014672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:02:02.925] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:41:0) after 1m0s
[03:03:02.968] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:41:0)
[03:03:02.968] Timer tick, asking Buildbucket for the build status
[03:03:03.290] Build 8852912051013014672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:03:03.290] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:42:0) after 1m0s
[03:04:03.328] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:42:0)
[03:04:03.328] Timer tick, asking Buildbucket for the build status
[03:04:03.393] Build 8852912051013014672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:04:03.393] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:43:0) after 1m0s
[03:05:03.420] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:43:0)
[03:05:03.420] Timer tick, asking Buildbucket for the build status
[03:05:03.488] Build 8852912051013014672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:05:03.488] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:44:0) after 1m0s
[03:06:03.488] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:44:0)
[03:06:03.488] Timer tick, asking Buildbucket for the build status
[03:06:03.553] Build 8852912051013014672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:06:03.553] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:45:0) after 1m0s
[03:07:03.614] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:45:0)
[03:07:03.614] Timer tick, asking Buildbucket for the build status
[03:07:03.689] Build 8852912051013014672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:07:03.690] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:46:0) after 1m0s
[03:08:03.835] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:46:0)
[03:08:03.835] Timer tick, asking Buildbucket for the build status
[03:08:03.936] Build 8852912051013014672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:08:03.936] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:47:0) after 1m0s
[03:09:04.007] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:47:0)
[03:09:04.007] Timer tick, asking Buildbucket for the build status
[03:09:04.072] Build 8852912051013014672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:09:04.072] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:48:0) after 1m0s
[03:10:04.141] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:48:0)
[03:10:04.141] Timer tick, asking Buildbucket for the build status
[03:10:04.248] Build 8852912051013014672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:10:04.248] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:49:0) after 1m0s
[03:11:04.312] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:49:0)
[03:11:04.312] Timer tick, asking Buildbucket for the build status
[03:11:04.430] Build 8852912051013014672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:11:04.430] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:50:0) after 1m0s
[03:12:04.575] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:50:0)
[03:12:04.575] Timer tick, asking Buildbucket for the build status
[03:12:04.707] Build 8852912051013014672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:12:04.708] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:51:0) after 1m0s
[03:13:04.807] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:51:0)
[03:13:04.807] Timer tick, asking Buildbucket for the build status
[03:13:04.893] Build 8852912051013014672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:13:04.893] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:52:0) after 1m0s
[03:14:05.110] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:52:0)
[03:14:05.110] Timer tick, asking Buildbucket for the build status
[03:14:05.187] Build 8852912051013014672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:14:05.187] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:53:0) after 1m0s
[03:15:05.329] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:53:0)
[03:15:05.329] Timer tick, asking Buildbucket for the build status
[03:15:05.414] Build 8852912051013014672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:15:05.414] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:54:0) after 1m0s
[03:16:05.519] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:54:0)
[03:16:05.519] Timer tick, asking Buildbucket for the build status
[03:16:05.580] Build 8852912051013014672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:16:05.580] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:55:0) after 1m0s
[03:17:05.630] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:55:0)
[03:17:05.630] Timer tick, asking Buildbucket for the build status
[03:17:05.690] Build 8852912051013014672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:17:05.690] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:56:0) after 1m0s
[03:18:05.737] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:56:0)
[03:18:05.737] Timer tick, asking Buildbucket for the build status
[03:18:05.833] Build 8852912051013014672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:18:05.833] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:57:0) after 1m0s
[03:19:05.909] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:57:0)
[03:19:05.909] Timer tick, asking Buildbucket for the build status
[03:19:06.001] Build 8852912051013014672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:19:06.001] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:58:0) after 1m0s
[03:20:06.093] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:58:0)
[03:20:06.093] Timer tick, asking Buildbucket for the build status
[03:20:06.241] Build 8852912051013014672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:20:06.241] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:59:0) after 1m0s
[03:21:06.553] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:59:0)
[03:21:06.553] Timer tick, asking Buildbucket for the build status
[03:21:07.003] Build 8852912051013014672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:21:07.003] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:60:0) after 1m0s
[03:22:07.011] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:60:0)
[03:22:07.011] Timer tick, asking Buildbucket for the build status
[03:22:07.123] Build 8852912051013014672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:22:07.123] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:61:0) after 1m0s
[03:23:07.228] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:61:0)
[03:23:07.228] Timer tick, asking Buildbucket for the build status
[03:23:07.682] Build 8852912051013014672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:23:07.682] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:62:0) after 1m0s
[03:24:07.767] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:62:0)
[03:24:07.767] Timer tick, asking Buildbucket for the build status
[03:24:08.143] Build 8852912051013014672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:24:08.143] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:63:0) after 1m0s
[03:25:08.273] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:63:0)
[03:25:08.273] Timer tick, asking Buildbucket for the build status
[03:25:08.344] Build 8852912051013014672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:25:08.344] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:64:0) after 1m0s
[03:26:08.443] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:64:0)
[03:26:08.443] Timer tick, asking Buildbucket for the build status
[03:26:08.516] Build 8852912051013014672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:26:08.516] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:65:0) after 1m0s
[03:27:08.562] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:65:0)
[03:27:08.562] Timer tick, asking Buildbucket for the build status
[03:27:09.924] Build 8852912051013014672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:27:09.924] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:66:0) after 1m0s
[03:28:09.974] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:66:0)
[03:28:09.974] Timer tick, asking Buildbucket for the build status
[03:28:10.096] Build 8852912051013014672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:28:10.096] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:67:0) after 1m0s
[03:29:10.120] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:67:0)
[03:29:10.120] Timer tick, asking Buildbucket for the build status
[03:29:10.240] Build 8852912051013014672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:29:10.240] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:68:0) after 1m0s
[03:30:10.259] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:68:0)
[03:30:10.259] Timer tick, asking Buildbucket for the build status
[03:30:10.371] Build 8852912051013014672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:30:10.371] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:69:0) after 1m0s
[03:31:10.416] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:69:0)
[03:31:10.416] Timer tick, asking Buildbucket for the build status
[03:31:10.499] Build 8852912051013014672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:31:10.499] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:70:0) after 1m0s
[03:32:10.533] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:70:0)
[03:32:10.533] Timer tick, asking Buildbucket for the build status
[03:32:10.592] Build 8852912051013014672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:32:10.592] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:71:0) after 1m0s
[03:33:10.619] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:71:0)
[03:33:10.619] Timer tick, asking Buildbucket for the build status
[03:33:10.734] Build 8852912051013014672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:33:10.734] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:72:0) after 1m0s
[03:34:10.859] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:72:0)
[03:34:10.859] Timer tick, asking Buildbucket for the build status
[03:34:10.930] Build 8852912051013014672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:34:10.930] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:73:0) after 1m0s
[03:35:11.042] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:73:0)
[03:35:11.042] Timer tick, asking Buildbucket for the build status
[03:35:11.106] Build 8852912051013014672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:35:11.106] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:74:0) after 1m0s
[03:36:11.165] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:74:0)
[03:36:11.165] Timer tick, asking Buildbucket for the build status
[03:36:11.335] Build 8852912051013014672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:36:11.335] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:75:0) after 1m0s
[03:37:11.411] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:75:0)
[03:37:11.411] Timer tick, asking Buildbucket for the build status
[03:37:11.705] Build 8852912051013014672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:37:11.705] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:76:0) after 1m0s
[03:38:11.725] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:76:0)
[03:38:11.725] Timer tick, asking Buildbucket for the build status
[03:38:11.802] Build 8852912051013014672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:38:11.802] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:77:0) after 1m0s
[03:39:11.819] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:77:0)
[03:39:11.819] Timer tick, asking Buildbucket for the build status
[03:39:11.898] Build 8852912051013014672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:39:11.898] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:78:0) after 1m0s
[03:40:11.955] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:78:0)
[03:40:11.955] Timer tick, asking Buildbucket for the build status
[03:40:12.098] Build 8852912051013014672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:40:12.098] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:79:0) after 1m0s
[03:41:12.138] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:79:0)
[03:41:12.138] Timer tick, asking Buildbucket for the build status
[03:41:12.198] Build 8852912051013014672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:41:12.198] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:80:0) after 1m0s
[03:42:12.255] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:80:0)
[03:42:12.255] Timer tick, asking Buildbucket for the build status
[03:42:12.563] Build 8852912051013014672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:42:12.563] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:81:0) after 1m0s
[03:43:12.598] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:81:0)
[03:43:12.598] Timer tick, asking Buildbucket for the build status
[03:43:13.404] Build 8852912051013014672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:43:13.404] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:82:0) after 1m0s
[03:44:13.418] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:82:0)
[03:44:13.418] Timer tick, asking Buildbucket for the build status
[03:44:13.524] Build 8852912051013014672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:44:13.524] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:83:0) after 1m0s
[03:45:13.611] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:83:0)
[03:45:13.611] Timer tick, asking Buildbucket for the build status
[03:45:14.097] Build 8852912051013014672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:45:14.097] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:84:0) after 1m0s
[03:46:14.165] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:84:0)
[03:46:14.165] Timer tick, asking Buildbucket for the build status
[03:46:14.251] Build 8852912051013014672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:46:14.251] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:85:0) after 1m0s
[03:47:14.273] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:85:0)
[03:47:14.273] Timer tick, asking Buildbucket for the build status
[03:47:14.357] Build 8852912051013014672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:47:14.357] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:86:0) after 1m0s
[03:48:14.409] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:86:0)
[03:48:14.409] Timer tick, asking Buildbucket for the build status
[03:48:14.697] Build 8852912051013014672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:48:14.697] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:87:0) after 1m0s
[03:49:10.978] Received PubSub notification, asking Buildbucket for the build status
[03:49:11.065] Build 8852912051013014672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:49:14.807] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:87:0)
[03:49:14.807] Timer tick, asking Buildbucket for the build status
[03:49:14.989] Build 8852912051013014672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:49:14.989] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:89:0) after 1m0s
[03:50:15.008] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:89:0)
[03:50:15.008] Timer tick, asking Buildbucket for the build status
[03:50:15.323] Build 8852912051013014672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:50:15.323] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:90:0) after 1m0s
[03:51:15.423] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:90:0)
[03:51:15.423] Timer tick, asking Buildbucket for the build status
[03:51:15.495] Build 8852912051013014672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:51:15.495] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:91:0) after 1m0s
[03:52:15.551] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:91:0)
[03:52:15.551] Timer tick, asking Buildbucket for the build status
[03:52:15.919] Build 8852912051013014672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:52:15.919] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:92:0) after 1m0s
[03:53:15.960] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:92:0)
[03:53:15.960] Timer tick, asking Buildbucket for the build status
[03:53:16.247] Build 8852912051013014672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:53:16.247] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:93:0) after 1m0s
[03:54:16.311] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:93:0)
[03:54:16.311] Timer tick, asking Buildbucket for the build status
[03:54:16.381] Build 8852912051013014672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:54:16.381] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:94:0) after 1m0s
[03:55:16.436] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:94:0)
[03:55:16.436] Timer tick, asking Buildbucket for the build status
[03:55:16.512] Build 8852912051013014672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:55:16.512] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:95:0) after 1m0s
[03:56:16.531] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:95:0)
[03:56:16.531] Timer tick, asking Buildbucket for the build status
[03:56:16.597] Build 8852912051013014672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:56:16.597] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:96:0) after 1m0s
[03:57:16.687] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:96:0)
[03:57:16.687] Timer tick, asking Buildbucket for the build status
[03:57:16.939] Build 8852912051013014672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:57:16.939] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:97:0) after 1m0s
[03:58:16.958] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:97:0)
[03:58:16.958] Timer tick, asking Buildbucket for the build status
[03:58:17.062] Build 8852912051013014672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:58:17.062] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:98:0) after 1m0s
[03:59:17.153] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:98:0)
[03:59:17.153] Timer tick, asking Buildbucket for the build status
[03:59:17.232] Build 8852912051013014672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:59:17.232] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:99:0) after 1m0s
[04:00:17.259] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:99:0)
[04:00:17.259] Timer tick, asking Buildbucket for the build status
[04:00:17.524] Build 8852912051013014672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:00:17.524] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:100:0) after 1m0s
[04:01:17.566] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:100:0)
[04:01:17.566] Timer tick, asking Buildbucket for the build status
[04:01:17.641] Build 8852912051013014672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:01:17.641] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:101:0) after 1m0s
[04:02:17.759] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:101:0)
[04:02:17.759] Timer tick, asking Buildbucket for the build status
[04:02:18.047] Build 8852912051013014672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:02:18.047] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:102:0) after 1m0s
[04:03:18.125] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:102:0)
[04:03:18.125] Timer tick, asking Buildbucket for the build status
[04:03:18.344] Build 8852912051013014672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:03:18.344] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:103:0) after 1m0s
[04:04:18.368] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:103:0)
[04:04:18.368] Timer tick, asking Buildbucket for the build status
[04:04:18.462] Build 8852912051013014672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:04:18.462] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:104:0) after 1m0s
[04:05:18.487] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:104:0)
[04:05:18.487] Timer tick, asking Buildbucket for the build status
[04:05:18.688] Build 8852912051013014672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:05:18.688] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:105:0) after 1m0s
[04:06:18.767] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:105:0)
[04:06:18.767] Timer tick, asking Buildbucket for the build status
[04:06:18.980] Build 8852912051013014672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:06:18.980] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:106:0) after 1m0s
[04:07:19.002] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:106:0)
[04:07:19.002] Timer tick, asking Buildbucket for the build status
[04:07:19.071] Build 8852912051013014672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:07:19.071] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:107:0) after 1m0s
[04:08:19.091] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:107:0)
[04:08:19.091] Timer tick, asking Buildbucket for the build status
[04:08:19.224] Build 8852912051013014672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:08:19.224] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:108:0) after 1m0s
[04:09:19.327] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:108:0)
[04:09:19.327] Timer tick, asking Buildbucket for the build status
[04:09:19.557] Build 8852912051013014672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:09:19.557] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:109:0) after 1m0s
[04:10:19.547] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:109:0)
[04:10:19.547] Timer tick, asking Buildbucket for the build status
[04:10:19.623] Build 8852912051013014672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:10:19.623] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:110:0) after 1m0s
[04:11:19.650] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:110:0)
[04:11:19.650] Timer tick, asking Buildbucket for the build status
[04:11:19.807] Build 8852912051013014672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:11:19.807] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:111:0) after 1m0s
[04:12:19.859] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:111:0)
[04:12:19.859] Timer tick, asking Buildbucket for the build status
[04:12:19.929] Build 8852912051013014672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:12:19.929] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:112:0) after 1m0s
[04:13:19.952] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:112:0)
[04:13:19.952] Timer tick, asking Buildbucket for the build status
[04:13:20.250] Build 8852912051013014672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:13:20.250] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:113:0) after 1m0s
[04:14:20.287] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:113:0)
[04:14:20.287] Timer tick, asking Buildbucket for the build status
[04:14:20.364] Build 8852912051013014672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:14:20.364] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:114:0) after 1m0s
[04:15:20.397] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:114:0)
[04:15:20.397] Timer tick, asking Buildbucket for the build status
[04:15:20.561] Build 8852912051013014672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:15:20.561] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:115:0) after 1m0s
[04:16:20.582] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:115:0)
[04:16:20.582] Timer tick, asking Buildbucket for the build status
[04:16:20.668] Build 8852912051013014672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:16:20.668] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:116:0) after 1m0s
[04:17:20.778] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:116:0)
[04:17:20.778] Timer tick, asking Buildbucket for the build status
[04:17:20.872] Build 8852912051013014672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:17:20.872] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:117:0) after 1m0s
[04:18:20.929] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:117:0)
[04:18:20.929] Timer tick, asking Buildbucket for the build status
[04:18:21.335] Build 8852912051013014672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:18:21.335] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:118:0) after 1m0s
[04:19:21.420] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:118:0)
[04:19:21.420] Timer tick, asking Buildbucket for the build status
[04:19:21.641] Build 8852912051013014672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:19:21.641] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:119:0) after 1m0s
[04:20:21.664] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:119:0)
[04:20:21.664] Timer tick, asking Buildbucket for the build status
[04:20:21.998] Build 8852912051013014672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:20:21.998] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:120:0) after 1m0s
[04:21:22.049] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:120:0)
[04:21:22.049] Timer tick, asking Buildbucket for the build status
[04:21:22.119] Build 8852912051013014672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:21:22.119] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:121:0) after 1m0s
[04:22:22.224] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:121:0)
[04:22:22.224] Timer tick, asking Buildbucket for the build status
[04:22:22.303] Build 8852912051013014672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:22:22.303] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:122:0) after 1m0s
[04:23:22.435] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:122:0)
[04:23:22.435] Timer tick, asking Buildbucket for the build status
[04:23:22.555] Build 8852912051013014672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:23:22.555] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:123:0) after 1m0s
[04:24:22.574] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:123:0)
[04:24:22.574] Timer tick, asking Buildbucket for the build status
[04:24:22.657] Build 8852912051013014672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:24:22.657] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:124:0) after 1m0s
[04:25:22.685] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:124:0)
[04:25:22.685] Timer tick, asking Buildbucket for the build status
[04:25:22.817] Build 8852912051013014672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:25:22.817] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:125:0) after 1m0s
[04:26:22.838] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:125:0)
[04:26:22.838] Timer tick, asking Buildbucket for the build status
[04:26:23.054] Build 8852912051013014672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:26:23.054] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:126:0) after 1m0s
[04:27:23.085] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:126:0)
[04:27:23.085] Timer tick, asking Buildbucket for the build status
[04:27:23.258] Build 8852912051013014672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:27:23.258] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:127:0) after 1m0s
[04:28:23.335] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:127:0)
[04:28:23.335] Timer tick, asking Buildbucket for the build status
[04:28:23.449] Build 8852912051013014672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:28:23.449] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:128:0) after 1m0s
[04:29:23.486] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:128:0)
[04:29:23.486] Timer tick, asking Buildbucket for the build status
[04:29:23.736] Build 8852912051013014672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:29:23.737] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:129:0) after 1m0s
[04:30:23.827] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:129:0)
[04:30:23.827] Timer tick, asking Buildbucket for the build status
[04:30:24.136] Build 8852912051013014672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:30:24.136] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:130:0) after 1m0s
[04:31:24.157] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:130:0)
[04:31:24.157] Timer tick, asking Buildbucket for the build status
[04:31:24.345] Build 8852912051013014672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:31:24.345] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:131:0) after 1m0s
[04:32:24.334] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:131:0)
[04:32:24.334] Timer tick, asking Buildbucket for the build status
[04:32:24.750] Build 8852912051013014672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:32:24.750] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:132:0) after 1m0s
[04:33:24.915] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:132:0)
[04:33:24.915] Timer tick, asking Buildbucket for the build status
[04:33:25.542] Build 8852912051013014672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:33:25.542] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:133:0) after 1m0s
[04:34:25.639] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:133:0)
[04:34:25.639] Timer tick, asking Buildbucket for the build status
[04:34:25.732] Build 8852912051013014672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:34:25.732] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:134:0) after 1m0s
[04:35:25.759] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:134:0)
[04:35:25.759] Timer tick, asking Buildbucket for the build status
[04:35:26.009] Build 8852912051013014672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:35:26.009] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:135:0) after 1m0s
[04:36:26.023] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:135:0)
[04:36:26.023] Timer tick, asking Buildbucket for the build status
[04:36:26.105] Build 8852912051013014672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:36:26.105] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:136:0) after 1m0s
[04:37:26.129] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:136:0)
[04:37:26.129] Timer tick, asking Buildbucket for the build status
[04:37:26.218] Build 8852912051013014672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:37:26.218] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:137:0) after 1m0s
[04:38:26.302] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:137:0)
[04:38:26.302] Timer tick, asking Buildbucket for the build status
[04:38:26.643] Build 8852912051013014672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:38:26.643] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9018342112962266960:138:0) after 1m0s
[04:39:00.909] Received PubSub notification, asking Buildbucket for the build status
[04:39:00.997] Build 8852912051013014672: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[04:39:00.997] Buildbucket build:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary_preference": "PROD",
"completed_ts": "1615610340472836",
"created_by": "project:emscripten-releases",
"created_ts": "1615602173753010",
"id": "8852912051013014672",
"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\": \"adb95b804d80e1c4342f096071ccc9a0174b62f1\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@adb95b804d80e1c4342f096071ccc9a0174b62f1\", \"title\": \"adb95b804d80e1c4342f096071ccc9a0174b62f1\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/adb95b804d80e1c4342f096071ccc9a0174b62f1\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"adb95b804d80e1c4342f096071ccc9a0174b62f1\"}}",
"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\": \"adb95b804d80e1c4342f096071ccc9a0174b62f1\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@adb95b804d80e1c4342f096071ccc9a0174b62f1\", \"title\": \"adb95b804d80e1c4342f096071ccc9a0174b62f1\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/adb95b804d80e1c4342f096071ccc9a0174b62f1\"}]}, \"bot_id\": \"luci-emscripten-releases-ci-win10-0-1uhs\", \"branch\": \"refs/heads/master\", \"buildername\": \"win\", \"got_revision\": \"adb95b804d80e1c4342f096071ccc9a0174b62f1\", \"path_config\": \"generic\", \"recipe\": \"emscripten_releases\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"adb95b804d80e1c4342f096071ccc9a0174b62f1\"}, \"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-1uhs\"], \"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": "1615607350309038",
"status": "COMPLETED",
"status_changed_ts": "1615610340703923",
"tags": [
"builder:win",
"buildset:commit/git/adb95b804d80e1c4342f096071ccc9a0174b62f1",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/adb95b804d80e1c4342f096071ccc9a0174b62f1",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9018342112962266960",
"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/8852912051013014672/+/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:52423651a4a6ab10",
"user_agent:luci-scheduler"
],
"updated_ts": "1615610340720320",
"url": "https://ci.chromium.org/b/8852912051013014672",
"utcnow_ts": "1615610340988969"
}
}
[04:39:00.997] Invocation finished in 2h16m8.486827969s with status SUCCEEDED