[12:09:40.714] New invocation is queued and will start shortly
[12:09:41.783] Starting the invocation (attempt 1)
[12:09:41.816] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[12:09:41.816] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[12:09:41.816] Buildbucket request:
{
"bucket": "luci.emscripten-releases.ci",
"client_operation_id": "9078914565022694640",
"parameters_json": "{\"builder_name\":\"win\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/emscripten-releases\",\"revision\":\"779d493c49e77aca6a948bdadddf896a680580ed\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win",
"scheduler_invocation_id:9078914565022694640",
"scheduler_job_id:emscripten-releases/win",
"user_agent:luci-scheduler",
"buildset:commit/git/779d493c49e77aca6a948bdadddf896a680580ed",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/779d493c49e77aca6a948bdadddf896a680580ed",
"gitiles_ref:refs/heads/master"
]
}
[12:09:42.574] Buildbucket response:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary_preference": "AUTO",
"created_by": "project:emscripten-releases",
"created_ts": "1557835782001603",
"id": "8913484503011190096",
"parameters_json": "{\"builder_name\": \"win\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"779d493c49e77aca6a948bdadddf896a680580ed\"}}",
"project": "emscripten-releases",
"result_details_json": "{\"properties\": {}}",
"service_account": "emscripten-releases-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1557835782427787",
"tags": [
"builder:win",
"buildset:commit/git/779d493c49e77aca6a948bdadddf896a680580ed",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/779d493c49e77aca6a948bdadddf896a680580ed",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9078914565022694640",
"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/8913484503011190096/+/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": "1557835782427942",
"url": "https://ci.chromium.org/b/8913484503011190096",
"utcnow_ts": "1557835782566532"
}
}
[12:09:42.574] Task URL: https://ci.chromium.org/b/8913484503011190096
[12:09:42.574] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:2:0) after 1m0s
[12:10:42.572] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:2:0)
[12:10:42.572] Timer tick, asking Buildbucket for the build status
[12:10:43.418] Build 8913484503011190096: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:10:43.418] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:3:0) after 1m0s
[12:11:43.435] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:3:0)
[12:11:43.435] Timer tick, asking Buildbucket for the build status
[12:11:43.502] Build 8913484503011190096: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:11:43.502] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:4:0) after 1m0s
[12:12:43.483] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:4:0)
[12:12:43.483] Timer tick, asking Buildbucket for the build status
[12:12:44.151] Build 8913484503011190096: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:12:44.151] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:5:0) after 1m0s
[12:13:44.149] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:5:0)
[12:13:44.149] Timer tick, asking Buildbucket for the build status
[12:13:44.195] Build 8913484503011190096: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:13:44.195] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:6:0) after 1m0s
[12:14:44.176] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:6:0)
[12:14:44.176] Timer tick, asking Buildbucket for the build status
[12:14:44.287] Build 8913484503011190096: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:14:44.287] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:7:0) after 1m0s
[12:15:44.269] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:7:0)
[12:15:44.269] Timer tick, asking Buildbucket for the build status
[12:15:44.359] Build 8913484503011190096: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:15:44.359] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:8:0) after 1m0s
[12:16:44.363] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:8:0)
[12:16:44.363] Timer tick, asking Buildbucket for the build status
[12:16:44.617] Build 8913484503011190096: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:16:44.617] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:9:0) after 1m0s
[12:17:44.646] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:9:0)
[12:17:44.646] Timer tick, asking Buildbucket for the build status
[12:17:44.996] Build 8913484503011190096: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:17:44.996] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:10:0) after 1m0s
[12:18:45.027] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:10:0)
[12:18:45.027] Timer tick, asking Buildbucket for the build status
[12:18:45.145] Build 8913484503011190096: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:18:45.145] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:11:0) after 1m0s
[12:19:45.177] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:11:0)
[12:19:45.177] Timer tick, asking Buildbucket for the build status
[12:19:45.229] Build 8913484503011190096: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:19:45.229] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:12:0) after 1m0s
[12:20:45.230] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:12:0)
[12:20:45.230] Timer tick, asking Buildbucket for the build status
[12:20:45.431] Build 8913484503011190096: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:20:45.431] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:13:0) after 1m0s
[12:21:45.503] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:13:0)
[12:21:45.503] Timer tick, asking Buildbucket for the build status
[12:21:45.633] Build 8913484503011190096: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:21:45.633] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:14:0) after 1m0s
[12:22:45.710] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:14:0)
[12:22:45.710] Timer tick, asking Buildbucket for the build status
[12:22:45.809] Build 8913484503011190096: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:22:45.809] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:15:0) after 1m0s
[12:23:45.946] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:15:0)
[12:23:45.946] Timer tick, asking Buildbucket for the build status
[12:23:46.153] Build 8913484503011190096: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:23:46.153] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:16:0) after 1m0s
[12:24:13.930] Received PubSub notification, asking Buildbucket for the build status
[12:24:14.119] Build 8913484503011190096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:24:46.179] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:16:0)
[12:24:46.179] Timer tick, asking Buildbucket for the build status
[12:24:46.313] Build 8913484503011190096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:24:46.313] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:18:0) after 1m0s
[12:25:46.294] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:18:0)
[12:25:46.294] Timer tick, asking Buildbucket for the build status
[12:25:46.486] Build 8913484503011190096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:25:46.486] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:19:0) after 1m0s
[12:26:46.524] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:19:0)
[12:26:46.524] Timer tick, asking Buildbucket for the build status
[12:26:46.598] Build 8913484503011190096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:26:46.598] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:20:0) after 1m0s
[12:27:46.616] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:20:0)
[12:27:46.616] Timer tick, asking Buildbucket for the build status
[12:27:47.610] Build 8913484503011190096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:27:47.610] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:21:0) after 1m0s
[12:28:47.600] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:21:0)
[12:28:47.600] Timer tick, asking Buildbucket for the build status
[12:28:47.649] Build 8913484503011190096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:28:47.649] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:22:0) after 1m0s
[12:29:47.659] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:22:0)
[12:29:47.659] Timer tick, asking Buildbucket for the build status
[12:29:48.003] Build 8913484503011190096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:29:48.003] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:23:0) after 1m0s
[12:30:47.988] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:23:0)
[12:30:47.988] Timer tick, asking Buildbucket for the build status
[12:30:48.101] Build 8913484503011190096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:30:48.102] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:24:0) after 1m0s
[12:31:48.156] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:24:0)
[12:31:48.156] Timer tick, asking Buildbucket for the build status
[12:31:48.892] Build 8913484503011190096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:31:48.892] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:25:0) after 1m0s
[12:32:48.910] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:25:0)
[12:32:48.910] Timer tick, asking Buildbucket for the build status
[12:32:49.255] Build 8913484503011190096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:32:49.255] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:26:0) after 1m0s
[12:33:49.275] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:26:0)
[12:33:49.275] Timer tick, asking Buildbucket for the build status
[12:33:49.319] Build 8913484503011190096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:33:49.319] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:27:0) after 1m0s
[12:34:49.344] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:27:0)
[12:34:49.344] Timer tick, asking Buildbucket for the build status
[12:34:49.439] Build 8913484503011190096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:34:49.439] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:28:0) after 1m0s
[12:35:49.458] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:28:0)
[12:35:49.458] Timer tick, asking Buildbucket for the build status
[12:35:49.495] Build 8913484503011190096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:35:49.495] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:29:0) after 1m0s
[12:36:50.013] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:29:0)
[12:36:50.013] Timer tick, asking Buildbucket for the build status
[12:36:50.174] Build 8913484503011190096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:36:50.174] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:30:0) after 1m0s
[12:37:50.203] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:30:0)
[12:37:50.203] Timer tick, asking Buildbucket for the build status
[12:37:50.353] Build 8913484503011190096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:37:50.353] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:31:0) after 1m0s
[12:38:50.370] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:31:0)
[12:38:50.370] Timer tick, asking Buildbucket for the build status
[12:38:50.408] Build 8913484503011190096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:38:50.408] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:32:0) after 1m0s
[12:39:50.581] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:32:0)
[12:39:50.581] Timer tick, asking Buildbucket for the build status
[12:39:50.669] Build 8913484503011190096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:39:50.669] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:33:0) after 1m0s
[12:40:50.691] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:33:0)
[12:40:50.691] Timer tick, asking Buildbucket for the build status
[12:40:50.760] Build 8913484503011190096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:40:50.760] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:34:0) after 1m0s
[12:41:50.784] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:34:0)
[12:41:50.784] Timer tick, asking Buildbucket for the build status
[12:41:51.394] Build 8913484503011190096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:41:51.394] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:35:0) after 1m0s
[12:42:51.583] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:35:0)
[12:42:51.583] Timer tick, asking Buildbucket for the build status
[12:42:51.631] Build 8913484503011190096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:42:51.631] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:36:0) after 1m0s
[12:43:51.631] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:36:0)
[12:43:51.631] Timer tick, asking Buildbucket for the build status
[12:43:51.765] Build 8913484503011190096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:43:51.765] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:37:0) after 1m0s
[12:44:51.784] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:37:0)
[12:44:51.784] Timer tick, asking Buildbucket for the build status
[12:44:51.893] Build 8913484503011190096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:44:51.893] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:38:0) after 1m0s
[12:45:51.926] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:38:0)
[12:45:51.926] Timer tick, asking Buildbucket for the build status
[12:45:52.061] Build 8913484503011190096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:45:52.061] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:39:0) after 1m0s
[12:46:52.099] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:39:0)
[12:46:52.100] Timer tick, asking Buildbucket for the build status
[12:46:52.292] Build 8913484503011190096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:46:52.292] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:40:0) after 1m0s
[12:47:52.349] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:40:0)
[12:47:52.349] Timer tick, asking Buildbucket for the build status
[12:47:52.483] Build 8913484503011190096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:47:52.483] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:41:0) after 1m0s
[12:48:52.512] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:41:0)
[12:48:52.512] Timer tick, asking Buildbucket for the build status
[12:48:52.649] Build 8913484503011190096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:48:52.649] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:42:0) after 1m0s
[12:49:52.681] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:42:0)
[12:49:52.681] Timer tick, asking Buildbucket for the build status
[12:49:53.015] Build 8913484503011190096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:49:53.015] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:43:0) after 1m0s
[12:50:53.042] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:43:0)
[12:50:53.042] Timer tick, asking Buildbucket for the build status
[12:50:53.207] Build 8913484503011190096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:50:53.207] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:44:0) after 1m0s
[12:51:53.230] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:44:0)
[12:51:53.230] Timer tick, asking Buildbucket for the build status
[12:51:53.344] Build 8913484503011190096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:51:53.344] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:45:0) after 1m0s
[12:52:54.102] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:45:0)
[12:52:54.102] Timer tick, asking Buildbucket for the build status
[12:52:54.265] Build 8913484503011190096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:52:54.265] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:46:0) after 1m0s
[12:53:54.253] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:46:0)
[12:53:54.253] Timer tick, asking Buildbucket for the build status
[12:53:54.315] Build 8913484503011190096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:53:54.315] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:47:0) after 1m0s
[12:54:54.335] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:47:0)
[12:54:54.335] Timer tick, asking Buildbucket for the build status
[12:54:54.373] Build 8913484503011190096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:54:54.373] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:48:0) after 1m0s
[12:55:54.724] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:48:0)
[12:55:54.724] Timer tick, asking Buildbucket for the build status
[12:55:54.789] Build 8913484503011190096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:55:54.789] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:49:0) after 1m0s
[12:56:54.891] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:49:0)
[12:56:54.891] Timer tick, asking Buildbucket for the build status
[12:56:55.280] Build 8913484503011190096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:56:55.280] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:50:0) after 1m0s
[12:57:55.302] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:50:0)
[12:57:55.302] Timer tick, asking Buildbucket for the build status
[12:57:55.343] Build 8913484503011190096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:57:55.343] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:51:0) after 1m0s
[12:58:55.397] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:51:0)
[12:58:55.398] Timer tick, asking Buildbucket for the build status
[12:58:55.436] Build 8913484503011190096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:58:55.436] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:52:0) after 1m0s
[12:59:55.611] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:52:0)
[12:59:55.611] Timer tick, asking Buildbucket for the build status
[12:59:55.732] Build 8913484503011190096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:59:55.732] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:53:0) after 1m0s
[13:00:55.757] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:53:0)
[13:00:55.757] Timer tick, asking Buildbucket for the build status
[13:00:55.946] Build 8913484503011190096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:00:55.946] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:54:0) after 1m0s
[13:01:56.001] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:54:0)
[13:01:56.001] Timer tick, asking Buildbucket for the build status
[13:01:56.256] Build 8913484503011190096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:01:56.256] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:55:0) after 1m0s
[13:02:56.366] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:55:0)
[13:02:56.366] Timer tick, asking Buildbucket for the build status
[13:02:56.450] Build 8913484503011190096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:02:56.450] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:56:0) after 1m0s
[13:03:56.471] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:56:0)
[13:03:56.471] Timer tick, asking Buildbucket for the build status
[13:03:56.785] Build 8913484503011190096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:03:56.785] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:57:0) after 1m0s
[13:04:56.807] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:57:0)
[13:04:56.807] Timer tick, asking Buildbucket for the build status
[13:04:56.950] Build 8913484503011190096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:04:56.950] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:58:0) after 1m0s
[13:05:56.933] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:58:0)
[13:05:56.933] Timer tick, asking Buildbucket for the build status
[13:05:57.432] Build 8913484503011190096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:05:57.432] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:59:0) after 1m0s
[13:06:57.612] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:59:0)
[13:06:57.612] Timer tick, asking Buildbucket for the build status
[13:06:57.717] Build 8913484503011190096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:06:57.717] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:60:0) after 1m0s
[13:07:57.692] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:60:0)
[13:07:57.692] Timer tick, asking Buildbucket for the build status
[13:07:57.747] Build 8913484503011190096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:07:57.747] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:61:0) after 1m0s
[13:08:57.735] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:61:0)
[13:08:57.735] Timer tick, asking Buildbucket for the build status
[13:08:57.872] Build 8913484503011190096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:08:57.872] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:62:0) after 1m0s
[13:09:57.890] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:62:0)
[13:09:57.890] Timer tick, asking Buildbucket for the build status
[13:09:57.987] Build 8913484503011190096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:09:57.987] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:63:0) after 1m0s
[13:10:58.143] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:63:0)
[13:10:58.143] Timer tick, asking Buildbucket for the build status
[13:10:58.346] Build 8913484503011190096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:10:58.346] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:64:0) after 1m0s
[13:11:58.371] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:64:0)
[13:11:58.371] Timer tick, asking Buildbucket for the build status
[13:11:58.419] Build 8913484503011190096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:11:58.419] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:65:0) after 1m0s
[13:12:59.434] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:65:0)
[13:12:59.434] Timer tick, asking Buildbucket for the build status
[13:12:59.812] Build 8913484503011190096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:12:59.812] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:66:0) after 1m0s
[13:13:59.838] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:66:0)
[13:13:59.838] Timer tick, asking Buildbucket for the build status
[13:13:59.891] Build 8913484503011190096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:13:59.891] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:67:0) after 1m0s
[13:14:59.869] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:67:0)
[13:14:59.869] Timer tick, asking Buildbucket for the build status
[13:15:00.035] Build 8913484503011190096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:15:00.035] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:68:0) after 1m0s
[13:16:00.106] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:68:0)
[13:16:00.106] Timer tick, asking Buildbucket for the build status
[13:16:00.199] Build 8913484503011190096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:16:00.199] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:69:0) after 1m0s
[13:17:00.231] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:69:0)
[13:17:00.231] Timer tick, asking Buildbucket for the build status
[13:17:00.278] Build 8913484503011190096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:17:00.278] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:70:0) after 1m0s
[13:18:00.299] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:70:0)
[13:18:00.299] Timer tick, asking Buildbucket for the build status
[13:18:00.549] Build 8913484503011190096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:18:00.549] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:71:0) after 1m0s
[13:19:00.597] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:71:0)
[13:19:00.597] Timer tick, asking Buildbucket for the build status
[13:19:00.763] Build 8913484503011190096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:19:00.763] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:72:0) after 1m0s
[13:20:00.802] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:72:0)
[13:20:00.802] Timer tick, asking Buildbucket for the build status
[13:20:00.938] Build 8913484503011190096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:20:00.938] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:73:0) after 1m0s
[13:21:00.967] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:73:0)
[13:21:00.967] Timer tick, asking Buildbucket for the build status
[13:21:01.041] Build 8913484503011190096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:21:01.041] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:74:0) after 1m0s
[13:22:01.047] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:74:0)
[13:22:01.047] Timer tick, asking Buildbucket for the build status
[13:22:01.091] Build 8913484503011190096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:22:01.091] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:75:0) after 1m0s
[13:23:01.068] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:75:0)
[13:23:01.068] Timer tick, asking Buildbucket for the build status
[13:23:01.277] Build 8913484503011190096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:23:01.277] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:76:0) after 1m0s
[13:24:01.303] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:76:0)
[13:24:01.303] Timer tick, asking Buildbucket for the build status
[13:24:01.469] Build 8913484503011190096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:24:01.469] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:77:0) after 1m0s
[13:25:01.504] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:77:0)
[13:25:01.504] Timer tick, asking Buildbucket for the build status
[13:25:02.163] Build 8913484503011190096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:25:02.164] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:78:0) after 1m0s
[13:26:02.215] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:78:0)
[13:26:02.215] Timer tick, asking Buildbucket for the build status
[13:26:02.643] Build 8913484503011190096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:26:02.643] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:79:0) after 1m0s
[13:27:02.639] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:79:0)
[13:27:02.639] Timer tick, asking Buildbucket for the build status
[13:27:02.819] Build 8913484503011190096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:27:02.819] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:80:0) after 1m0s
[13:28:02.849] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:80:0)
[13:28:02.849] Timer tick, asking Buildbucket for the build status
[13:28:02.990] Build 8913484503011190096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:28:02.990] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:81:0) after 1m0s
[13:29:03.009] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:81:0)
[13:29:03.009] Timer tick, asking Buildbucket for the build status
[13:29:03.080] Build 8913484503011190096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:29:03.080] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:82:0) after 1m0s
[13:30:03.127] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:82:0)
[13:30:03.127] Timer tick, asking Buildbucket for the build status
[13:30:03.272] Build 8913484503011190096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:30:03.272] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:83:0) after 1m0s
[13:31:03.289] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:83:0)
[13:31:03.289] Timer tick, asking Buildbucket for the build status
[13:31:03.442] Build 8913484503011190096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:31:03.442] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:84:0) after 1m0s
[13:32:03.463] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:84:0)
[13:32:03.463] Timer tick, asking Buildbucket for the build status
[13:32:03.575] Build 8913484503011190096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:32:03.576] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:85:0) after 1m0s
[13:33:03.595] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:85:0)
[13:33:03.595] Timer tick, asking Buildbucket for the build status
[13:33:03.774] Build 8913484503011190096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:33:03.774] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:86:0) after 1m0s
[13:34:03.794] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:86:0)
[13:34:03.794] Timer tick, asking Buildbucket for the build status
[13:34:04.963] Build 8913484503011190096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:34:04.963] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:87:0) after 1m0s
[13:35:04.951] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:87:0)
[13:35:04.951] Timer tick, asking Buildbucket for the build status
[13:35:05.015] Build 8913484503011190096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:35:05.015] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:88:0) after 1m0s
[13:36:05.005] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:88:0)
[13:36:05.005] Timer tick, asking Buildbucket for the build status
[13:36:05.768] Build 8913484503011190096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:36:05.768] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:89:0) after 1m0s
[13:37:05.808] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:89:0)
[13:37:05.808] Timer tick, asking Buildbucket for the build status
[13:37:05.931] Build 8913484503011190096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:37:05.931] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:90:0) after 1m0s
[13:38:05.973] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:90:0)
[13:38:05.973] Timer tick, asking Buildbucket for the build status
[13:38:06.193] Build 8913484503011190096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:38:06.193] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:91:0) after 1m0s
[13:39:06.224] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:91:0)
[13:39:06.224] Timer tick, asking Buildbucket for the build status
[13:39:06.352] Build 8913484503011190096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:39:06.352] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:92:0) after 1m0s
[13:40:06.383] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:92:0)
[13:40:06.383] Timer tick, asking Buildbucket for the build status
[13:40:09.003] Build 8913484503011190096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:40:09.003] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:93:0) after 1m0s
[13:41:09.027] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:93:0)
[13:41:09.027] Timer tick, asking Buildbucket for the build status
[13:41:09.187] Build 8913484503011190096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:41:09.187] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:94:0) after 1m0s
[13:42:09.213] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:94:0)
[13:42:09.213] Timer tick, asking Buildbucket for the build status
[13:42:09.376] Build 8913484503011190096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:42:09.376] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:95:0) after 1m0s
[13:43:09.400] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:95:0)
[13:43:09.400] Timer tick, asking Buildbucket for the build status
[13:43:09.549] Build 8913484503011190096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:43:09.549] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:96:0) after 1m0s
[13:44:09.547] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:96:0)
[13:44:09.547] Timer tick, asking Buildbucket for the build status
[13:44:09.616] Build 8913484503011190096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:44:09.616] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:97:0) after 1m0s
[13:45:09.727] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:97:0)
[13:45:09.727] Timer tick, asking Buildbucket for the build status
[13:45:09.890] Build 8913484503011190096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:45:09.890] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:98:0) after 1m0s
[13:46:09.923] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:98:0)
[13:46:09.923] Timer tick, asking Buildbucket for the build status
[13:46:10.124] Build 8913484503011190096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:46:10.124] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:99:0) after 1m0s
[13:47:10.148] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:99:0)
[13:47:10.148] Timer tick, asking Buildbucket for the build status
[13:47:10.238] Build 8913484503011190096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:47:10.238] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:100:0) after 1m0s
[13:48:10.242] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:100:0)
[13:48:10.242] Timer tick, asking Buildbucket for the build status
[13:48:10.578] Build 8913484503011190096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:48:10.578] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:101:0) after 1m0s
[13:49:10.600] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:101:0)
[13:49:10.600] Timer tick, asking Buildbucket for the build status
[13:49:10.707] Build 8913484503011190096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:49:10.707] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:102:0) after 1m0s
[13:50:10.728] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:102:0)
[13:50:10.728] Timer tick, asking Buildbucket for the build status
[13:50:10.771] Build 8913484503011190096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:50:10.771] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:103:0) after 1m0s
[13:51:10.803] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:103:0)
[13:51:10.803] Timer tick, asking Buildbucket for the build status
[13:51:11.156] Build 8913484503011190096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:51:11.156] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:104:0) after 1m0s
[13:52:11.178] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:104:0)
[13:52:11.178] Timer tick, asking Buildbucket for the build status
[13:52:11.812] Build 8913484503011190096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:52:11.812] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:105:0) after 1m0s
[13:53:11.839] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:105:0)
[13:53:11.839] Timer tick, asking Buildbucket for the build status
[13:53:12.154] Build 8913484503011190096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:53:12.154] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:106:0) after 1m0s
[13:54:12.524] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:106:0)
[13:54:12.524] Timer tick, asking Buildbucket for the build status
[13:54:12.570] Build 8913484503011190096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:54:12.570] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:107:0) after 1m0s
[13:55:12.590] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:107:0)
[13:55:12.590] Timer tick, asking Buildbucket for the build status
[13:55:12.658] Build 8913484503011190096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:55:12.658] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:108:0) after 1m0s
[13:56:12.679] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:108:0)
[13:56:12.679] Timer tick, asking Buildbucket for the build status
[13:56:12.995] Build 8913484503011190096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:56:12.995] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:109:0) after 1m0s
[13:57:12.984] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:109:0)
[13:57:12.984] Timer tick, asking Buildbucket for the build status
[13:57:13.156] Build 8913484503011190096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:57:13.156] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:110:0) after 1m0s
[13:58:13.177] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:110:0)
[13:58:13.177] Timer tick, asking Buildbucket for the build status
[13:58:13.411] Build 8913484503011190096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:58:13.411] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:111:0) after 1m0s
[13:59:13.419] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:111:0)
[13:59:13.419] Timer tick, asking Buildbucket for the build status
[13:59:14.140] Build 8913484503011190096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:59:14.140] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:112:0) after 1m0s
[14:00:14.158] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:112:0)
[14:00:14.158] Timer tick, asking Buildbucket for the build status
[14:00:14.418] Build 8913484503011190096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:00:14.418] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:113:0) after 1m0s
[14:01:14.441] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:113:0)
[14:01:14.441] Timer tick, asking Buildbucket for the build status
[14:01:14.486] Build 8913484503011190096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:01:14.486] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:114:0) after 1m0s
[14:02:14.647] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:114:0)
[14:02:14.647] Timer tick, asking Buildbucket for the build status
[14:02:14.762] Build 8913484503011190096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:02:14.762] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:115:0) after 1m0s
[14:03:14.806] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:115:0)
[14:03:14.806] Timer tick, asking Buildbucket for the build status
[14:03:15.013] Build 8913484503011190096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:03:15.013] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:116:0) after 1m0s
[14:04:15.081] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:116:0)
[14:04:15.081] Timer tick, asking Buildbucket for the build status
[14:04:15.182] Build 8913484503011190096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:04:15.182] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:117:0) after 1m0s
[14:05:15.221] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:117:0)
[14:05:15.221] Timer tick, asking Buildbucket for the build status
[14:05:15.416] Build 8913484503011190096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:05:15.416] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:118:0) after 1m0s
[14:06:15.439] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:118:0)
[14:06:15.439] Timer tick, asking Buildbucket for the build status
[14:06:15.497] Build 8913484503011190096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:06:15.497] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:119:0) after 1m0s
[14:07:15.485] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:119:0)
[14:07:15.485] Timer tick, asking Buildbucket for the build status
[14:07:15.868] Build 8913484503011190096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:07:15.868] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:120:0) after 1m0s
[14:08:15.887] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:120:0)
[14:08:15.887] Timer tick, asking Buildbucket for the build status
[14:08:16.063] Build 8913484503011190096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:08:16.063] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:121:0) after 1m0s
[14:09:16.095] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:121:0)
[14:09:16.095] Timer tick, asking Buildbucket for the build status
[14:09:16.174] Build 8913484503011190096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:09:16.174] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:122:0) after 1m0s
[14:10:16.204] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:122:0)
[14:10:16.204] Timer tick, asking Buildbucket for the build status
[14:10:16.424] Build 8913484503011190096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:10:16.424] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:123:0) after 1m0s
[14:11:16.413] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:123:0)
[14:11:16.413] Timer tick, asking Buildbucket for the build status
[14:11:16.485] Build 8913484503011190096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:11:16.485] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:124:0) after 1m0s
[14:12:16.511] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:124:0)
[14:12:16.511] Timer tick, asking Buildbucket for the build status
[14:12:16.594] Build 8913484503011190096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:12:16.594] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:125:0) after 1m0s
[14:13:16.615] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:125:0)
[14:13:16.615] Timer tick, asking Buildbucket for the build status
[14:13:16.821] Build 8913484503011190096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:13:16.821] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:126:0) after 1m0s
[14:14:17.629] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:126:0)
[14:14:17.629] Timer tick, asking Buildbucket for the build status
[14:14:17.960] Build 8913484503011190096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:14:17.960] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:127:0) after 1m0s
[14:15:18.036] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:127:0)
[14:15:18.036] Timer tick, asking Buildbucket for the build status
[14:15:18.335] Build 8913484503011190096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:15:18.335] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:128:0) after 1m0s
[14:16:18.355] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:128:0)
[14:16:18.355] Timer tick, asking Buildbucket for the build status
[14:16:18.458] Build 8913484503011190096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:16:18.458] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:129:0) after 1m0s
[14:17:18.436] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:129:0)
[14:17:18.436] Timer tick, asking Buildbucket for the build status
[14:17:18.569] Build 8913484503011190096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:17:18.569] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:130:0) after 1m0s
[14:18:18.598] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:130:0)
[14:18:18.598] Timer tick, asking Buildbucket for the build status
[14:18:18.670] Build 8913484503011190096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:18:18.670] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:131:0) after 1m0s
[14:19:18.688] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:131:0)
[14:19:18.688] Timer tick, asking Buildbucket for the build status
[14:19:18.830] Build 8913484503011190096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:19:18.830] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:132:0) after 1m0s
[14:20:18.863] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:132:0)
[14:20:18.863] Timer tick, asking Buildbucket for the build status
[14:20:18.930] Build 8913484503011190096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:20:18.930] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:133:0) after 1m0s
[14:21:18.913] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:133:0)
[14:21:18.913] Timer tick, asking Buildbucket for the build status
[14:21:19.336] Build 8913484503011190096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:21:19.336] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:134:0) after 1m0s
[14:22:19.381] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:134:0)
[14:22:19.381] Timer tick, asking Buildbucket for the build status
[14:22:19.505] Build 8913484503011190096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:22:19.505] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:135:0) after 1m0s
[14:23:19.575] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:135:0)
[14:23:19.575] Timer tick, asking Buildbucket for the build status
[14:23:19.625] Build 8913484503011190096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:23:19.625] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:136:0) after 1m0s
[14:24:19.605] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:136:0)
[14:24:19.605] Timer tick, asking Buildbucket for the build status
[14:24:19.658] Build 8913484503011190096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:24:19.658] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:137:0) after 1m0s
[14:25:19.694] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:137:0)
[14:25:19.694] Timer tick, asking Buildbucket for the build status
[14:25:19.762] Build 8913484503011190096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:25:19.762] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078914565022694640:138:0) after 1m0s
[14:26:00.459] Received PubSub notification, asking Buildbucket for the build status
[14:26:00.751] Build 8913484503011190096: status "COMPLETED", result "CANCELED", failure_reason "", cancelation_reason "TIMEOUT"
[14:26:00.751] Buildbucket build:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary_preference": "AUTO",
"cancelation_reason": "TIMEOUT",
"completed_ts": "1557843959388010",
"created_by": "project:emscripten-releases",
"created_ts": "1557835782001603",
"id": "8913484503011190096",
"parameters_json": "{\"builder_name\": \"win\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"779d493c49e77aca6a948bdadddf896a680580ed\"}}",
"project": "emscripten-releases",
"result": "CANCELED",
"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}, \"bot_id\": \"luci-emscripten-releases-ci-win10-0-k3ps\", \"branch\": \"refs/heads/master\", \"buildername\": \"win\", \"got_revision\": \"779d493c49e77aca6a948bdadddf896a680580ed\", \"path_config\": \"generic\", \"recipe\": \"emscripten_releases\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"779d493c49e77aca6a948bdadddf896a680580ed\"}, \"swarming\": {\"bot_dimensions\": {\"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"host_class\": [\"default\"], \"id\": [\"luci-emscripten-releases-ci-win10-0-k3ps\"], \"image\": [\"chrome-win10-1703-2018-04-20-61acaf8983f\"], \"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\": [\"4293-d3520ea\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-b\"]}}}",
"service_account": "emscripten-releases-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1557836652574028",
"status": "COMPLETED",
"status_changed_ts": "1557843959739067",
"tags": [
"builder:win",
"buildset:commit/git/779d493c49e77aca6a948bdadddf896a680580ed",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/779d493c49e77aca6a948bdadddf896a680580ed",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9078914565022694640",
"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/8913484503011190096/+/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:44cf1128e8684610",
"user_agent:luci-scheduler"
],
"updated_ts": "1557843959743722",
"url": "https://ci.chromium.org/b/8913484503011190096",
"utcnow_ts": "1557843960726836"
}
}
[14:26:00.751] Invocation finished in 2h16m20.052863041s with status FAILED