[20:48:58.378] New invocation is queued and will start shortly
[20:48:59.978] Starting the invocation (attempt 1)
[20:49:00.024] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[20:49:00.024] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[20:49:00.024] Buildbucket request:
{
"bucket": "luci.emscripten-releases.ci",
"client_operation_id": "9065382945861419728",
"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\":\"7ec86f994cbbc52d150f425ce20c2e2259679297\"},\"id\":\"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@7ec86f994cbbc52d150f425ce20c2e2259679297\",\"title\":\"7ec86f994cbbc52d150f425ce20c2e2259679297\",\"url\":\"https://chromium.googlesource.com/emscripten-releases/+/7ec86f994cbbc52d150f425ce20c2e2259679297\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/emscripten-releases\",\"revision\":\"7ec86f994cbbc52d150f425ce20c2e2259679297\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win",
"scheduler_invocation_id:9065382945861419728",
"scheduler_job_id:emscripten-releases/win",
"user_agent:luci-scheduler",
"buildset:commit/git/7ec86f994cbbc52d150f425ce20c2e2259679297",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/7ec86f994cbbc52d150f425ce20c2e2259679297",
"gitiles_ref:refs/heads/master"
]
}
[20:49:01.134] Buildbucket response:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary": true,
"canary_preference": "CANARY",
"created_by": "project:emscripten-releases",
"created_ts": "1570740540247331",
"id": "8899952883228608384",
"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\": \"7ec86f994cbbc52d150f425ce20c2e2259679297\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@7ec86f994cbbc52d150f425ce20c2e2259679297\", \"title\": \"7ec86f994cbbc52d150f425ce20c2e2259679297\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/7ec86f994cbbc52d150f425ce20c2e2259679297\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"7ec86f994cbbc52d150f425ce20c2e2259679297\"}}",
"project": "emscripten-releases",
"result_details_json": "{\"properties\": {}}",
"service_account": "emscripten-releases-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1570740540892759",
"tags": [
"builder:win",
"buildset:commit/git/7ec86f994cbbc52d150f425ce20c2e2259679297",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/7ec86f994cbbc52d150f425ce20c2e2259679297",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9065382945861419728",
"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/8899952883228608384/+/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": "1570740540892893",
"url": "https://ci.chromium.org/b/8899952883228608384",
"utcnow_ts": "1570740541118380"
}
}
[20:49:01.134] Task URL: https://ci.chromium.org/b/8899952883228608384
[20:49:01.134] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:2:0) after 1m0s
[20:50:01.214] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:2:0)
[20:50:01.214] Timer tick, asking Buildbucket for the build status
[20:50:01.652] Build 8899952883228608384: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:50:01.652] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:3:0) after 1m0s
[20:51:01.686] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:3:0)
[20:51:01.686] Timer tick, asking Buildbucket for the build status
[20:51:02.756] Build 8899952883228608384: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:51:02.756] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:4:0) after 1m0s
[20:52:02.799] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:4:0)
[20:52:02.800] Timer tick, asking Buildbucket for the build status
[20:52:03.087] Build 8899952883228608384: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:52:03.087] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:5:0) after 1m0s
[20:53:03.154] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:5:0)
[20:53:03.154] Timer tick, asking Buildbucket for the build status
[20:53:03.451] Build 8899952883228608384: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:53:03.451] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:6:0) after 1m0s
[20:54:03.663] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:6:0)
[20:54:03.664] Timer tick, asking Buildbucket for the build status
[20:54:03.903] Build 8899952883228608384: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:54:03.903] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:7:0) after 1m0s
[20:55:03.945] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:7:0)
[20:55:03.945] Timer tick, asking Buildbucket for the build status
[20:55:04.684] Build 8899952883228608384: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:55:04.684] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:8:0) after 1m0s
[20:56:04.836] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:8:0)
[20:56:04.836] Timer tick, asking Buildbucket for the build status
[20:56:04.930] Build 8899952883228608384: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:56:04.930] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:9:0) after 1m0s
[20:57:04.971] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:9:0)
[20:57:04.971] Timer tick, asking Buildbucket for the build status
[20:57:05.073] Build 8899952883228608384: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:57:05.073] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:10:0) after 1m0s
[20:58:05.238] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:10:0)
[20:58:05.238] Timer tick, asking Buildbucket for the build status
[20:58:05.371] Build 8899952883228608384: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:58:05.371] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:11:0) after 1m0s
[20:59:05.504] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:11:0)
[20:59:05.504] Timer tick, asking Buildbucket for the build status
[20:59:05.776] Build 8899952883228608384: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:59:05.776] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:12:0) after 1m0s
[21:00:05.879] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:12:0)
[21:00:05.879] Timer tick, asking Buildbucket for the build status
[21:00:06.909] Build 8899952883228608384: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:00:06.909] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:13:0) after 1m0s
[21:01:07.313] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:13:0)
[21:01:07.313] Timer tick, asking Buildbucket for the build status
[21:01:07.647] Build 8899952883228608384: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:01:07.647] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:14:0) after 1m0s
[21:02:07.761] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:14:0)
[21:02:07.761] Timer tick, asking Buildbucket for the build status
[21:02:08.731] Build 8899952883228608384: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:02:08.731] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:15:0) after 1m0s
[21:03:08.809] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:15:0)
[21:03:08.809] Timer tick, asking Buildbucket for the build status
[21:03:09.007] Build 8899952883228608384: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:03:09.007] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:16:0) after 1m0s
[21:04:09.133] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:16:0)
[21:04:09.133] Timer tick, asking Buildbucket for the build status
[21:04:09.366] Build 8899952883228608384: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:04:09.366] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:17:0) after 1m0s
[21:05:09.344] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:17:0)
[21:05:09.344] Timer tick, asking Buildbucket for the build status
[21:05:09.441] Build 8899952883228608384: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:05:09.441] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:18:0) after 1m0s
[21:06:09.596] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:18:0)
[21:06:09.596] Timer tick, asking Buildbucket for the build status
[21:06:09.850] Build 8899952883228608384: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:06:09.850] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:19:0) after 1m0s
[21:07:09.873] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:19:0)
[21:07:09.873] Timer tick, asking Buildbucket for the build status
[21:07:11.140] Build 8899952883228608384: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:07:11.140] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:20:0) after 1m0s
[21:08:11.315] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:20:0)
[21:08:11.315] Timer tick, asking Buildbucket for the build status
[21:08:11.465] Build 8899952883228608384: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:08:11.465] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:21:0) after 1m0s
[21:09:11.662] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:21:0)
[21:09:11.662] Timer tick, asking Buildbucket for the build status
[21:09:11.855] Build 8899952883228608384: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:09:11.855] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:22:0) after 1m0s
[21:10:11.933] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:22:0)
[21:10:11.933] Timer tick, asking Buildbucket for the build status
[21:10:13.338] Build 8899952883228608384: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:10:13.338] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:23:0) after 1m0s
[21:11:13.495] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:23:0)
[21:11:13.495] Timer tick, asking Buildbucket for the build status
[21:11:18.058] Build 8899952883228608384: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:11:18.058] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:24:0) after 1m0s
[21:12:18.191] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:24:0)
[21:12:18.191] Timer tick, asking Buildbucket for the build status
[21:12:18.347] Build 8899952883228608384: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:12:18.347] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:25:0) after 1m0s
[21:13:18.585] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:25:0)
[21:13:18.585] Timer tick, asking Buildbucket for the build status
[21:13:18.682] Build 8899952883228608384: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:13:18.682] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:26:0) after 1m0s
[21:14:18.899] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:26:0)
[21:14:18.899] Timer tick, asking Buildbucket for the build status
[21:14:19.017] Build 8899952883228608384: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:14:19.017] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:27:0) after 1m0s
[21:15:19.110] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:27:0)
[21:15:19.110] Timer tick, asking Buildbucket for the build status
[21:15:19.959] Build 8899952883228608384: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:15:19.959] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:28:0) after 1m0s
[21:16:20.092] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:28:0)
[21:16:20.092] Timer tick, asking Buildbucket for the build status
[21:16:20.455] Build 8899952883228608384: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:16:20.455] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:29:0) after 1m0s
[21:17:20.466] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:29:0)
[21:17:20.466] Timer tick, asking Buildbucket for the build status
[21:17:20.707] Build 8899952883228608384: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:17:20.707] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:30:0) after 1m0s
[21:18:20.939] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:30:0)
[21:18:20.939] Timer tick, asking Buildbucket for the build status
[21:18:21.142] Build 8899952883228608384: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:18:21.142] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:31:0) after 1m0s
[21:19:21.248] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:31:0)
[21:19:21.248] Timer tick, asking Buildbucket for the build status
[21:19:21.629] Build 8899952883228608384: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:19:21.629] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:32:0) after 1m0s
[21:20:21.719] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:32:0)
[21:20:21.719] Timer tick, asking Buildbucket for the build status
[21:20:21.831] Build 8899952883228608384: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:20:21.831] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:33:0) after 1m0s
[21:21:21.908] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:33:0)
[21:21:21.908] Timer tick, asking Buildbucket for the build status
[21:21:22.203] Build 8899952883228608384: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:21:22.203] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:34:0) after 1m0s
[21:22:22.301] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:34:0)
[21:22:22.301] Timer tick, asking Buildbucket for the build status
[21:22:22.635] Build 8899952883228608384: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:22:22.635] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:35:0) after 1m0s
[21:23:22.661] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:35:0)
[21:23:22.661] Timer tick, asking Buildbucket for the build status
[21:23:22.918] Build 8899952883228608384: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:23:22.918] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:36:0) after 1m0s
[21:24:23.011] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:36:0)
[21:24:23.011] Timer tick, asking Buildbucket for the build status
[21:24:23.202] Build 8899952883228608384: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:24:23.202] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:37:0) after 1m0s
[21:25:23.367] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:37:0)
[21:25:23.367] Timer tick, asking Buildbucket for the build status
[21:25:24.713] Build 8899952883228608384: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:25:24.713] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:38:0) after 1m0s
[21:26:24.767] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:38:0)
[21:26:24.767] Timer tick, asking Buildbucket for the build status
[21:26:24.914] Build 8899952883228608384: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:26:24.914] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:39:0) after 1m0s
[21:27:25.088] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:39:0)
[21:27:25.088] Timer tick, asking Buildbucket for the build status
[21:27:25.184] Build 8899952883228608384: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:27:25.184] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:40:0) after 1m0s
[21:28:25.528] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:40:0)
[21:28:25.528] Timer tick, asking Buildbucket for the build status
[21:28:25.739] Build 8899952883228608384: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:28:25.739] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:41:0) after 1m0s
[21:29:25.900] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:41:0)
[21:29:25.900] Timer tick, asking Buildbucket for the build status
[21:29:26.055] Build 8899952883228608384: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:29:26.055] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:42:0) after 1m0s
[21:30:26.222] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:42:0)
[21:30:26.222] Timer tick, asking Buildbucket for the build status
[21:30:26.413] Build 8899952883228608384: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:30:26.413] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:43:0) after 1m0s
[21:31:26.534] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:43:0)
[21:31:26.534] Timer tick, asking Buildbucket for the build status
[21:31:26.623] Build 8899952883228608384: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:31:26.623] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:44:0) after 1m0s
[21:32:26.742] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:44:0)
[21:32:26.742] Timer tick, asking Buildbucket for the build status
[21:32:26.924] Build 8899952883228608384: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:32:26.924] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:45:0) after 1m0s
[21:33:27.051] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:45:0)
[21:33:27.051] Timer tick, asking Buildbucket for the build status
[21:33:27.495] Build 8899952883228608384: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:33:27.495] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:46:0) after 1m0s
[21:34:27.585] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:46:0)
[21:34:27.585] Timer tick, asking Buildbucket for the build status
[21:34:27.964] Build 8899952883228608384: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:34:27.964] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:47:0) after 1m0s
[21:35:28.164] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:47:0)
[21:35:28.164] Timer tick, asking Buildbucket for the build status
[21:35:28.402] Build 8899952883228608384: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:35:28.402] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:48:0) after 1m0s
[21:36:28.612] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:48:0)
[21:36:28.612] Timer tick, asking Buildbucket for the build status
[21:36:28.791] Build 8899952883228608384: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:36:28.791] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:49:0) after 1m0s
[21:37:28.918] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:49:0)
[21:37:28.918] Timer tick, asking Buildbucket for the build status
[21:37:29.137] Build 8899952883228608384: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:37:29.137] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:50:0) after 1m0s
[21:38:29.223] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:50:0)
[21:38:29.223] Timer tick, asking Buildbucket for the build status
[21:38:29.314] Build 8899952883228608384: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:38:29.314] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:51:0) after 1m0s
[21:39:29.370] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:51:0)
[21:39:29.370] Timer tick, asking Buildbucket for the build status
[21:39:29.491] Build 8899952883228608384: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:39:29.491] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:52:0) after 1m0s
[21:40:29.480] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:52:0)
[21:40:29.481] Timer tick, asking Buildbucket for the build status
[21:40:29.609] Build 8899952883228608384: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:40:29.609] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:53:0) after 1m0s
[21:41:29.691] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:53:0)
[21:41:29.691] Timer tick, asking Buildbucket for the build status
[21:41:30.597] Build 8899952883228608384: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:41:30.597] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:54:0) after 1m0s
[21:42:30.709] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:54:0)
[21:42:30.709] Timer tick, asking Buildbucket for the build status
[21:42:30.843] Build 8899952883228608384: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:42:30.843] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:55:0) after 1m0s
[21:43:30.938] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:55:0)
[21:43:30.938] Timer tick, asking Buildbucket for the build status
[21:43:31.116] Build 8899952883228608384: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:43:31.116] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:56:0) after 1m0s
[21:44:31.234] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:56:0)
[21:44:31.234] Timer tick, asking Buildbucket for the build status
[21:44:31.336] Build 8899952883228608384: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:44:31.336] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:57:0) after 1m0s
[21:45:31.508] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:57:0)
[21:45:31.508] Timer tick, asking Buildbucket for the build status
[21:45:31.668] Build 8899952883228608384: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:45:31.668] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:58:0) after 1m0s
[21:46:31.830] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:58:0)
[21:46:31.830] Timer tick, asking Buildbucket for the build status
[21:46:32.088] Build 8899952883228608384: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:46:32.088] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:59:0) after 1m0s
[21:47:32.209] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:59:0)
[21:47:32.209] Timer tick, asking Buildbucket for the build status
[21:47:32.374] Build 8899952883228608384: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:47:32.374] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:60:0) after 1m0s
[21:48:32.781] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:60:0)
[21:48:32.781] Timer tick, asking Buildbucket for the build status
[21:48:33.021] Build 8899952883228608384: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:48:33.021] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:61:0) after 1m0s
[21:49:33.085] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:61:0)
[21:49:33.085] Timer tick, asking Buildbucket for the build status
[21:49:33.190] Build 8899952883228608384: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:49:33.190] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:62:0) after 1m0s
[21:50:33.246] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:62:0)
[21:50:33.246] Timer tick, asking Buildbucket for the build status
[21:50:33.659] Build 8899952883228608384: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:50:33.659] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:63:0) after 1m0s
[21:51:33.646] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:63:0)
[21:51:33.646] Timer tick, asking Buildbucket for the build status
[21:51:34.127] Build 8899952883228608384: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:51:34.127] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:64:0) after 1m0s
[21:52:34.139] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:64:0)
[21:52:34.139] Timer tick, asking Buildbucket for the build status
[21:52:34.434] Build 8899952883228608384: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:52:34.434] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:65:0) after 1m0s
[21:53:34.548] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:65:0)
[21:53:34.548] Timer tick, asking Buildbucket for the build status
[21:53:34.847] Build 8899952883228608384: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:53:34.847] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:66:0) after 1m0s
[21:54:34.989] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:66:0)
[21:54:34.989] Timer tick, asking Buildbucket for the build status
[21:54:35.285] Build 8899952883228608384: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:54:35.285] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:67:0) after 1m0s
[21:55:35.550] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:67:0)
[21:55:35.550] Timer tick, asking Buildbucket for the build status
[21:55:36.749] Build 8899952883228608384: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:55:36.749] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:68:0) after 1m0s
[21:56:36.770] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:68:0)
[21:56:36.770] Timer tick, asking Buildbucket for the build status
[21:56:36.870] Build 8899952883228608384: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:56:36.870] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:69:0) after 1m0s
[21:57:37.251] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:69:0)
[21:57:37.251] Timer tick, asking Buildbucket for the build status
[21:57:37.395] Build 8899952883228608384: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:57:37.395] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:70:0) after 1m0s
[21:58:37.671] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:70:0)
[21:58:37.671] Timer tick, asking Buildbucket for the build status
[21:58:37.776] Build 8899952883228608384: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:58:37.776] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:71:0) after 1m0s
[21:59:37.870] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:71:0)
[21:59:37.870] Timer tick, asking Buildbucket for the build status
[21:59:37.996] Build 8899952883228608384: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:59:37.996] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:72:0) after 1m0s
[22:00:38.211] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:72:0)
[22:00:38.212] Timer tick, asking Buildbucket for the build status
[22:00:38.317] Build 8899952883228608384: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:00:38.317] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:73:0) after 1m0s
[22:01:38.624] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:73:0)
[22:01:38.624] Timer tick, asking Buildbucket for the build status
[22:01:38.894] Build 8899952883228608384: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:01:38.894] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:74:0) after 1m0s
[22:02:37.331] Received PubSub notification, asking Buildbucket for the build status
[22:02:37.413] Build 8899952883228608384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:02:39.053] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:74:0)
[22:02:39.053] Timer tick, asking Buildbucket for the build status
[22:02:39.203] Build 8899952883228608384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:02:39.203] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:76:0) after 1m0s
[22:03:39.329] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:76:0)
[22:03:39.329] Timer tick, asking Buildbucket for the build status
[22:03:40.323] Build 8899952883228608384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:03:40.323] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:77:0) after 1m0s
[22:04:40.551] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:77:0)
[22:04:40.551] Timer tick, asking Buildbucket for the build status
[22:04:40.931] Build 8899952883228608384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:04:40.931] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:78:0) after 1m0s
[22:05:41.019] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:78:0)
[22:05:41.019] Timer tick, asking Buildbucket for the build status
[22:05:41.862] Build 8899952883228608384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:05:41.862] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:79:0) after 1m0s
[22:06:42.045] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:79:0)
[22:06:42.045] Timer tick, asking Buildbucket for the build status
[22:06:42.173] Build 8899952883228608384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:06:42.173] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:80:0) after 1m0s
[22:07:42.274] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:80:0)
[22:07:42.274] Timer tick, asking Buildbucket for the build status
[22:07:42.565] Build 8899952883228608384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:07:42.565] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:81:0) after 1m0s
[22:08:42.837] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:81:0)
[22:08:42.837] Timer tick, asking Buildbucket for the build status
[22:08:43.013] Build 8899952883228608384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:08:43.013] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:82:0) after 1m0s
[22:09:43.376] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:82:0)
[22:09:43.376] Timer tick, asking Buildbucket for the build status
[22:09:43.513] Build 8899952883228608384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:09:43.513] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:83:0) after 1m0s
[22:10:43.651] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:83:0)
[22:10:43.651] Timer tick, asking Buildbucket for the build status
[22:10:43.796] Build 8899952883228608384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:10:43.796] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:84:0) after 1m0s
[22:11:43.886] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:84:0)
[22:11:43.886] Timer tick, asking Buildbucket for the build status
[22:11:44.105] Build 8899952883228608384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:11:44.105] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:85:0) after 1m0s
[22:12:44.364] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:85:0)
[22:12:44.364] Timer tick, asking Buildbucket for the build status
[22:12:45.234] Build 8899952883228608384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:12:45.234] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:86:0) after 1m0s
[22:13:45.469] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:86:0)
[22:13:45.469] Timer tick, asking Buildbucket for the build status
[22:13:45.845] Build 8899952883228608384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:13:45.845] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:87:0) after 1m0s
[22:14:45.953] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:87:0)
[22:14:45.953] Timer tick, asking Buildbucket for the build status
[22:14:46.124] Build 8899952883228608384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:14:46.124] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:88:0) after 1m0s
[22:15:46.229] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:88:0)
[22:15:46.229] Timer tick, asking Buildbucket for the build status
[22:15:46.358] Build 8899952883228608384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:15:46.358] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:89:0) after 1m0s
[22:16:46.499] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:89:0)
[22:16:46.499] Timer tick, asking Buildbucket for the build status
[22:16:46.874] Build 8899952883228608384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:16:46.874] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:90:0) after 1m0s
[22:17:46.935] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:90:0)
[22:17:46.935] Timer tick, asking Buildbucket for the build status
[22:17:47.085] Build 8899952883228608384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:17:47.085] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:91:0) after 1m0s
[22:18:47.122] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:91:0)
[22:18:47.122] Timer tick, asking Buildbucket for the build status
[22:18:47.780] Build 8899952883228608384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:18:47.780] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:92:0) after 1m0s
[22:19:47.847] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:92:0)
[22:19:47.847] Timer tick, asking Buildbucket for the build status
[22:19:47.962] Build 8899952883228608384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:19:47.962] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:93:0) after 1m0s
[22:20:48.207] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:93:0)
[22:20:48.207] Timer tick, asking Buildbucket for the build status
[22:20:48.626] Build 8899952883228608384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:20:48.626] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:94:0) after 1m0s
[22:21:48.724] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:94:0)
[22:21:48.724] Timer tick, asking Buildbucket for the build status
[22:21:48.839] Build 8899952883228608384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:21:48.839] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:95:0) after 1m0s
[22:22:48.948] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:95:0)
[22:22:48.948] Timer tick, asking Buildbucket for the build status
[22:22:49.325] Build 8899952883228608384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:22:49.325] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:96:0) after 1m0s
[22:23:49.374] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:96:0)
[22:23:49.374] Timer tick, asking Buildbucket for the build status
[22:23:49.528] Build 8899952883228608384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:23:49.528] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:97:0) after 1m0s
[22:24:49.579] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:97:0)
[22:24:49.579] Timer tick, asking Buildbucket for the build status
[22:24:49.794] Build 8899952883228608384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:24:49.794] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:98:0) after 1m0s
[22:25:49.862] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:98:0)
[22:25:49.862] Timer tick, asking Buildbucket for the build status
[22:25:50.068] Build 8899952883228608384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:25:50.068] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:99:0) after 1m0s
[22:26:50.273] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:99:0)
[22:26:50.273] Timer tick, asking Buildbucket for the build status
[22:26:50.410] Build 8899952883228608384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:26:50.410] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:100:0) after 1m0s
[22:27:50.524] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:100:0)
[22:27:50.524] Timer tick, asking Buildbucket for the build status
[22:27:50.739] Build 8899952883228608384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:27:50.739] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:101:0) after 1m0s
[22:28:50.982] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:101:0)
[22:28:50.982] Timer tick, asking Buildbucket for the build status
[22:28:51.236] Build 8899952883228608384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:28:51.236] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:102:0) after 1m0s
[22:29:51.309] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:102:0)
[22:29:51.309] Timer tick, asking Buildbucket for the build status
[22:29:53.140] Build 8899952883228608384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:29:53.140] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:103:0) after 1m0s
[22:30:53.352] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:103:0)
[22:30:53.352] Timer tick, asking Buildbucket for the build status
[22:30:53.715] Build 8899952883228608384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:30:53.715] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:104:0) after 1m0s
[22:31:53.807] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:104:0)
[22:31:53.807] Timer tick, asking Buildbucket for the build status
[22:31:53.920] Build 8899952883228608384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:31:53.920] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:105:0) after 1m0s
[22:32:54.148] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:105:0)
[22:32:54.148] Timer tick, asking Buildbucket for the build status
[22:32:55.168] Build 8899952883228608384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:32:55.168] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:106:0) after 1m0s
[22:33:55.206] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:106:0)
[22:33:55.206] Timer tick, asking Buildbucket for the build status
[22:33:55.323] Build 8899952883228608384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:33:55.323] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:107:0) after 1m0s
[22:34:55.557] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:107:0)
[22:34:55.557] Timer tick, asking Buildbucket for the build status
[22:34:55.932] Build 8899952883228608384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:34:55.932] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:108:0) after 1m0s
[22:35:56.025] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:108:0)
[22:35:56.025] Timer tick, asking Buildbucket for the build status
[22:35:57.547] Build 8899952883228608384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:35:57.547] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:109:0) after 1m0s
[22:36:57.830] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:109:0)
[22:36:57.830] Timer tick, asking Buildbucket for the build status
[22:36:58.202] Build 8899952883228608384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:36:58.202] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:110:0) after 1m0s
[22:37:58.306] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:110:0)
[22:37:58.306] Timer tick, asking Buildbucket for the build status
[22:37:58.454] Build 8899952883228608384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:37:58.454] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:111:0) after 1m0s
[22:38:58.540] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:111:0)
[22:38:58.540] Timer tick, asking Buildbucket for the build status
[22:38:58.922] Build 8899952883228608384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:38:58.922] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:112:0) after 1m0s
[22:39:58.972] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:112:0)
[22:39:58.972] Timer tick, asking Buildbucket for the build status
[22:39:59.131] Build 8899952883228608384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:39:59.131] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:113:0) after 1m0s
[22:40:59.411] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:113:0)
[22:40:59.411] Timer tick, asking Buildbucket for the build status
[22:40:59.559] Build 8899952883228608384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:40:59.559] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:114:0) after 1m0s
[22:41:59.666] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:114:0)
[22:41:59.666] Timer tick, asking Buildbucket for the build status
[22:41:59.757] Build 8899952883228608384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:41:59.757] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:115:0) after 1m0s
[22:42:59.857] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:115:0)
[22:42:59.857] Timer tick, asking Buildbucket for the build status
[22:43:00.046] Build 8899952883228608384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:43:00.046] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:116:0) after 1m0s
[22:44:00.221] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:116:0)
[22:44:00.221] Timer tick, asking Buildbucket for the build status
[22:44:00.773] Build 8899952883228608384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:44:00.773] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:117:0) after 1m0s
[22:45:00.860] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:117:0)
[22:45:00.860] Timer tick, asking Buildbucket for the build status
[22:45:01.263] Build 8899952883228608384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:45:01.263] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:118:0) after 1m0s
[22:46:01.264] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:118:0)
[22:46:01.264] Timer tick, asking Buildbucket for the build status
[22:46:01.379] Build 8899952883228608384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:46:01.379] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:119:0) after 1m0s
[22:47:01.413] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:119:0)
[22:47:01.413] Timer tick, asking Buildbucket for the build status
[22:47:01.515] Build 8899952883228608384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:47:01.515] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:120:0) after 1m0s
[22:48:01.545] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:120:0)
[22:48:01.545] Timer tick, asking Buildbucket for the build status
[22:48:01.751] Build 8899952883228608384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:48:01.751] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:121:0) after 1m0s
[22:49:01.777] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:121:0)
[22:49:01.777] Timer tick, asking Buildbucket for the build status
[22:49:02.033] Build 8899952883228608384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:49:02.033] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:122:0) after 1m0s
[22:50:02.019] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:122:0)
[22:50:02.019] Timer tick, asking Buildbucket for the build status
[22:50:02.419] Build 8899952883228608384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:50:02.419] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:123:0) after 1m0s
[22:51:02.542] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:123:0)
[22:51:02.542] Timer tick, asking Buildbucket for the build status
[22:51:02.637] Build 8899952883228608384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:51:02.637] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:124:0) after 1m0s
[22:52:02.725] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:124:0)
[22:52:02.725] Timer tick, asking Buildbucket for the build status
[22:52:03.175] Build 8899952883228608384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:52:03.175] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:125:0) after 1m0s
[22:53:03.209] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:125:0)
[22:53:03.209] Timer tick, asking Buildbucket for the build status
[22:53:05.062] Build 8899952883228608384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:53:05.062] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:126:0) after 1m0s
[22:54:05.157] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:126:0)
[22:54:05.157] Timer tick, asking Buildbucket for the build status
[22:54:05.519] Build 8899952883228608384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:54:05.519] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:127:0) after 1m0s
[22:55:05.762] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:127:0)
[22:55:05.762] Timer tick, asking Buildbucket for the build status
[22:55:05.910] Build 8899952883228608384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:55:05.910] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:128:0) after 1m0s
[22:56:06.075] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:128:0)
[22:56:06.075] Timer tick, asking Buildbucket for the build status
[22:56:06.206] Build 8899952883228608384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:56:06.206] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:129:0) after 1m0s
[22:57:06.339] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:129:0)
[22:57:06.339] Timer tick, asking Buildbucket for the build status
[22:57:09.132] Build 8899952883228608384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:57:09.133] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:130:0) after 1m0s
[22:58:09.173] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:130:0)
[22:58:09.173] Timer tick, asking Buildbucket for the build status
[22:58:09.622] Build 8899952883228608384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:58:09.622] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:131:0) after 1m0s
[22:59:09.742] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:131:0)
[22:59:09.742] Timer tick, asking Buildbucket for the build status
[22:59:10.192] Build 8899952883228608384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:59:10.192] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:132:0) after 1m0s
[23:00:10.281] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:132:0)
[23:00:10.281] Timer tick, asking Buildbucket for the build status
[23:00:10.708] Build 8899952883228608384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:00:10.708] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:133:0) after 1m0s
[23:01:10.905] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:133:0)
[23:01:10.905] Timer tick, asking Buildbucket for the build status
[23:01:11.706] Build 8899952883228608384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:01:11.706] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:134:0) after 1m0s
[23:02:11.830] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:134:0)
[23:02:11.830] Timer tick, asking Buildbucket for the build status
[23:02:12.270] Build 8899952883228608384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:02:12.270] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:135:0) after 1m0s
[23:03:12.391] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:135:0)
[23:03:12.391] Timer tick, asking Buildbucket for the build status
[23:03:13.492] Build 8899952883228608384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:03:13.492] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:136:0) after 1m0s
[23:04:13.581] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:136:0)
[23:04:13.581] Timer tick, asking Buildbucket for the build status
[23:04:13.945] Build 8899952883228608384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:04:13.945] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:137:0) after 1m0s
[23:05:14.014] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:137:0)
[23:05:14.014] Timer tick, asking Buildbucket for the build status
[23:05:14.306] Build 8899952883228608384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:05:14.306] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:138:0) after 1m0s
[23:06:14.403] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:138:0)
[23:06:14.403] Timer tick, asking Buildbucket for the build status
[23:06:14.483] Build 8899952883228608384: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:06:14.483] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065382945861419728:139:0) after 1m0s
[23:07:08.238] Received PubSub notification, asking Buildbucket for the build status
[23:07:08.338] Build 8899952883228608384: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[23:07:08.338] Buildbucket build:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary": true,
"canary_preference": "CANARY",
"completed_ts": "1570748827143469",
"created_by": "project:emscripten-releases",
"created_ts": "1570740540247331",
"id": "8899952883228608384",
"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\": \"7ec86f994cbbc52d150f425ce20c2e2259679297\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@7ec86f994cbbc52d150f425ce20c2e2259679297\", \"title\": \"7ec86f994cbbc52d150f425ce20c2e2259679297\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/7ec86f994cbbc52d150f425ce20c2e2259679297\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"7ec86f994cbbc52d150f425ce20c2e2259679297\"}}",
"project": "emscripten-releases",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$recipe_engine/path\": {\"cache_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\cache\", \"temp_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\tmp\\\\rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"7ec86f994cbbc52d150f425ce20c2e2259679297\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@7ec86f994cbbc52d150f425ce20c2e2259679297\", \"title\": \"7ec86f994cbbc52d150f425ce20c2e2259679297\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/7ec86f994cbbc52d150f425ce20c2e2259679297\"}]}, \"bot_id\": \"luci-emscripten-releases-ci-win10-0-0kjg\", \"branch\": \"refs/heads/master\", \"buildername\": \"win\", \"got_revision\": \"7ec86f994cbbc52d150f425ce20c2e2259679297\", \"path_config\": \"generic\", \"recipe\": \"emscripten_releases\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"7ec86f994cbbc52d150f425ce20c2e2259679297\"}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"git\", \"goma_v2\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"host_class\": [\"default\"], \"id\": [\"luci-emscripten-releases-ci-win10-0-0kjg\"], \"image\": [\"chrome-win10-1703-19091300-a244b1e46d2\"], \"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\": [\"4501-487d554\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-b\"]}}}",
"service_account": "emscripten-releases-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1570744955992099",
"status": "COMPLETED",
"status_changed_ts": "1570748827665188",
"tags": [
"builder:win",
"buildset:commit/git/7ec86f994cbbc52d150f425ce20c2e2259679297",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/7ec86f994cbbc52d150f425ce20c2e2259679297",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9065382945861419728",
"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/8899952883228608384/+/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:47d0407935001f10",
"user_agent:luci-scheduler"
],
"updated_ts": "1570748827689018",
"url": "https://ci.chromium.org/b/8899952883228608384",
"utcnow_ts": "1570748828318523"
}
}
[23:07:08.338] Invocation finished in 2h18m9.986543752s with status SUCCEEDED