[01:39:57.202] New invocation is queued and will start shortly
[01:39:58.709] Starting the invocation (attempt 1)
[01:39:58.763] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[01:39:58.763] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[01:39:58.764] Buildbucket request:
{
"bucket": "luci.emscripten-releases.ci",
"client_operation_id": "9056486136243305632",
"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\":\"9d669e85b5b431b45ba2128d0982add2dad2349c\"},\"id\":\"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@9d669e85b5b431b45ba2128d0982add2dad2349c\",\"title\":\"9d669e85b5b431b45ba2128d0982add2dad2349c\",\"url\":\"https://chromium.googlesource.com/emscripten-releases/+/9d669e85b5b431b45ba2128d0982add2dad2349c\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/emscripten-releases\",\"revision\":\"9d669e85b5b431b45ba2128d0982add2dad2349c\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win",
"scheduler_invocation_id:9056486136243305632",
"scheduler_job_id:emscripten-releases/win",
"user_agent:luci-scheduler",
"buildset:commit/git/9d669e85b5b431b45ba2128d0982add2dad2349c",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/9d669e85b5b431b45ba2128d0982add2dad2349c",
"gitiles_ref:refs/heads/master"
]
}
[01:39:59.371] Buildbucket response:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary_preference": "PROD",
"created_by": "project:emscripten-releases",
"created_ts": "1579225198923567",
"id": "8891056073772139760",
"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\": \"9d669e85b5b431b45ba2128d0982add2dad2349c\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@9d669e85b5b431b45ba2128d0982add2dad2349c\", \"title\": \"9d669e85b5b431b45ba2128d0982add2dad2349c\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/9d669e85b5b431b45ba2128d0982add2dad2349c\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"9d669e85b5b431b45ba2128d0982add2dad2349c\"}}",
"project": "emscripten-releases",
"result_details_json": "{\"properties\": {}}",
"service_account": "emscripten-releases-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1579225199188076",
"tags": [
"builder:win",
"buildset:commit/git/9d669e85b5b431b45ba2128d0982add2dad2349c",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/9d669e85b5b431b45ba2128d0982add2dad2349c",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9056486136243305632",
"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/8891056073772139760/+/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": "1579225199188230",
"url": "https://ci.chromium.org/b/8891056073772139760",
"utcnow_ts": "1579225199364473"
}
}
[01:39:59.371] Task URL: https://ci.chromium.org/b/8891056073772139760
[01:39:59.371] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:2:0) after 1m0s
[01:40:59.398] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:2:0)
[01:40:59.398] Timer tick, asking Buildbucket for the build status
[01:40:59.576] Build 8891056073772139760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:40:59.576] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:3:0) after 1m0s
[01:41:59.662] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:3:0)
[01:41:59.662] Timer tick, asking Buildbucket for the build status
[01:41:59.787] Build 8891056073772139760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:41:59.787] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:4:0) after 1m0s
[01:42:59.792] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:4:0)
[01:42:59.792] Timer tick, asking Buildbucket for the build status
[01:42:59.911] Build 8891056073772139760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:42:59.911] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:5:0) after 1m0s
[01:43:59.919] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:5:0)
[01:43:59.919] Timer tick, asking Buildbucket for the build status
[01:44:00.339] Build 8891056073772139760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:44:00.339] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:6:0) after 1m0s
[01:45:00.393] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:6:0)
[01:45:00.393] Timer tick, asking Buildbucket for the build status
[01:45:00.562] Build 8891056073772139760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:45:00.562] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:7:0) after 1m0s
[01:46:00.787] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:7:0)
[01:46:00.787] Timer tick, asking Buildbucket for the build status
[01:46:00.912] Build 8891056073772139760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:46:00.912] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:8:0) after 1m0s
[01:47:01.102] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:8:0)
[01:47:01.102] Timer tick, asking Buildbucket for the build status
[01:47:01.467] Build 8891056073772139760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:47:01.467] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:9:0) after 1m0s
[01:48:01.487] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:9:0)
[01:48:01.487] Timer tick, asking Buildbucket for the build status
[01:48:01.643] Build 8891056073772139760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:48:01.643] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:10:0) after 1m0s
[01:49:01.727] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:10:0)
[01:49:01.727] Timer tick, asking Buildbucket for the build status
[01:49:01.893] Build 8891056073772139760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:49:01.893] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:11:0) after 1m0s
[01:50:02.033] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:11:0)
[01:50:02.033] Timer tick, asking Buildbucket for the build status
[01:50:02.547] Build 8891056073772139760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:50:02.547] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:12:0) after 1m0s
[01:51:02.780] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:12:0)
[01:51:02.780] Timer tick, asking Buildbucket for the build status
[01:51:02.904] Build 8891056073772139760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:51:02.904] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:13:0) after 1m0s
[01:52:02.940] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:13:0)
[01:52:02.940] Timer tick, asking Buildbucket for the build status
[01:52:03.115] Build 8891056073772139760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:52:03.115] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:14:0) after 1m0s
[01:53:03.247] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:14:0)
[01:53:03.247] Timer tick, asking Buildbucket for the build status
[01:53:03.585] Build 8891056073772139760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:53:03.585] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:15:0) after 1m0s
[01:54:03.727] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:15:0)
[01:54:03.727] Timer tick, asking Buildbucket for the build status
[01:54:03.910] Build 8891056073772139760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:54:03.911] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:16:0) after 1m0s
[01:55:03.919] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:16:0)
[01:55:03.919] Timer tick, asking Buildbucket for the build status
[01:55:04.103] Build 8891056073772139760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:55:04.103] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:17:0) after 1m0s
[01:56:04.163] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:17:0)
[01:56:04.163] Timer tick, asking Buildbucket for the build status
[01:56:04.546] Build 8891056073772139760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:56:04.546] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:18:0) after 1m0s
[01:57:04.593] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:18:0)
[01:57:04.593] Timer tick, asking Buildbucket for the build status
[01:57:04.836] Build 8891056073772139760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:57:04.836] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:19:0) after 1m0s
[01:58:05.042] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:19:0)
[01:58:05.042] Timer tick, asking Buildbucket for the build status
[01:58:05.188] Build 8891056073772139760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:58:05.188] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:20:0) after 1m0s
[01:59:05.352] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:20:0)
[01:59:05.352] Timer tick, asking Buildbucket for the build status
[01:59:05.579] Build 8891056073772139760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:59:05.579] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:21:0) after 1m0s
[02:00:05.697] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:21:0)
[02:00:05.697] Timer tick, asking Buildbucket for the build status
[02:00:05.843] Build 8891056073772139760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:00:05.843] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:22:0) after 1m0s
[02:01:06.176] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:22:0)
[02:01:06.176] Timer tick, asking Buildbucket for the build status
[02:01:06.281] Build 8891056073772139760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:01:06.281] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:23:0) after 1m0s
[02:02:06.475] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:23:0)
[02:02:06.475] Timer tick, asking Buildbucket for the build status
[02:02:06.569] Build 8891056073772139760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:02:06.569] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:24:0) after 1m0s
[02:03:06.713] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:24:0)
[02:03:06.713] Timer tick, asking Buildbucket for the build status
[02:03:06.886] Build 8891056073772139760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:03:06.886] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:25:0) after 1m0s
[02:04:06.906] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:25:0)
[02:04:06.906] Timer tick, asking Buildbucket for the build status
[02:04:07.007] Build 8891056073772139760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:04:07.007] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:26:0) after 1m0s
[02:05:07.123] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:26:0)
[02:05:07.123] Timer tick, asking Buildbucket for the build status
[02:05:07.429] Build 8891056073772139760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:05:07.429] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:27:0) after 1m0s
[02:06:07.487] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:27:0)
[02:06:07.487] Timer tick, asking Buildbucket for the build status
[02:06:07.580] Build 8891056073772139760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:06:07.580] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:28:0) after 1m0s
[02:07:07.691] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:28:0)
[02:07:07.691] Timer tick, asking Buildbucket for the build status
[02:07:07.795] Build 8891056073772139760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:07:07.795] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:29:0) after 1m0s
[02:08:07.808] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:29:0)
[02:08:07.808] Timer tick, asking Buildbucket for the build status
[02:08:07.943] Build 8891056073772139760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:08:07.943] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:30:0) after 1m0s
[02:09:08.045] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:30:0)
[02:09:08.045] Timer tick, asking Buildbucket for the build status
[02:09:08.283] Build 8891056073772139760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:09:08.283] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:31:0) after 1m0s
[02:10:08.287] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:31:0)
[02:10:08.287] Timer tick, asking Buildbucket for the build status
[02:10:08.437] Build 8891056073772139760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:10:08.437] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:32:0) after 1m0s
[02:11:08.481] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:32:0)
[02:11:08.481] Timer tick, asking Buildbucket for the build status
[02:11:08.597] Build 8891056073772139760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:11:08.597] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:33:0) after 1m0s
[02:12:08.700] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:33:0)
[02:12:08.700] Timer tick, asking Buildbucket for the build status
[02:12:08.794] Build 8891056073772139760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:12:08.794] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:34:0) after 1m0s
[02:13:08.875] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:34:0)
[02:13:08.876] Timer tick, asking Buildbucket for the build status
[02:13:09.063] Build 8891056073772139760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:13:09.063] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:35:0) after 1m0s
[02:14:09.136] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:35:0)
[02:14:09.136] Timer tick, asking Buildbucket for the build status
[02:14:09.274] Build 8891056073772139760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:14:09.274] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:36:0) after 1m0s
[02:15:09.357] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:36:0)
[02:15:09.357] Timer tick, asking Buildbucket for the build status
[02:15:09.462] Build 8891056073772139760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:15:09.462] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:37:0) after 1m0s
[02:16:09.527] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:37:0)
[02:16:09.527] Timer tick, asking Buildbucket for the build status
[02:16:09.618] Build 8891056073772139760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:16:09.618] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:38:0) after 1m0s
[02:17:09.683] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:38:0)
[02:17:09.683] Timer tick, asking Buildbucket for the build status
[02:17:10.078] Build 8891056073772139760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:17:10.078] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:39:0) after 1m0s
[02:18:10.151] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:39:0)
[02:18:10.152] Timer tick, asking Buildbucket for the build status
[02:18:10.363] Build 8891056073772139760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:18:10.363] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:40:0) after 1m0s
[02:19:10.434] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:40:0)
[02:19:10.434] Timer tick, asking Buildbucket for the build status
[02:19:10.855] Build 8891056073772139760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:19:10.855] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:41:0) after 1m0s
[02:20:10.830] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:41:0)
[02:20:10.830] Timer tick, asking Buildbucket for the build status
[02:20:10.944] Build 8891056073772139760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:20:10.944] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:42:0) after 1m0s
[02:21:10.979] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:42:0)
[02:21:10.979] Timer tick, asking Buildbucket for the build status
[02:21:11.115] Build 8891056073772139760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:21:11.115] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:43:0) after 1m0s
[02:22:11.185] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:43:0)
[02:22:11.185] Timer tick, asking Buildbucket for the build status
[02:22:11.299] Build 8891056073772139760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:22:11.299] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:44:0) after 1m0s
[02:23:11.389] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:44:0)
[02:23:11.389] Timer tick, asking Buildbucket for the build status
[02:23:11.689] Build 8891056073772139760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:23:11.689] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:45:0) after 1m0s
[02:24:11.834] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:45:0)
[02:24:11.834] Timer tick, asking Buildbucket for the build status
[02:24:12.165] Build 8891056073772139760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:24:12.165] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:46:0) after 1m0s
[02:25:12.249] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:46:0)
[02:25:12.249] Timer tick, asking Buildbucket for the build status
[02:25:12.342] Build 8891056073772139760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:25:12.342] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:47:0) after 1m0s
[02:26:12.421] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:47:0)
[02:26:12.421] Timer tick, asking Buildbucket for the build status
[02:26:12.639] Build 8891056073772139760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:26:12.639] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:48:0) after 1m0s
[02:27:12.781] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:48:0)
[02:27:12.781] Timer tick, asking Buildbucket for the build status
[02:27:12.881] Build 8891056073772139760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:27:12.881] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:49:0) after 1m0s
[02:28:12.947] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:49:0)
[02:28:12.947] Timer tick, asking Buildbucket for the build status
[02:28:13.104] Build 8891056073772139760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:28:13.104] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:50:0) after 1m0s
[02:29:13.181] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:50:0)
[02:29:13.181] Timer tick, asking Buildbucket for the build status
[02:29:13.648] Build 8891056073772139760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:29:13.648] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:51:0) after 1m0s
[02:30:13.664] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:51:0)
[02:30:13.664] Timer tick, asking Buildbucket for the build status
[02:30:14.039] Build 8891056073772139760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:30:14.039] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:52:0) after 1m0s
[02:31:14.088] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:52:0)
[02:31:14.088] Timer tick, asking Buildbucket for the build status
[02:31:14.202] Build 8891056073772139760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:31:14.202] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:53:0) after 1m0s
[02:32:14.260] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:53:0)
[02:32:14.260] Timer tick, asking Buildbucket for the build status
[02:32:14.495] Build 8891056073772139760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:32:14.495] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:54:0) after 1m0s
[02:33:14.483] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:54:0)
[02:33:14.483] Timer tick, asking Buildbucket for the build status
[02:33:14.618] Build 8891056073772139760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:33:14.618] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:55:0) after 1m0s
[02:34:14.648] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:55:0)
[02:34:14.648] Timer tick, asking Buildbucket for the build status
[02:34:14.801] Build 8891056073772139760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:34:14.801] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:56:0) after 1m0s
[02:35:14.955] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:56:0)
[02:35:14.955] Timer tick, asking Buildbucket for the build status
[02:35:15.046] Build 8891056073772139760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:35:15.046] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:57:0) after 1m0s
[02:36:15.195] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:57:0)
[02:36:15.195] Timer tick, asking Buildbucket for the build status
[02:36:15.521] Build 8891056073772139760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:36:15.521] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:58:0) after 1m0s
[02:37:15.581] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:58:0)
[02:37:15.581] Timer tick, asking Buildbucket for the build status
[02:37:15.667] Build 8891056073772139760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:37:15.667] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:59:0) after 1m0s
[02:38:15.692] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:59:0)
[02:38:15.692] Timer tick, asking Buildbucket for the build status
[02:38:15.835] Build 8891056073772139760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:38:15.835] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:60:0) after 1m0s
[02:39:15.976] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:60:0)
[02:39:15.976] Timer tick, asking Buildbucket for the build status
[02:39:16.162] Build 8891056073772139760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:39:16.162] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:61:0) after 1m0s
[02:40:16.263] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:61:0)
[02:40:16.263] Timer tick, asking Buildbucket for the build status
[02:40:16.373] Build 8891056073772139760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:40:16.373] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:62:0) after 1m0s
[02:41:16.443] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:62:0)
[02:41:16.443] Timer tick, asking Buildbucket for the build status
[02:41:16.543] Build 8891056073772139760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:41:16.543] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:63:0) after 1m0s
[02:42:16.738] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:63:0)
[02:42:16.738] Timer tick, asking Buildbucket for the build status
[02:42:16.883] Build 8891056073772139760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:42:16.883] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:64:0) after 1m0s
[02:43:16.992] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:64:0)
[02:43:16.992] Timer tick, asking Buildbucket for the build status
[02:43:17.116] Build 8891056073772139760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:43:17.116] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:65:0) after 1m0s
[02:44:17.216] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:65:0)
[02:44:17.216] Timer tick, asking Buildbucket for the build status
[02:44:17.675] Build 8891056073772139760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:44:17.675] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:66:0) after 1m0s
[02:45:17.746] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:66:0)
[02:45:17.746] Timer tick, asking Buildbucket for the build status
[02:45:17.897] Build 8891056073772139760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:45:17.897] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:67:0) after 1m0s
[02:46:17.967] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:67:0)
[02:46:17.967] Timer tick, asking Buildbucket for the build status
[02:46:18.195] Build 8891056073772139760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:46:18.195] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:68:0) after 1m0s
[02:47:18.258] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:68:0)
[02:47:18.258] Timer tick, asking Buildbucket for the build status
[02:47:18.358] Build 8891056073772139760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:47:18.358] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:69:0) after 1m0s
[02:48:18.437] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:69:0)
[02:48:18.437] Timer tick, asking Buildbucket for the build status
[02:48:18.771] Build 8891056073772139760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:48:18.771] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:70:0) after 1m0s
[02:49:18.844] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:70:0)
[02:49:18.844] Timer tick, asking Buildbucket for the build status
[02:49:18.949] Build 8891056073772139760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:49:18.949] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:71:0) after 1m0s
[02:50:19.185] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:71:0)
[02:50:19.185] Timer tick, asking Buildbucket for the build status
[02:50:19.307] Build 8891056073772139760: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:50:19.307] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:72:0) after 1m0s
[02:50:24.826] Received PubSub notification, asking Buildbucket for the build status
[02:50:25.177] Build 8891056073772139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:51:19.327] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:72:0)
[02:51:19.327] Timer tick, asking Buildbucket for the build status
[02:51:19.419] Build 8891056073772139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:51:19.419] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:74:0) after 1m0s
[02:52:19.601] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:74:0)
[02:52:19.601] Timer tick, asking Buildbucket for the build status
[02:52:19.711] Build 8891056073772139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:52:19.711] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:75:0) after 1m0s
[02:53:19.767] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:75:0)
[02:53:19.767] Timer tick, asking Buildbucket for the build status
[02:53:20.039] Build 8891056073772139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:53:20.039] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:76:0) after 1m0s
[02:54:20.141] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:76:0)
[02:54:20.142] Timer tick, asking Buildbucket for the build status
[02:54:20.381] Build 8891056073772139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:54:20.381] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:77:0) after 1m0s
[02:55:20.405] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:77:0)
[02:55:20.405] Timer tick, asking Buildbucket for the build status
[02:55:20.774] Build 8891056073772139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:55:20.774] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:78:0) after 1m0s
[02:56:20.923] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:78:0)
[02:56:20.923] Timer tick, asking Buildbucket for the build status
[02:56:21.139] Build 8891056073772139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:56:21.139] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:79:0) after 1m0s
[02:57:21.175] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:79:0)
[02:57:21.175] Timer tick, asking Buildbucket for the build status
[02:57:21.562] Build 8891056073772139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:57:21.562] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:80:0) after 1m0s
[02:58:21.660] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:80:0)
[02:58:21.660] Timer tick, asking Buildbucket for the build status
[02:58:21.758] Build 8891056073772139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:58:21.758] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:81:0) after 1m0s
[02:59:21.817] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:81:0)
[02:59:21.817] Timer tick, asking Buildbucket for the build status
[02:59:22.149] Build 8891056073772139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:59:22.149] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:82:0) after 1m0s
[03:00:22.407] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:82:0)
[03:00:22.407] Timer tick, asking Buildbucket for the build status
[03:00:22.677] Build 8891056073772139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:00:22.677] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:83:0) after 1m0s
[03:01:22.697] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:83:0)
[03:01:22.697] Timer tick, asking Buildbucket for the build status
[03:01:22.964] Build 8891056073772139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:01:22.964] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:84:0) after 1m0s
[03:02:23.008] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:84:0)
[03:02:23.008] Timer tick, asking Buildbucket for the build status
[03:02:23.756] Build 8891056073772139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:02:23.756] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:85:0) after 1m0s
[03:03:23.747] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:85:0)
[03:03:23.747] Timer tick, asking Buildbucket for the build status
[03:03:24.031] Build 8891056073772139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:03:24.031] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:86:0) after 1m0s
[03:04:24.147] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:86:0)
[03:04:24.147] Timer tick, asking Buildbucket for the build status
[03:04:24.338] Build 8891056073772139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:04:24.338] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:87:0) after 1m0s
[03:05:24.452] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:87:0)
[03:05:24.452] Timer tick, asking Buildbucket for the build status
[03:05:24.892] Build 8891056073772139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:05:24.892] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:88:0) after 1m0s
[03:06:24.919] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:88:0)
[03:06:24.919] Timer tick, asking Buildbucket for the build status
[03:06:25.106] Build 8891056073772139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:06:25.106] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:89:0) after 1m0s
[03:07:25.268] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:89:0)
[03:07:25.268] Timer tick, asking Buildbucket for the build status
[03:07:25.613] Build 8891056073772139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:07:25.613] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:90:0) after 1m0s
[03:08:25.723] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:90:0)
[03:08:25.723] Timer tick, asking Buildbucket for the build status
[03:08:25.829] Build 8891056073772139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:08:25.829] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:91:0) after 1m0s
[03:09:25.887] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:91:0)
[03:09:25.887] Timer tick, asking Buildbucket for the build status
[03:09:26.068] Build 8891056073772139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:09:26.068] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:92:0) after 1m0s
[03:10:26.271] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:92:0)
[03:10:26.271] Timer tick, asking Buildbucket for the build status
[03:10:26.577] Build 8891056073772139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:10:26.577] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:93:0) after 1m0s
[03:11:26.840] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:93:0)
[03:11:26.840] Timer tick, asking Buildbucket for the build status
[03:11:26.958] Build 8891056073772139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:11:26.958] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:94:0) after 1m0s
[03:12:27.169] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:94:0)
[03:12:27.169] Timer tick, asking Buildbucket for the build status
[03:12:27.499] Build 8891056073772139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:12:27.499] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:95:0) after 1m0s
[03:13:27.601] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:95:0)
[03:13:27.601] Timer tick, asking Buildbucket for the build status
[03:13:28.121] Build 8891056073772139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:13:28.121] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:96:0) after 1m0s
[03:14:28.202] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:96:0)
[03:14:28.202] Timer tick, asking Buildbucket for the build status
[03:14:29.927] Build 8891056073772139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:14:29.927] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:97:0) after 1m0s
[03:15:30.007] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:97:0)
[03:15:30.007] Timer tick, asking Buildbucket for the build status
[03:15:30.484] Build 8891056073772139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:15:30.484] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:98:0) after 1m0s
[03:16:30.590] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:98:0)
[03:16:30.590] Timer tick, asking Buildbucket for the build status
[03:16:30.880] Build 8891056073772139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:16:30.880] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:99:0) after 1m0s
[03:17:30.951] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:99:0)
[03:17:30.951] Timer tick, asking Buildbucket for the build status
[03:17:31.264] Build 8891056073772139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:17:31.264] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:100:0) after 1m0s
[03:18:31.250] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:100:0)
[03:18:31.250] Timer tick, asking Buildbucket for the build status
[03:18:31.569] Build 8891056073772139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:18:31.569] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:101:0) after 1m0s
[03:19:31.778] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:101:0)
[03:19:31.778] Timer tick, asking Buildbucket for the build status
[03:19:32.315] Build 8891056073772139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:19:32.315] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:102:0) after 1m0s
[03:20:32.586] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:102:0)
[03:20:32.586] Timer tick, asking Buildbucket for the build status
[03:20:32.702] Build 8891056073772139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:20:32.702] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:103:0) after 1m0s
[03:21:33.109] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:103:0)
[03:21:33.109] Timer tick, asking Buildbucket for the build status
[03:21:33.306] Build 8891056073772139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:21:33.306] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:104:0) after 1m0s
[03:22:33.378] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:104:0)
[03:22:33.378] Timer tick, asking Buildbucket for the build status
[03:22:33.665] Build 8891056073772139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:22:33.665] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:105:0) after 1m0s
[03:23:33.730] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:105:0)
[03:23:33.730] Timer tick, asking Buildbucket for the build status
[03:23:34.042] Build 8891056073772139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:23:34.042] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:106:0) after 1m0s
[03:24:34.219] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:106:0)
[03:24:34.219] Timer tick, asking Buildbucket for the build status
[03:24:34.337] Build 8891056073772139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:24:34.337] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:107:0) after 1m0s
[03:25:34.425] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:107:0)
[03:25:34.425] Timer tick, asking Buildbucket for the build status
[03:25:34.544] Build 8891056073772139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:25:34.544] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:108:0) after 1m0s
[03:26:34.676] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:108:0)
[03:26:34.676] Timer tick, asking Buildbucket for the build status
[03:26:34.792] Build 8891056073772139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:26:34.793] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:109:0) after 1m0s
[03:27:34.868] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:109:0)
[03:27:34.868] Timer tick, asking Buildbucket for the build status
[03:27:34.977] Build 8891056073772139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:27:34.977] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:110:0) after 1m0s
[03:28:35.058] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:110:0)
[03:28:35.058] Timer tick, asking Buildbucket for the build status
[03:28:35.182] Build 8891056073772139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:28:35.182] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:111:0) after 1m0s
[03:29:35.212] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:111:0)
[03:29:35.212] Timer tick, asking Buildbucket for the build status
[03:29:35.410] Build 8891056073772139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:29:35.410] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:112:0) after 1m0s
[03:30:35.604] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:112:0)
[03:30:35.604] Timer tick, asking Buildbucket for the build status
[03:30:35.963] Build 8891056073772139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:30:35.963] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:113:0) after 1m0s
[03:31:36.014] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:113:0)
[03:31:36.014] Timer tick, asking Buildbucket for the build status
[03:31:36.257] Build 8891056073772139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:31:36.257] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:114:0) after 1m0s
[03:32:36.486] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:114:0)
[03:32:36.486] Timer tick, asking Buildbucket for the build status
[03:32:36.732] Build 8891056073772139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:32:36.732] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:115:0) after 1m0s
[03:33:36.802] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:115:0)
[03:33:36.802] Timer tick, asking Buildbucket for the build status
[03:33:37.376] Build 8891056073772139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:33:37.376] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:116:0) after 1m0s
[03:34:37.461] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:116:0)
[03:34:37.461] Timer tick, asking Buildbucket for the build status
[03:34:37.613] Build 8891056073772139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:34:37.613] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:117:0) after 1m0s
[03:35:37.670] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:117:0)
[03:35:37.670] Timer tick, asking Buildbucket for the build status
[03:35:37.951] Build 8891056073772139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:35:37.951] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:118:0) after 1m0s
[03:36:38.090] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:118:0)
[03:36:38.090] Timer tick, asking Buildbucket for the build status
[03:36:38.207] Build 8891056073772139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:36:38.207] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:119:0) after 1m0s
[03:37:38.368] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:119:0)
[03:37:38.368] Timer tick, asking Buildbucket for the build status
[03:37:38.785] Build 8891056073772139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:37:38.785] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:120:0) after 1m0s
[03:38:38.967] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:120:0)
[03:38:38.967] Timer tick, asking Buildbucket for the build status
[03:38:39.335] Build 8891056073772139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:38:39.335] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:121:0) after 1m0s
[03:39:39.482] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:121:0)
[03:39:39.482] Timer tick, asking Buildbucket for the build status
[03:39:39.987] Build 8891056073772139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:39:39.987] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:122:0) after 1m0s
[03:40:40.279] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:122:0)
[03:40:40.280] Timer tick, asking Buildbucket for the build status
[03:40:40.477] Build 8891056073772139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:40:40.477] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:123:0) after 1m0s
[03:41:40.553] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:123:0)
[03:41:40.554] Timer tick, asking Buildbucket for the build status
[03:41:41.016] Build 8891056073772139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:41:41.016] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:124:0) after 1m0s
[03:42:41.091] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:124:0)
[03:42:41.091] Timer tick, asking Buildbucket for the build status
[03:42:41.247] Build 8891056073772139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:42:41.247] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:125:0) after 1m0s
[03:43:41.420] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:125:0)
[03:43:41.420] Timer tick, asking Buildbucket for the build status
[03:43:41.623] Build 8891056073772139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:43:41.623] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:126:0) after 1m0s
[03:44:41.680] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:126:0)
[03:44:41.680] Timer tick, asking Buildbucket for the build status
[03:44:41.867] Build 8891056073772139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:44:41.867] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:127:0) after 1m0s
[03:45:42.019] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:127:0)
[03:45:42.019] Timer tick, asking Buildbucket for the build status
[03:45:42.459] Build 8891056073772139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:45:42.459] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:128:0) after 1m0s
[03:46:42.491] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:128:0)
[03:46:42.492] Timer tick, asking Buildbucket for the build status
[03:46:42.661] Build 8891056073772139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:46:42.661] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:129:0) after 1m0s
[03:47:42.950] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:129:0)
[03:47:42.950] Timer tick, asking Buildbucket for the build status
[03:47:43.073] Build 8891056073772139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:47:43.073] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:130:0) after 1m0s
[03:48:43.134] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:130:0)
[03:48:43.134] Timer tick, asking Buildbucket for the build status
[03:48:43.257] Build 8891056073772139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:48:43.257] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:131:0) after 1m0s
[03:49:43.292] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:131:0)
[03:49:43.292] Timer tick, asking Buildbucket for the build status
[03:49:43.416] Build 8891056073772139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:49:43.416] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:132:0) after 1m0s
[03:50:43.451] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:132:0)
[03:50:43.451] Timer tick, asking Buildbucket for the build status
[03:50:43.553] Build 8891056073772139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:50:43.553] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:133:0) after 1m0s
[03:51:43.608] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:133:0)
[03:51:43.608] Timer tick, asking Buildbucket for the build status
[03:51:43.742] Build 8891056073772139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:51:43.742] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:134:0) after 1m0s
[03:52:43.759] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:134:0)
[03:52:43.759] Timer tick, asking Buildbucket for the build status
[03:52:43.873] Build 8891056073772139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:52:43.873] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:135:0) after 1m0s
[03:53:43.905] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:135:0)
[03:53:43.905] Timer tick, asking Buildbucket for the build status
[03:53:44.241] Build 8891056073772139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:53:44.241] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:136:0) after 1m0s
[03:54:44.388] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:136:0)
[03:54:44.388] Timer tick, asking Buildbucket for the build status
[03:54:44.639] Build 8891056073772139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:54:44.639] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:137:0) after 1m0s
[03:55:44.667] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:137:0)
[03:55:44.667] Timer tick, asking Buildbucket for the build status
[03:55:44.839] Build 8891056073772139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:55:44.839] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:138:0) after 1m0s
[03:56:44.873] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:138:0)
[03:56:44.874] Timer tick, asking Buildbucket for the build status
[03:56:44.984] Build 8891056073772139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:56:44.984] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:139:0) after 1m0s
[03:57:45.118] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:139:0)
[03:57:45.118] Timer tick, asking Buildbucket for the build status
[03:57:45.377] Build 8891056073772139760: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:57:45.377] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9056486136243305632:140:0) after 1m0s
[03:58:14.890] Received PubSub notification, asking Buildbucket for the build status
[03:58:14.988] Build 8891056073772139760: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[03:58:14.988] Buildbucket build:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary_preference": "PROD",
"completed_ts": "1579233493916323",
"created_by": "project:emscripten-releases",
"created_ts": "1579225198923567",
"id": "8891056073772139760",
"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\": \"9d669e85b5b431b45ba2128d0982add2dad2349c\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@9d669e85b5b431b45ba2128d0982add2dad2349c\", \"title\": \"9d669e85b5b431b45ba2128d0982add2dad2349c\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/9d669e85b5b431b45ba2128d0982add2dad2349c\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"9d669e85b5b431b45ba2128d0982add2dad2349c\"}}",
"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\": \"9d669e85b5b431b45ba2128d0982add2dad2349c\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@9d669e85b5b431b45ba2128d0982add2dad2349c\", \"title\": \"9d669e85b5b431b45ba2128d0982add2dad2349c\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/9d669e85b5b431b45ba2128d0982add2dad2349c\"}]}, \"bot_id\": \"luci-emscripten-releases-ci-win10-0-dxq9\", \"branch\": \"refs/heads/master\", \"buildername\": \"win\", \"got_revision\": \"9d669e85b5b431b45ba2128d0982add2dad2349c\", \"path_config\": \"generic\", \"recipe\": \"emscripten_releases\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"9d669e85b5b431b45ba2128d0982add2dad2349c\"}, \"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-dxq9\"], \"image\": [\"chrome-win10-1703-19112100-3085293b610\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"n1-standard-8\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.emscripten-releases.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"4760-d9a0e6d\"], \"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": "1579229423823901",
"status": "COMPLETED",
"status_changed_ts": "1579233494402585",
"tags": [
"builder:win",
"buildset:commit/git/9d669e85b5b431b45ba2128d0982add2dad2349c",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/9d669e85b5b431b45ba2128d0982add2dad2349c",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9056486136243305632",
"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/8891056073772139760/+/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:49c9f9bc03e45210",
"user_agent:luci-scheduler"
],
"updated_ts": "1579233494411267",
"url": "https://ci.chromium.org/b/8891056073772139760",
"utcnow_ts": "1579233494981448"
}
}
[03:58:14.988] Invocation finished in 2h18m17.805124595s with status SUCCEEDED