[17:59:00.260] New invocation is queued and will start shortly
[17:59:01.333] Starting the invocation (attempt 1)
[17:59:01.394] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[17:59:01.394] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[17:59:01.394] Buildbucket request:
{
"bucket": "luci.emscripten-releases.ci",
"client_operation_id": "9078530199481941584",
"parameters_json": "{\"builder_name\":\"win\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/emscripten-releases\",\"revision\":\"e0c58749d4bc355c6953f5577b4ee177b5304a24\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win",
"scheduler_invocation_id:9078530199481941584",
"scheduler_job_id:emscripten-releases/win",
"user_agent:luci-scheduler",
"buildset:commit/git/e0c58749d4bc355c6953f5577b4ee177b5304a24",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/e0c58749d4bc355c6953f5577b4ee177b5304a24",
"gitiles_ref:refs/heads/master"
]
}
[17:59:01.818] Buildbucket response:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary_preference": "AUTO",
"created_by": "project:emscripten-releases",
"created_ts": "1558202341464529",
"id": "8913100137554978608",
"parameters_json": "{\"builder_name\": \"win\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"e0c58749d4bc355c6953f5577b4ee177b5304a24\"}}",
"project": "emscripten-releases",
"result_details_json": "{\"properties\": {}}",
"service_account": "emscripten-releases-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1558202341690627",
"tags": [
"builder:win",
"buildset:commit/git/e0c58749d4bc355c6953f5577b4ee177b5304a24",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/e0c58749d4bc355c6953f5577b4ee177b5304a24",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9078530199481941584",
"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/8913100137554978608/+/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": "1558202341690715",
"url": "https://ci.chromium.org/b/8913100137554978608",
"utcnow_ts": "1558202341811228"
}
}
[17:59:01.818] Task URL: https://ci.chromium.org/b/8913100137554978608
[17:59:01.818] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:2:0) after 1m0s
[17:59:41.081] Received PubSub notification, asking Buildbucket for the build status
[17:59:41.143] Build 8913100137554978608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:00:01.861] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:2:0)
[18:00:01.861] Timer tick, asking Buildbucket for the build status
[18:00:01.983] Build 8913100137554978608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:00:01.983] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:4:0) after 1m0s
[18:01:02.019] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:4:0)
[18:01:02.019] Timer tick, asking Buildbucket for the build status
[18:01:02.118] Build 8913100137554978608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:01:02.118] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:5:0) after 1m0s
[18:02:02.150] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:5:0)
[18:02:02.150] Timer tick, asking Buildbucket for the build status
[18:02:02.970] Build 8913100137554978608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:02:02.970] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:6:0) after 1m0s
[18:03:03.019] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:6:0)
[18:03:03.019] Timer tick, asking Buildbucket for the build status
[18:03:03.146] Build 8913100137554978608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:03:03.146] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:7:0) after 1m0s
[18:04:03.175] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:7:0)
[18:04:03.175] Timer tick, asking Buildbucket for the build status
[18:04:03.268] Build 8913100137554978608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:04:03.268] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:8:0) after 1m0s
[18:05:03.305] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:8:0)
[18:05:03.305] Timer tick, asking Buildbucket for the build status
[18:05:03.469] Build 8913100137554978608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:05:03.469] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:9:0) after 1m0s
[18:06:03.477] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:9:0)
[18:06:03.477] Timer tick, asking Buildbucket for the build status
[18:06:03.613] Build 8913100137554978608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:06:03.613] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:10:0) after 1m0s
[18:07:03.649] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:10:0)
[18:07:03.649] Timer tick, asking Buildbucket for the build status
[18:07:03.786] Build 8913100137554978608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:07:03.786] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:11:0) after 1m0s
[18:08:03.760] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:11:0)
[18:08:03.760] Timer tick, asking Buildbucket for the build status
[18:08:03.813] Build 8913100137554978608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:08:03.813] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:12:0) after 1m0s
[18:09:03.839] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:12:0)
[18:09:03.839] Timer tick, asking Buildbucket for the build status
[18:09:03.941] Build 8913100137554978608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:09:03.941] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:13:0) after 1m0s
[18:10:03.963] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:13:0)
[18:10:03.963] Timer tick, asking Buildbucket for the build status
[18:10:04.073] Build 8913100137554978608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:10:04.073] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:14:0) after 1m0s
[18:11:04.082] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:14:0)
[18:11:04.082] Timer tick, asking Buildbucket for the build status
[18:11:04.198] Build 8913100137554978608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:11:04.198] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:15:0) after 1m0s
[18:12:04.265] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:15:0)
[18:12:04.265] Timer tick, asking Buildbucket for the build status
[18:12:04.366] Build 8913100137554978608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:12:04.366] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:16:0) after 1m0s
[18:13:04.400] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:16:0)
[18:13:04.400] Timer tick, asking Buildbucket for the build status
[18:13:04.461] Build 8913100137554978608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:13:04.461] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:17:0) after 1m0s
[18:14:04.511] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:17:0)
[18:14:04.511] Timer tick, asking Buildbucket for the build status
[18:14:04.642] Build 8913100137554978608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:14:04.642] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:18:0) after 1m0s
[18:15:04.650] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:18:0)
[18:15:04.650] Timer tick, asking Buildbucket for the build status
[18:15:04.770] Build 8913100137554978608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:15:04.770] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:19:0) after 1m0s
[18:16:04.793] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:19:0)
[18:16:04.793] Timer tick, asking Buildbucket for the build status
[18:16:04.859] Build 8913100137554978608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:16:04.859] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:20:0) after 1m0s
[18:17:04.841] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:20:0)
[18:17:04.841] Timer tick, asking Buildbucket for the build status
[18:17:04.901] Build 8913100137554978608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:17:04.901] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:21:0) after 1m0s
[18:18:04.962] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:21:0)
[18:18:04.962] Timer tick, asking Buildbucket for the build status
[18:18:05.037] Build 8913100137554978608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:18:05.037] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:22:0) after 1m0s
[18:19:05.068] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:22:0)
[18:19:05.068] Timer tick, asking Buildbucket for the build status
[18:19:05.260] Build 8913100137554978608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:19:05.260] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:23:0) after 1m0s
[18:20:05.280] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:23:0)
[18:20:05.280] Timer tick, asking Buildbucket for the build status
[18:20:05.476] Build 8913100137554978608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:20:05.476] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:24:0) after 1m0s
[18:21:05.515] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:24:0)
[18:21:05.515] Timer tick, asking Buildbucket for the build status
[18:21:05.592] Build 8913100137554978608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:21:05.592] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:25:0) after 1m0s
[18:22:05.613] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:25:0)
[18:22:05.613] Timer tick, asking Buildbucket for the build status
[18:22:05.737] Build 8913100137554978608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:22:05.737] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:26:0) after 1m0s
[18:23:05.891] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:26:0)
[18:23:05.891] Timer tick, asking Buildbucket for the build status
[18:23:06.129] Build 8913100137554978608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:23:06.129] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:27:0) after 1m0s
[18:24:06.184] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:27:0)
[18:24:06.184] Timer tick, asking Buildbucket for the build status
[18:24:06.311] Build 8913100137554978608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:24:06.311] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:28:0) after 1m0s
[18:25:06.369] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:28:0)
[18:25:06.369] Timer tick, asking Buildbucket for the build status
[18:25:06.451] Build 8913100137554978608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:25:06.451] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:29:0) after 1m0s
[18:26:06.484] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:29:0)
[18:26:06.484] Timer tick, asking Buildbucket for the build status
[18:26:06.615] Build 8913100137554978608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:26:06.615] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:30:0) after 1m0s
[18:27:06.656] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:30:0)
[18:27:06.656] Timer tick, asking Buildbucket for the build status
[18:27:06.841] Build 8913100137554978608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:27:06.841] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:31:0) after 1m0s
[18:28:06.952] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:31:0)
[18:28:06.952] Timer tick, asking Buildbucket for the build status
[18:28:07.036] Build 8913100137554978608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:28:07.036] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:32:0) after 1m0s
[18:29:07.131] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:32:0)
[18:29:07.131] Timer tick, asking Buildbucket for the build status
[18:29:07.598] Build 8913100137554978608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:29:07.598] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:33:0) after 1m0s
[18:30:07.629] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:33:0)
[18:30:07.629] Timer tick, asking Buildbucket for the build status
[18:30:07.719] Build 8913100137554978608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:30:07.719] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:34:0) after 1m0s
[18:31:07.752] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:34:0)
[18:31:07.752] Timer tick, asking Buildbucket for the build status
[18:31:08.369] Build 8913100137554978608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:31:08.369] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:35:0) after 1m0s
[18:32:08.381] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:35:0)
[18:32:08.381] Timer tick, asking Buildbucket for the build status
[18:32:08.431] Build 8913100137554978608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:32:08.431] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:36:0) after 1m0s
[18:33:08.457] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:36:0)
[18:33:08.457] Timer tick, asking Buildbucket for the build status
[18:33:08.805] Build 8913100137554978608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:33:08.805] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:37:0) after 1m0s
[18:34:08.811] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:37:0)
[18:34:08.811] Timer tick, asking Buildbucket for the build status
[18:34:08.912] Build 8913100137554978608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:34:08.912] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:38:0) after 1m0s
[18:35:08.933] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:38:0)
[18:35:08.933] Timer tick, asking Buildbucket for the build status
[18:35:09.062] Build 8913100137554978608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:35:09.062] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:39:0) after 1m0s
[18:36:09.121] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:39:0)
[18:36:09.121] Timer tick, asking Buildbucket for the build status
[18:36:09.354] Build 8913100137554978608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:36:09.354] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:40:0) after 1m0s
[18:37:11.484] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:40:0)
[18:37:11.484] Timer tick, asking Buildbucket for the build status
[18:37:12.362] Build 8913100137554978608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:37:12.362] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:41:0) after 1m0s
[18:38:12.374] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:41:0)
[18:38:12.374] Timer tick, asking Buildbucket for the build status
[18:38:12.560] Build 8913100137554978608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:38:12.560] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:42:0) after 1m0s
[18:39:12.582] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:42:0)
[18:39:12.582] Timer tick, asking Buildbucket for the build status
[18:39:12.959] Build 8913100137554978608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:39:12.959] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:43:0) after 1m0s
[18:40:12.987] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:43:0)
[18:40:12.987] Timer tick, asking Buildbucket for the build status
[18:40:13.389] Build 8913100137554978608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:40:13.389] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:44:0) after 1m0s
[18:41:13.419] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:44:0)
[18:41:13.419] Timer tick, asking Buildbucket for the build status
[18:41:13.485] Build 8913100137554978608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:41:13.485] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:45:0) after 1m0s
[18:42:13.490] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:45:0)
[18:42:13.490] Timer tick, asking Buildbucket for the build status
[18:42:13.955] Build 8913100137554978608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:42:13.955] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:46:0) after 1m0s
[18:43:14.014] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:46:0)
[18:43:14.014] Timer tick, asking Buildbucket for the build status
[18:43:14.383] Build 8913100137554978608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:43:14.383] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:47:0) after 1m0s
[18:44:14.396] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:47:0)
[18:44:14.396] Timer tick, asking Buildbucket for the build status
[18:44:14.584] Build 8913100137554978608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:44:14.584] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:48:0) after 1m0s
[18:45:14.609] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:48:0)
[18:45:14.609] Timer tick, asking Buildbucket for the build status
[18:45:15.619] Build 8913100137554978608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:45:15.619] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:49:0) after 1m0s
[18:46:15.637] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:49:0)
[18:46:15.637] Timer tick, asking Buildbucket for the build status
[18:46:15.704] Build 8913100137554978608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:46:15.704] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:50:0) after 1m0s
[18:47:15.712] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:50:0)
[18:47:15.712] Timer tick, asking Buildbucket for the build status
[18:47:15.814] Build 8913100137554978608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:47:15.814] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:51:0) after 1m0s
[18:48:15.844] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:51:0)
[18:48:15.844] Timer tick, asking Buildbucket for the build status
[18:48:15.933] Build 8913100137554978608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:48:15.933] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:52:0) after 1m0s
[18:49:15.956] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:52:0)
[18:49:15.956] Timer tick, asking Buildbucket for the build status
[18:49:16.032] Build 8913100137554978608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:49:16.032] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:53:0) after 1m0s
[18:50:16.072] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:53:0)
[18:50:16.072] Timer tick, asking Buildbucket for the build status
[18:50:16.186] Build 8913100137554978608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:50:16.186] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:54:0) after 1m0s
[18:51:16.211] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:54:0)
[18:51:16.211] Timer tick, asking Buildbucket for the build status
[18:51:16.560] Build 8913100137554978608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:51:16.560] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:55:0) after 1m0s
[18:52:16.600] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:55:0)
[18:52:16.600] Timer tick, asking Buildbucket for the build status
[18:52:16.750] Build 8913100137554978608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:52:16.750] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:56:0) after 1m0s
[18:53:16.774] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:56:0)
[18:53:16.774] Timer tick, asking Buildbucket for the build status
[18:53:16.951] Build 8913100137554978608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:53:16.951] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:57:0) after 1m0s
[18:54:17.054] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:57:0)
[18:54:17.054] Timer tick, asking Buildbucket for the build status
[18:54:17.250] Build 8913100137554978608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:54:17.250] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:58:0) after 1m0s
[18:55:17.271] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:58:0)
[18:55:17.271] Timer tick, asking Buildbucket for the build status
[18:55:17.380] Build 8913100137554978608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:55:17.380] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:59:0) after 1m0s
[18:56:17.400] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:59:0)
[18:56:17.400] Timer tick, asking Buildbucket for the build status
[18:56:17.457] Build 8913100137554978608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:56:17.457] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:60:0) after 1m0s
[18:57:17.474] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:60:0)
[18:57:17.474] Timer tick, asking Buildbucket for the build status
[18:57:17.515] Build 8913100137554978608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:57:17.515] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:61:0) after 1m0s
[18:58:17.540] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:61:0)
[18:58:17.540] Timer tick, asking Buildbucket for the build status
[18:58:17.592] Build 8913100137554978608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:58:17.592] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:62:0) after 1m0s
[18:59:17.692] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:62:0)
[18:59:17.692] Timer tick, asking Buildbucket for the build status
[18:59:17.974] Build 8913100137554978608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:59:17.974] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:63:0) after 1m0s
[19:00:18.010] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:63:0)
[19:00:18.010] Timer tick, asking Buildbucket for the build status
[19:00:18.139] Build 8913100137554978608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:00:18.139] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:64:0) after 1m0s
[19:01:18.206] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:64:0)
[19:01:18.206] Timer tick, asking Buildbucket for the build status
[19:01:18.565] Build 8913100137554978608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:01:18.565] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:65:0) after 1m0s
[19:02:18.547] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:65:0)
[19:02:18.547] Timer tick, asking Buildbucket for the build status
[19:02:18.751] Build 8913100137554978608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:02:18.751] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:66:0) after 1m0s
[19:03:18.786] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:66:0)
[19:03:18.786] Timer tick, asking Buildbucket for the build status
[19:03:18.823] Build 8913100137554978608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:03:18.823] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:67:0) after 1m0s
[19:04:18.848] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:67:0)
[19:04:18.848] Timer tick, asking Buildbucket for the build status
[19:04:18.905] Build 8913100137554978608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:04:18.905] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:68:0) after 1m0s
[19:05:18.929] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:68:0)
[19:05:18.929] Timer tick, asking Buildbucket for the build status
[19:05:19.103] Build 8913100137554978608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:05:19.104] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:69:0) after 1m0s
[19:06:19.183] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:69:0)
[19:06:19.183] Timer tick, asking Buildbucket for the build status
[19:06:19.518] Build 8913100137554978608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:06:19.518] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:70:0) after 1m0s
[19:07:19.741] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:70:0)
[19:07:19.742] Timer tick, asking Buildbucket for the build status
[19:07:19.805] Build 8913100137554978608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:07:19.805] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:71:0) after 1m0s
[19:08:19.836] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:71:0)
[19:08:19.836] Timer tick, asking Buildbucket for the build status
[19:08:19.924] Build 8913100137554978608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:08:19.924] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:72:0) after 1m0s
[19:09:19.918] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:72:0)
[19:09:19.918] Timer tick, asking Buildbucket for the build status
[19:09:19.981] Build 8913100137554978608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:09:19.981] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:73:0) after 1m0s
[19:10:19.978] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:73:0)
[19:10:19.978] Timer tick, asking Buildbucket for the build status
[19:10:20.114] Build 8913100137554978608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:10:20.114] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:74:0) after 1m0s
[19:11:20.138] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:74:0)
[19:11:20.138] Timer tick, asking Buildbucket for the build status
[19:11:20.198] Build 8913100137554978608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:11:20.198] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:75:0) after 1m0s
[19:12:20.225] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:75:0)
[19:12:20.225] Timer tick, asking Buildbucket for the build status
[19:12:20.401] Build 8913100137554978608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:12:20.401] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:76:0) after 1m0s
[19:13:20.433] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:76:0)
[19:13:20.433] Timer tick, asking Buildbucket for the build status
[19:13:20.572] Build 8913100137554978608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:13:20.572] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:77:0) after 1m0s
[19:14:20.590] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:77:0)
[19:14:20.590] Timer tick, asking Buildbucket for the build status
[19:14:20.706] Build 8913100137554978608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:14:20.706] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:78:0) after 1m0s
[19:15:20.707] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:78:0)
[19:15:20.707] Timer tick, asking Buildbucket for the build status
[19:15:20.958] Build 8913100137554978608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:15:20.958] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:79:0) after 1m0s
[19:16:20.957] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:79:0)
[19:16:20.957] Timer tick, asking Buildbucket for the build status
[19:16:20.996] Build 8913100137554978608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:16:20.996] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:80:0) after 1m0s
[19:17:20.985] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:80:0)
[19:17:20.986] Timer tick, asking Buildbucket for the build status
[19:17:21.176] Build 8913100137554978608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:17:21.176] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:81:0) after 1m0s
[19:18:21.211] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:81:0)
[19:18:21.211] Timer tick, asking Buildbucket for the build status
[19:18:21.258] Build 8913100137554978608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:18:21.258] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:82:0) after 1m0s
[19:19:21.266] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:82:0)
[19:19:21.266] Timer tick, asking Buildbucket for the build status
[19:19:21.322] Build 8913100137554978608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:19:21.322] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:83:0) after 1m0s
[19:20:21.336] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:83:0)
[19:20:21.336] Timer tick, asking Buildbucket for the build status
[19:20:22.014] Build 8913100137554978608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:20:22.014] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:84:0) after 1m0s
[19:21:22.041] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:84:0)
[19:21:22.041] Timer tick, asking Buildbucket for the build status
[19:21:22.099] Build 8913100137554978608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:21:22.099] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:85:0) after 1m0s
[19:22:22.139] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:85:0)
[19:22:22.139] Timer tick, asking Buildbucket for the build status
[19:22:22.680] Build 8913100137554978608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:22:22.680] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:86:0) after 1m0s
[19:23:22.725] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:86:0)
[19:23:22.725] Timer tick, asking Buildbucket for the build status
[19:23:22.765] Build 8913100137554978608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:23:22.765] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:87:0) after 1m0s
[19:24:22.789] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:87:0)
[19:24:22.789] Timer tick, asking Buildbucket for the build status
[19:24:23.010] Build 8913100137554978608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:24:23.010] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:88:0) after 1m0s
[19:25:23.037] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:88:0)
[19:25:23.037] Timer tick, asking Buildbucket for the build status
[19:25:23.103] Build 8913100137554978608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:25:23.103] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:89:0) after 1m0s
[19:26:23.157] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:89:0)
[19:26:23.157] Timer tick, asking Buildbucket for the build status
[19:26:23.282] Build 8913100137554978608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:26:23.282] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:90:0) after 1m0s
[19:27:23.302] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:90:0)
[19:27:23.302] Timer tick, asking Buildbucket for the build status
[19:27:23.396] Build 8913100137554978608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:27:23.396] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:91:0) after 1m0s
[19:28:23.441] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:91:0)
[19:28:23.441] Timer tick, asking Buildbucket for the build status
[19:28:23.486] Build 8913100137554978608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:28:23.486] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:92:0) after 1m0s
[19:29:23.518] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:92:0)
[19:29:23.518] Timer tick, asking Buildbucket for the build status
[19:29:23.843] Build 8913100137554978608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:29:23.843] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:93:0) after 1m0s
[19:30:23.867] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:93:0)
[19:30:23.867] Timer tick, asking Buildbucket for the build status
[19:30:23.931] Build 8913100137554978608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:30:23.931] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:94:0) after 1m0s
[19:31:24.215] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:94:0)
[19:31:24.215] Timer tick, asking Buildbucket for the build status
[19:31:24.295] Build 8913100137554978608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:31:24.295] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:95:0) after 1m0s
[19:32:24.586] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:95:0)
[19:32:24.586] Timer tick, asking Buildbucket for the build status
[19:32:24.871] Build 8913100137554978608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:32:24.872] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:96:0) after 1m0s
[19:33:24.910] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:96:0)
[19:33:24.910] Timer tick, asking Buildbucket for the build status
[19:33:25.004] Build 8913100137554978608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:33:25.004] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:97:0) after 1m0s
[19:34:25.033] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:97:0)
[19:34:25.033] Timer tick, asking Buildbucket for the build status
[19:34:25.085] Build 8913100137554978608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:34:25.085] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:98:0) after 1m0s
[19:35:25.069] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:98:0)
[19:35:25.069] Timer tick, asking Buildbucket for the build status
[19:35:25.114] Build 8913100137554978608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:35:25.114] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:99:0) after 1m0s
[19:36:25.141] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:99:0)
[19:36:25.141] Timer tick, asking Buildbucket for the build status
[19:36:25.190] Build 8913100137554978608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:36:25.190] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:100:0) after 1m0s
[19:37:25.210] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:100:0)
[19:37:25.211] Timer tick, asking Buildbucket for the build status
[19:37:25.283] Build 8913100137554978608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:37:25.283] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:101:0) after 1m0s
[19:38:25.310] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:101:0)
[19:38:25.310] Timer tick, asking Buildbucket for the build status
[19:38:25.491] Build 8913100137554978608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:38:25.491] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:102:0) after 1m0s
[19:39:25.519] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:102:0)
[19:39:25.519] Timer tick, asking Buildbucket for the build status
[19:39:25.569] Build 8913100137554978608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:39:25.569] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:103:0) after 1m0s
[19:40:25.646] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:103:0)
[19:40:25.646] Timer tick, asking Buildbucket for the build status
[19:40:26.629] Build 8913100137554978608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:40:26.630] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:104:0) after 1m0s
[19:41:26.680] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:104:0)
[19:41:26.680] Timer tick, asking Buildbucket for the build status
[19:41:26.845] Build 8913100137554978608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:41:26.845] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:105:0) after 1m0s
[19:42:26.870] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:105:0)
[19:42:26.870] Timer tick, asking Buildbucket for the build status
[19:42:26.933] Build 8913100137554978608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:42:26.933] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:106:0) after 1m0s
[19:43:26.935] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:106:0)
[19:43:26.935] Timer tick, asking Buildbucket for the build status
[19:43:26.995] Build 8913100137554978608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:43:26.995] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:107:0) after 1m0s
[19:44:27.020] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:107:0)
[19:44:27.020] Timer tick, asking Buildbucket for the build status
[19:44:27.061] Build 8913100137554978608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:44:27.061] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:108:0) after 1m0s
[19:45:27.083] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:108:0)
[19:45:27.084] Timer tick, asking Buildbucket for the build status
[19:45:27.313] Build 8913100137554978608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:45:27.313] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:109:0) after 1m0s
[19:46:27.371] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:109:0)
[19:46:27.372] Timer tick, asking Buildbucket for the build status
[19:46:27.443] Build 8913100137554978608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:46:27.443] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:110:0) after 1m0s
[19:47:27.456] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:110:0)
[19:47:27.456] Timer tick, asking Buildbucket for the build status
[19:47:27.612] Build 8913100137554978608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:47:27.612] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:111:0) after 1m0s
[19:48:27.699] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:111:0)
[19:48:27.699] Timer tick, asking Buildbucket for the build status
[19:48:27.787] Build 8913100137554978608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:48:27.787] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:112:0) after 1m0s
[19:49:27.813] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:112:0)
[19:49:27.813] Timer tick, asking Buildbucket for the build status
[19:49:28.051] Build 8913100137554978608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:49:28.051] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:113:0) after 1m0s
[19:50:28.059] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:113:0)
[19:50:28.059] Timer tick, asking Buildbucket for the build status
[19:50:28.303] Build 8913100137554978608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:50:28.303] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:114:0) after 1m0s
[19:51:28.329] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:114:0)
[19:51:28.329] Timer tick, asking Buildbucket for the build status
[19:51:28.436] Build 8913100137554978608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:51:28.436] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:115:0) after 1m0s
[19:52:28.461] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:115:0)
[19:52:28.461] Timer tick, asking Buildbucket for the build status
[19:52:28.602] Build 8913100137554978608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:52:28.602] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:116:0) after 1m0s
[19:53:28.591] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:116:0)
[19:53:28.591] Timer tick, asking Buildbucket for the build status
[19:53:28.798] Build 8913100137554978608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:53:28.798] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:117:0) after 1m0s
[19:54:28.821] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:117:0)
[19:54:28.821] Timer tick, asking Buildbucket for the build status
[19:54:28.998] Build 8913100137554978608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:54:28.998] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:118:0) after 1m0s
[19:55:29.033] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:118:0)
[19:55:29.033] Timer tick, asking Buildbucket for the build status
[19:55:29.092] Build 8913100137554978608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:55:29.092] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:119:0) after 1m0s
[19:56:29.116] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:119:0)
[19:56:29.116] Timer tick, asking Buildbucket for the build status
[19:56:29.334] Build 8913100137554978608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:56:29.334] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:120:0) after 1m0s
[19:57:29.357] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:120:0)
[19:57:29.357] Timer tick, asking Buildbucket for the build status
[19:57:29.471] Build 8913100137554978608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:57:29.471] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:121:0) after 1m0s
[19:58:29.495] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:121:0)
[19:58:29.495] Timer tick, asking Buildbucket for the build status
[19:58:29.536] Build 8913100137554978608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:58:29.536] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:122:0) after 1m0s
[19:59:29.561] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:122:0)
[19:59:29.561] Timer tick, asking Buildbucket for the build status
[19:59:29.625] Build 8913100137554978608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:59:29.625] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:123:0) after 1m0s
[20:00:29.667] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:123:0)
[20:00:29.667] Timer tick, asking Buildbucket for the build status
[20:00:29.734] Build 8913100137554978608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:00:29.734] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:124:0) after 1m0s
[20:01:29.757] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:124:0)
[20:01:29.757] Timer tick, asking Buildbucket for the build status
[20:01:29.884] Build 8913100137554978608: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:01:29.884] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078530199481941584:125:0) after 1m0s
[20:01:53.627] Received PubSub notification, asking Buildbucket for the build status
[20:01:53.732] Build 8913100137554978608: status "COMPLETED", result "CANCELED", failure_reason "", cancelation_reason "TIMEOUT"
[20:01:53.732] Buildbucket build:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary_preference": "AUTO",
"cancelation_reason": "TIMEOUT",
"completed_ts": "1558209711920173",
"created_by": "project:emscripten-releases",
"created_ts": "1558202341464529",
"id": "8913100137554978608",
"parameters_json": "{\"builder_name\": \"win\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"e0c58749d4bc355c6953f5577b4ee177b5304a24\"}}",
"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-5pty\", \"branch\": \"refs/heads/master\", \"buildername\": \"win\", \"got_revision\": \"e0c58749d4bc355c6953f5577b4ee177b5304a24\", \"path_config\": \"generic\", \"recipe\": \"emscripten_releases\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"e0c58749d4bc355c6953f5577b4ee177b5304a24\"}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"builder_0175bca77590805533ed6e20351c9bfa7effd036e42d11a5b619a6ce75a8e486_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"host_class\": [\"default\"], \"id\": [\"luci-emscripten-releases-ci-win10-0-5pty\"], \"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\": [\"4301-0053cb9\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-b\"]}}}",
"service_account": "emscripten-releases-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1558202380083437",
"status": "COMPLETED",
"status_changed_ts": "1558209712522473",
"tags": [
"builder:win",
"buildset:commit/git/e0c58749d4bc355c6953f5577b4ee177b5304a24",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/e0c58749d4bc355c6953f5577b4ee177b5304a24",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9078530199481941584",
"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/8913100137554978608/+/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:44e4ea6666f1ee10",
"user_agent:luci-scheduler"
],
"updated_ts": "1558209712554828",
"url": "https://ci.chromium.org/b/8913100137554978608",
"utcnow_ts": "1558209713721005"
}
}
[20:01:53.732] Invocation finished in 2h2m53.488811537s with status FAILED