[03:45:53.600] New invocation is queued and will start shortly
[03:45:55.155] Starting the invocation (attempt 1)
[03:45:55.192] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[03:45:55.192] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[03:45:55.192] Buildbucket request:
{
"bucket": "luci.emscripten-releases.ci",
"client_operation_id": "9065266118526042784",
"parameters_json": "{\"builder_name\":\"win\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/emscripten-releases\",\"revision\":\"3a9d8ded1225252d66de1046c179cc5116f34ca5\"},\"id\":\"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@3a9d8ded1225252d66de1046c179cc5116f34ca5\",\"title\":\"3a9d8ded1225252d66de1046c179cc5116f34ca5\",\"url\":\"https://chromium.googlesource.com/emscripten-releases/+/3a9d8ded1225252d66de1046c179cc5116f34ca5\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/emscripten-releases\",\"revision\":\"3a9d8ded1225252d66de1046c179cc5116f34ca5\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win",
"scheduler_invocation_id:9065266118526042784",
"scheduler_job_id:emscripten-releases/win",
"user_agent:luci-scheduler",
"buildset:commit/git/3a9d8ded1225252d66de1046c179cc5116f34ca5",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/3a9d8ded1225252d66de1046c179cc5116f34ca5",
"gitiles_ref:refs/heads/master"
]
}
[03:45:56.872] Buildbucket response:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary_preference": "PROD",
"created_by": "project:emscripten-releases",
"created_ts": "1570851955426197",
"id": "8899836055945577648",
"parameters_json": "{\"builder_name\": \"win\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"3a9d8ded1225252d66de1046c179cc5116f34ca5\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@3a9d8ded1225252d66de1046c179cc5116f34ca5\", \"title\": \"3a9d8ded1225252d66de1046c179cc5116f34ca5\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/3a9d8ded1225252d66de1046c179cc5116f34ca5\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"3a9d8ded1225252d66de1046c179cc5116f34ca5\"}}",
"project": "emscripten-releases",
"result_details_json": "{\"properties\": {}}",
"service_account": "emscripten-releases-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1570851956422576",
"tags": [
"builder:win",
"buildset:commit/git/3a9d8ded1225252d66de1046c179cc5116f34ca5",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/3a9d8ded1225252d66de1046c179cc5116f34ca5",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9065266118526042784",
"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/8899836055945577648/+/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": "1570851956422702",
"url": "https://ci.chromium.org/b/8899836055945577648",
"utcnow_ts": "1570851956824635"
}
}
[03:45:56.872] Task URL: https://ci.chromium.org/b/8899836055945577648
[03:45:56.872] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:2:0) after 1m0s
[03:46:56.963] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:2:0)
[03:46:56.963] Timer tick, asking Buildbucket for the build status
[03:46:57.087] Build 8899836055945577648: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:46:57.087] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:3:0) after 1m0s
[03:47:57.139] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:3:0)
[03:47:57.139] Timer tick, asking Buildbucket for the build status
[03:47:57.320] Build 8899836055945577648: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:47:57.320] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:4:0) after 1m0s
[03:48:57.304] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:4:0)
[03:48:57.304] Timer tick, asking Buildbucket for the build status
[03:48:57.612] Build 8899836055945577648: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:48:57.612] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:5:0) after 1m0s
[03:49:57.594] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:5:0)
[03:49:57.594] Timer tick, asking Buildbucket for the build status
[03:49:57.745] Build 8899836055945577648: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:49:57.745] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:6:0) after 1m0s
[03:50:57.819] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:6:0)
[03:50:57.819] Timer tick, asking Buildbucket for the build status
[03:50:57.966] Build 8899836055945577648: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:50:57.966] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:7:0) after 1m0s
[03:51:58.218] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:7:0)
[03:51:58.218] Timer tick, asking Buildbucket for the build status
[03:51:58.974] Build 8899836055945577648: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:51:58.974] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:8:0) after 1m0s
[03:52:59.139] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:8:0)
[03:52:59.139] Timer tick, asking Buildbucket for the build status
[03:52:59.238] Build 8899836055945577648: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:52:59.238] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:9:0) after 1m0s
[03:53:59.304] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:9:0)
[03:53:59.304] Timer tick, asking Buildbucket for the build status
[03:53:59.446] Build 8899836055945577648: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:53:59.446] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:10:0) after 1m0s
[03:54:59.521] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:10:0)
[03:54:59.521] Timer tick, asking Buildbucket for the build status
[03:54:59.624] Build 8899836055945577648: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:54:59.624] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:11:0) after 1m0s
[03:55:59.740] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:11:0)
[03:55:59.740] Timer tick, asking Buildbucket for the build status
[03:55:59.854] Build 8899836055945577648: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:55:59.854] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:12:0) after 1m0s
[03:56:59.958] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:12:0)
[03:56:59.958] Timer tick, asking Buildbucket for the build status
[03:57:00.196] Build 8899836055945577648: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:57:00.196] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:13:0) after 1m0s
[03:58:00.325] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:13:0)
[03:58:00.325] Timer tick, asking Buildbucket for the build status
[03:58:00.540] Build 8899836055945577648: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:58:00.540] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:14:0) after 1m0s
[03:59:00.595] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:14:0)
[03:59:00.595] Timer tick, asking Buildbucket for the build status
[03:59:00.804] Build 8899836055945577648: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:59:00.804] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:15:0) after 1m0s
[04:00:00.836] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:15:0)
[04:00:00.836] Timer tick, asking Buildbucket for the build status
[04:00:01.142] Build 8899836055945577648: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:00:01.142] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:16:0) after 1m0s
[04:00:31.650] Received PubSub notification, asking Buildbucket for the build status
[04:00:31.800] Build 8899836055945577648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:01:01.192] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:16:0)
[04:01:01.192] Timer tick, asking Buildbucket for the build status
[04:01:01.324] Build 8899836055945577648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:01:01.324] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:18:0) after 1m0s
[04:02:01.362] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:18:0)
[04:02:01.362] Timer tick, asking Buildbucket for the build status
[04:02:02.322] Build 8899836055945577648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:02:02.322] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:19:0) after 1m0s
[04:03:02.397] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:19:0)
[04:03:02.397] Timer tick, asking Buildbucket for the build status
[04:03:02.628] Build 8899836055945577648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:03:02.628] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:20:0) after 1m0s
[04:04:02.715] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:20:0)
[04:04:02.715] Timer tick, asking Buildbucket for the build status
[04:04:02.915] Build 8899836055945577648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:04:02.915] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:21:0) after 1m0s
[04:05:02.948] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:21:0)
[04:05:02.948] Timer tick, asking Buildbucket for the build status
[04:05:03.149] Build 8899836055945577648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:05:03.149] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:22:0) after 1m0s
[04:06:03.472] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:22:0)
[04:06:03.472] Timer tick, asking Buildbucket for the build status
[04:06:03.635] Build 8899836055945577648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:06:03.636] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:23:0) after 1m0s
[04:07:03.757] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:23:0)
[04:07:03.757] Timer tick, asking Buildbucket for the build status
[04:07:03.915] Build 8899836055945577648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:07:03.915] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:24:0) after 1m0s
[04:08:03.980] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:24:0)
[04:08:03.980] Timer tick, asking Buildbucket for the build status
[04:08:04.211] Build 8899836055945577648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:08:04.211] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:25:0) after 1m0s
[04:09:04.221] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:25:0)
[04:09:04.221] Timer tick, asking Buildbucket for the build status
[04:09:04.377] Build 8899836055945577648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:09:04.377] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:26:0) after 1m0s
[04:10:04.516] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:26:0)
[04:10:04.516] Timer tick, asking Buildbucket for the build status
[04:10:04.692] Build 8899836055945577648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:10:04.692] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:27:0) after 1m0s
[04:11:04.844] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:27:0)
[04:11:04.845] Timer tick, asking Buildbucket for the build status
[04:11:05.032] Build 8899836055945577648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:11:05.032] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:28:0) after 1m0s
[04:12:05.056] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:28:0)
[04:12:05.056] Timer tick, asking Buildbucket for the build status
[04:12:05.301] Build 8899836055945577648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:12:05.301] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:29:0) after 1m0s
[04:13:05.358] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:29:0)
[04:13:05.358] Timer tick, asking Buildbucket for the build status
[04:13:05.546] Build 8899836055945577648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:13:05.546] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:30:0) after 1m0s
[04:14:05.629] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:30:0)
[04:14:05.629] Timer tick, asking Buildbucket for the build status
[04:14:06.014] Build 8899836055945577648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:14:06.014] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:31:0) after 1m0s
[04:15:06.111] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:31:0)
[04:15:06.111] Timer tick, asking Buildbucket for the build status
[04:15:06.450] Build 8899836055945577648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:15:06.450] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:32:0) after 1m0s
[04:16:06.504] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:32:0)
[04:16:06.504] Timer tick, asking Buildbucket for the build status
[04:16:06.788] Build 8899836055945577648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:16:06.788] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:33:0) after 1m0s
[04:17:06.847] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:33:0)
[04:17:06.847] Timer tick, asking Buildbucket for the build status
[04:17:07.004] Build 8899836055945577648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:17:07.004] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:34:0) after 1m0s
[04:18:07.132] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:34:0)
[04:18:07.132] Timer tick, asking Buildbucket for the build status
[04:18:08.080] Build 8899836055945577648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:18:08.080] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:35:0) after 1m0s
[04:19:08.141] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:35:0)
[04:19:08.141] Timer tick, asking Buildbucket for the build status
[04:19:08.397] Build 8899836055945577648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:19:08.397] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:36:0) after 1m0s
[04:20:08.656] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:36:0)
[04:20:08.656] Timer tick, asking Buildbucket for the build status
[04:20:09.015] Build 8899836055945577648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:20:09.015] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:37:0) after 1m0s
[04:21:09.117] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:37:0)
[04:21:09.117] Timer tick, asking Buildbucket for the build status
[04:21:09.259] Build 8899836055945577648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:21:09.259] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:38:0) after 1m0s
[04:22:09.411] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:38:0)
[04:22:09.411] Timer tick, asking Buildbucket for the build status
[04:22:09.921] Build 8899836055945577648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:22:09.921] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:39:0) after 1m0s
[04:23:10.092] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:39:0)
[04:23:10.092] Timer tick, asking Buildbucket for the build status
[04:23:10.185] Build 8899836055945577648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:23:10.185] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:40:0) after 1m0s
[04:24:10.458] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:40:0)
[04:24:10.458] Timer tick, asking Buildbucket for the build status
[04:24:10.815] Build 8899836055945577648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:24:10.815] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:41:0) after 1m0s
[04:25:10.896] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:41:0)
[04:25:10.896] Timer tick, asking Buildbucket for the build status
[04:25:11.183] Build 8899836055945577648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:25:11.183] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:42:0) after 1m0s
[04:26:11.243] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:42:0)
[04:26:11.243] Timer tick, asking Buildbucket for the build status
[04:26:11.410] Build 8899836055945577648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:26:11.410] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:43:0) after 1m0s
[04:27:11.481] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:43:0)
[04:27:11.481] Timer tick, asking Buildbucket for the build status
[04:27:11.807] Build 8899836055945577648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:27:11.807] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:44:0) after 1m0s
[04:28:11.943] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:44:0)
[04:28:11.943] Timer tick, asking Buildbucket for the build status
[04:28:12.131] Build 8899836055945577648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:28:12.131] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:45:0) after 1m0s
[04:29:12.270] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:45:0)
[04:29:12.270] Timer tick, asking Buildbucket for the build status
[04:29:12.423] Build 8899836055945577648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:29:12.423] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:46:0) after 1m0s
[04:30:12.475] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:46:0)
[04:30:12.475] Timer tick, asking Buildbucket for the build status
[04:30:14.745] Build 8899836055945577648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:30:14.745] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:47:0) after 1m0s
[04:31:14.811] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:47:0)
[04:31:14.811] Timer tick, asking Buildbucket for the build status
[04:31:14.961] Build 8899836055945577648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:31:14.961] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:48:0) after 1m0s
[04:32:14.996] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:48:0)
[04:32:14.996] Timer tick, asking Buildbucket for the build status
[04:32:15.376] Build 8899836055945577648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:32:15.376] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:49:0) after 1m0s
[04:33:15.450] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:49:0)
[04:33:15.450] Timer tick, asking Buildbucket for the build status
[04:33:15.602] Build 8899836055945577648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:33:15.602] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:50:0) after 1m0s
[04:34:15.626] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:50:0)
[04:34:15.626] Timer tick, asking Buildbucket for the build status
[04:34:15.809] Build 8899836055945577648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:34:15.809] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:51:0) after 1m0s
[04:35:15.858] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:51:0)
[04:35:15.858] Timer tick, asking Buildbucket for the build status
[04:35:15.968] Build 8899836055945577648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:35:15.968] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:52:0) after 1m0s
[04:36:16.042] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:52:0)
[04:36:16.042] Timer tick, asking Buildbucket for the build status
[04:36:16.278] Build 8899836055945577648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:36:16.278] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:53:0) after 1m0s
[04:37:16.305] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:53:0)
[04:37:16.305] Timer tick, asking Buildbucket for the build status
[04:37:16.563] Build 8899836055945577648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:37:16.563] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:54:0) after 1m0s
[04:38:16.761] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:54:0)
[04:38:16.761] Timer tick, asking Buildbucket for the build status
[04:38:17.043] Build 8899836055945577648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:38:17.043] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:55:0) after 1m0s
[04:39:17.228] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:55:0)
[04:39:17.228] Timer tick, asking Buildbucket for the build status
[04:39:17.384] Build 8899836055945577648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:39:17.384] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:56:0) after 1m0s
[04:40:17.471] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:56:0)
[04:40:17.471] Timer tick, asking Buildbucket for the build status
[04:40:17.567] Build 8899836055945577648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:40:17.567] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:57:0) after 1m0s
[04:41:17.659] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:57:0)
[04:41:17.659] Timer tick, asking Buildbucket for the build status
[04:41:17.809] Build 8899836055945577648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:41:17.809] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:58:0) after 1m0s
[04:42:17.885] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:58:0)
[04:42:17.885] Timer tick, asking Buildbucket for the build status
[04:42:18.038] Build 8899836055945577648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:42:18.038] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:59:0) after 1m0s
[04:43:18.260] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:59:0)
[04:43:18.260] Timer tick, asking Buildbucket for the build status
[04:43:18.556] Build 8899836055945577648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:43:18.556] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:60:0) after 1m0s
[04:44:18.586] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:60:0)
[04:44:18.586] Timer tick, asking Buildbucket for the build status
[04:44:18.718] Build 8899836055945577648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:44:18.718] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:61:0) after 1m0s
[04:45:18.858] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:61:0)
[04:45:18.858] Timer tick, asking Buildbucket for the build status
[04:45:19.157] Build 8899836055945577648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:45:19.157] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:62:0) after 1m0s
[04:46:19.291] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:62:0)
[04:46:19.291] Timer tick, asking Buildbucket for the build status
[04:46:19.604] Build 8899836055945577648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:46:19.604] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:63:0) after 1m0s
[04:47:19.746] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:63:0)
[04:47:19.746] Timer tick, asking Buildbucket for the build status
[04:47:20.584] Build 8899836055945577648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:47:20.584] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:64:0) after 1m0s
[04:48:20.619] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:64:0)
[04:48:20.619] Timer tick, asking Buildbucket for the build status
[04:48:20.950] Build 8899836055945577648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:48:20.950] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:65:0) after 1m0s
[04:49:21.052] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:65:0)
[04:49:21.052] Timer tick, asking Buildbucket for the build status
[04:49:21.178] Build 8899836055945577648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:49:21.178] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:66:0) after 1m0s
[04:50:21.239] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:66:0)
[04:50:21.239] Timer tick, asking Buildbucket for the build status
[04:50:21.361] Build 8899836055945577648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:50:21.361] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:67:0) after 1m0s
[04:51:21.387] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:67:0)
[04:51:21.387] Timer tick, asking Buildbucket for the build status
[04:51:21.620] Build 8899836055945577648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:51:21.620] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:68:0) after 1m0s
[04:52:21.645] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:68:0)
[04:52:21.645] Timer tick, asking Buildbucket for the build status
[04:52:21.924] Build 8899836055945577648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:52:21.924] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:69:0) after 1m0s
[04:53:22.015] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:69:0)
[04:53:22.015] Timer tick, asking Buildbucket for the build status
[04:53:22.184] Build 8899836055945577648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:53:22.184] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:70:0) after 1m0s
[04:54:22.308] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:70:0)
[04:54:22.308] Timer tick, asking Buildbucket for the build status
[04:54:22.512] Build 8899836055945577648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:54:22.512] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:71:0) after 1m0s
[04:55:22.540] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:71:0)
[04:55:22.540] Timer tick, asking Buildbucket for the build status
[04:55:22.703] Build 8899836055945577648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:55:22.703] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:72:0) after 1m0s
[04:56:22.730] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:72:0)
[04:56:22.730] Timer tick, asking Buildbucket for the build status
[04:56:22.966] Build 8899836055945577648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:56:22.966] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:73:0) after 1m0s
[04:57:22.949] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:73:0)
[04:57:22.949] Timer tick, asking Buildbucket for the build status
[04:57:23.102] Build 8899836055945577648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:57:23.102] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:74:0) after 1m0s
[04:58:23.135] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:74:0)
[04:58:23.135] Timer tick, asking Buildbucket for the build status
[04:58:23.276] Build 8899836055945577648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:58:23.276] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:75:0) after 1m0s
[04:59:23.299] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:75:0)
[04:59:23.299] Timer tick, asking Buildbucket for the build status
[04:59:23.526] Build 8899836055945577648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:59:23.526] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:76:0) after 1m0s
[05:00:23.567] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:76:0)
[05:00:23.567] Timer tick, asking Buildbucket for the build status
[05:00:23.771] Build 8899836055945577648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:00:23.771] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:77:0) after 1m0s
[05:01:23.811] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:77:0)
[05:01:23.811] Timer tick, asking Buildbucket for the build status
[05:01:24.205] Build 8899836055945577648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:01:24.205] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:78:0) after 1m0s
[05:02:24.234] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:78:0)
[05:02:24.234] Timer tick, asking Buildbucket for the build status
[05:02:24.545] Build 8899836055945577648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:02:24.545] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:79:0) after 1m0s
[05:03:24.646] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:79:0)
[05:03:24.646] Timer tick, asking Buildbucket for the build status
[05:03:24.831] Build 8899836055945577648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:03:24.831] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:80:0) after 1m0s
[05:04:24.888] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:80:0)
[05:04:24.888] Timer tick, asking Buildbucket for the build status
[05:04:25.135] Build 8899836055945577648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:04:25.135] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:81:0) after 1m0s
[05:05:25.211] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:81:0)
[05:05:25.211] Timer tick, asking Buildbucket for the build status
[05:05:25.373] Build 8899836055945577648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:05:25.373] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:82:0) after 1m0s
[05:06:25.365] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:82:0)
[05:06:25.365] Timer tick, asking Buildbucket for the build status
[05:06:25.751] Build 8899836055945577648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:06:25.751] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:83:0) after 1m0s
[05:07:25.934] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:83:0)
[05:07:25.934] Timer tick, asking Buildbucket for the build status
[05:07:26.115] Build 8899836055945577648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:07:26.115] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:84:0) after 1m0s
[05:08:26.253] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:84:0)
[05:08:26.253] Timer tick, asking Buildbucket for the build status
[05:08:26.580] Build 8899836055945577648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:08:26.580] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:85:0) after 1m0s
[05:09:26.635] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:85:0)
[05:09:26.635] Timer tick, asking Buildbucket for the build status
[05:09:26.754] Build 8899836055945577648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:09:26.754] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:86:0) after 1m0s
[05:10:26.777] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:86:0)
[05:10:26.777] Timer tick, asking Buildbucket for the build status
[05:10:27.113] Build 8899836055945577648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:10:27.113] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:87:0) after 1m0s
[05:11:27.148] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:87:0)
[05:11:27.148] Timer tick, asking Buildbucket for the build status
[05:11:27.351] Build 8899836055945577648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:11:27.351] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:88:0) after 1m0s
[05:12:27.351] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:88:0)
[05:12:27.351] Timer tick, asking Buildbucket for the build status
[05:12:27.487] Build 8899836055945577648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:12:27.487] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:89:0) after 1m0s
[05:13:27.579] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:89:0)
[05:13:27.579] Timer tick, asking Buildbucket for the build status
[05:13:27.679] Build 8899836055945577648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:13:27.679] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:90:0) after 1m0s
[05:14:27.704] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:90:0)
[05:14:27.704] Timer tick, asking Buildbucket for the build status
[05:14:27.830] Build 8899836055945577648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:14:27.830] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:91:0) after 1m0s
[05:15:27.951] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:91:0)
[05:15:27.951] Timer tick, asking Buildbucket for the build status
[05:15:28.427] Build 8899836055945577648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:15:28.427] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:92:0) after 1m0s
[05:16:28.484] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:92:0)
[05:16:28.484] Timer tick, asking Buildbucket for the build status
[05:16:28.603] Build 8899836055945577648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:16:28.603] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:93:0) after 1m0s
[05:17:28.620] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:93:0)
[05:17:28.620] Timer tick, asking Buildbucket for the build status
[05:17:28.911] Build 8899836055945577648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:17:28.911] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:94:0) after 1m0s
[05:18:28.970] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:94:0)
[05:18:28.970] Timer tick, asking Buildbucket for the build status
[05:18:29.133] Build 8899836055945577648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:18:29.133] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:95:0) after 1m0s
[05:19:29.186] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:95:0)
[05:19:29.186] Timer tick, asking Buildbucket for the build status
[05:19:29.711] Build 8899836055945577648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:19:29.711] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:96:0) after 1m0s
[05:20:29.915] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:96:0)
[05:20:29.915] Timer tick, asking Buildbucket for the build status
[05:20:30.022] Build 8899836055945577648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:20:30.022] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:97:0) after 1m0s
[05:21:30.089] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:97:0)
[05:21:30.089] Timer tick, asking Buildbucket for the build status
[05:21:30.269] Build 8899836055945577648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:21:30.269] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:98:0) after 1m0s
[05:22:30.353] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:98:0)
[05:22:30.353] Timer tick, asking Buildbucket for the build status
[05:22:30.512] Build 8899836055945577648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:22:30.512] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:99:0) after 1m0s
[05:23:30.602] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:99:0)
[05:23:30.602] Timer tick, asking Buildbucket for the build status
[05:23:31.049] Build 8899836055945577648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:23:31.049] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:100:0) after 1m0s
[05:24:31.169] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:100:0)
[05:24:31.169] Timer tick, asking Buildbucket for the build status
[05:24:31.621] Build 8899836055945577648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:24:31.621] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:101:0) after 1m0s
[05:25:31.697] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:101:0)
[05:25:31.697] Timer tick, asking Buildbucket for the build status
[05:25:31.801] Build 8899836055945577648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:25:31.801] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:102:0) after 1m0s
[05:26:31.979] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:102:0)
[05:26:31.979] Timer tick, asking Buildbucket for the build status
[05:26:32.178] Build 8899836055945577648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:26:32.179] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:103:0) after 1m0s
[05:27:32.209] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:103:0)
[05:27:32.209] Timer tick, asking Buildbucket for the build status
[05:27:33.310] Build 8899836055945577648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:27:33.310] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:104:0) after 1m0s
[05:28:33.325] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:104:0)
[05:28:33.325] Timer tick, asking Buildbucket for the build status
[05:28:33.670] Build 8899836055945577648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:28:33.670] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:105:0) after 1m0s
[05:29:34.006] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:105:0)
[05:29:34.006] Timer tick, asking Buildbucket for the build status
[05:29:34.192] Build 8899836055945577648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:29:34.192] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:106:0) after 1m0s
[05:30:34.297] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:106:0)
[05:30:34.297] Timer tick, asking Buildbucket for the build status
[05:30:34.485] Build 8899836055945577648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:30:34.485] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:107:0) after 1m0s
[05:31:34.565] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:107:0)
[05:31:34.565] Timer tick, asking Buildbucket for the build status
[05:31:34.753] Build 8899836055945577648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:31:34.753] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:108:0) after 1m0s
[05:32:34.796] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:108:0)
[05:32:34.796] Timer tick, asking Buildbucket for the build status
[05:32:34.918] Build 8899836055945577648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:32:34.918] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:109:0) after 1m0s
[05:33:34.953] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:109:0)
[05:33:34.953] Timer tick, asking Buildbucket for the build status
[05:33:35.197] Build 8899836055945577648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:33:35.197] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:110:0) after 1m0s
[05:34:35.231] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:110:0)
[05:34:35.231] Timer tick, asking Buildbucket for the build status
[05:34:35.521] Build 8899836055945577648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:34:35.521] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:111:0) after 1m0s
[05:35:35.552] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:111:0)
[05:35:35.552] Timer tick, asking Buildbucket for the build status
[05:35:36.638] Build 8899836055945577648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:35:36.638] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:112:0) after 1m0s
[05:36:36.748] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:112:0)
[05:36:36.748] Timer tick, asking Buildbucket for the build status
[05:36:36.875] Build 8899836055945577648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:36:36.875] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:113:0) after 1m0s
[05:37:37.239] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:113:0)
[05:37:37.239] Timer tick, asking Buildbucket for the build status
[05:37:37.481] Build 8899836055945577648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:37:37.481] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:114:0) after 1m0s
[05:38:37.599] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:114:0)
[05:38:37.599] Timer tick, asking Buildbucket for the build status
[05:38:37.899] Build 8899836055945577648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:38:37.899] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:115:0) after 1m0s
[05:39:38.057] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:115:0)
[05:39:38.057] Timer tick, asking Buildbucket for the build status
[05:39:38.311] Build 8899836055945577648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:39:38.312] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:116:0) after 1m0s
[05:40:38.545] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:116:0)
[05:40:38.545] Timer tick, asking Buildbucket for the build status
[05:40:38.781] Build 8899836055945577648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:40:38.781] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:117:0) after 1m0s
[05:41:38.883] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:117:0)
[05:41:38.883] Timer tick, asking Buildbucket for the build status
[05:41:39.126] Build 8899836055945577648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:41:39.126] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:118:0) after 1m0s
[05:42:39.248] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:118:0)
[05:42:39.248] Timer tick, asking Buildbucket for the build status
[05:42:39.367] Build 8899836055945577648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:42:39.367] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:119:0) after 1m0s
[05:43:39.707] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:119:0)
[05:43:39.707] Timer tick, asking Buildbucket for the build status
[05:43:39.892] Build 8899836055945577648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:43:39.892] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:120:0) after 1m0s
[05:44:40.168] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:120:0)
[05:44:40.168] Timer tick, asking Buildbucket for the build status
[05:44:40.285] Build 8899836055945577648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:44:40.285] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:121:0) after 1m0s
[05:45:40.364] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:121:0)
[05:45:40.364] Timer tick, asking Buildbucket for the build status
[05:45:40.565] Build 8899836055945577648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:45:40.565] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:122:0) after 1m0s
[05:46:40.670] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:122:0)
[05:46:40.670] Timer tick, asking Buildbucket for the build status
[05:46:40.789] Build 8899836055945577648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:46:40.789] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:123:0) after 1m0s
[05:47:41.058] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:123:0)
[05:47:41.058] Timer tick, asking Buildbucket for the build status
[05:47:41.184] Build 8899836055945577648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:47:41.184] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:124:0) after 1m0s
[05:48:41.460] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:124:0)
[05:48:41.460] Timer tick, asking Buildbucket for the build status
[05:48:41.691] Build 8899836055945577648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:48:41.691] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:125:0) after 1m0s
[05:49:41.675] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:125:0)
[05:49:41.675] Timer tick, asking Buildbucket for the build status
[05:49:41.898] Build 8899836055945577648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:49:41.898] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:126:0) after 1m0s
[05:50:41.976] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:126:0)
[05:50:41.976] Timer tick, asking Buildbucket for the build status
[05:50:42.105] Build 8899836055945577648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:50:42.105] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:127:0) after 1m0s
[05:51:42.129] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:127:0)
[05:51:42.129] Timer tick, asking Buildbucket for the build status
[05:51:44.333] Build 8899836055945577648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:51:44.333] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:128:0) after 1m0s
[05:52:44.351] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:128:0)
[05:52:44.351] Timer tick, asking Buildbucket for the build status
[05:52:44.508] Build 8899836055945577648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:52:44.508] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:129:0) after 1m0s
[05:53:44.753] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:129:0)
[05:53:44.753] Timer tick, asking Buildbucket for the build status
[05:53:44.914] Build 8899836055945577648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:53:44.914] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:130:0) after 1m0s
[05:54:45.006] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:130:0)
[05:54:45.006] Timer tick, asking Buildbucket for the build status
[05:54:45.334] Build 8899836055945577648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:54:45.334] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:131:0) after 1m0s
[05:55:45.470] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:131:0)
[05:55:45.470] Timer tick, asking Buildbucket for the build status
[05:55:45.628] Build 8899836055945577648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:55:45.628] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:132:0) after 1m0s
[05:56:45.702] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:132:0)
[05:56:45.702] Timer tick, asking Buildbucket for the build status
[05:56:46.012] Build 8899836055945577648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:56:46.012] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:133:0) after 1m0s
[05:57:46.130] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:133:0)
[05:57:46.130] Timer tick, asking Buildbucket for the build status
[05:57:46.289] Build 8899836055945577648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:57:46.289] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:134:0) after 1m0s
[05:58:46.403] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:134:0)
[05:58:46.403] Timer tick, asking Buildbucket for the build status
[05:58:46.847] Build 8899836055945577648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:58:46.847] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:135:0) after 1m0s
[05:59:46.932] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:135:0)
[05:59:46.932] Timer tick, asking Buildbucket for the build status
[05:59:47.082] Build 8899836055945577648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:59:47.082] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:136:0) after 1m0s
[06:00:47.117] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:136:0)
[06:00:47.117] Timer tick, asking Buildbucket for the build status
[06:00:48.529] Build 8899836055945577648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:00:48.529] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:137:0) after 1m0s
[06:01:48.602] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:137:0)
[06:01:48.602] Timer tick, asking Buildbucket for the build status
[06:01:48.796] Build 8899836055945577648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:01:48.796] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:138:0) after 1m0s
[06:02:48.872] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:138:0)
[06:02:48.872] Timer tick, asking Buildbucket for the build status
[06:02:49.198] Build 8899836055945577648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:02:49.198] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:139:0) after 1m0s
[06:03:49.396] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:139:0)
[06:03:49.397] Timer tick, asking Buildbucket for the build status
[06:03:50.184] Build 8899836055945577648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:03:50.184] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065266118526042784:140:0) after 1m0s
[06:03:56.343] Received PubSub notification, asking Buildbucket for the build status
[06:03:56.542] Build 8899836055945577648: status "COMPLETED", result "CANCELED", failure_reason "", cancelation_reason "TIMEOUT"
[06:03:56.542] Buildbucket build:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary_preference": "PROD",
"cancelation_reason": "TIMEOUT",
"completed_ts": "1570860234114996",
"created_by": "project:emscripten-releases",
"created_ts": "1570851955426197",
"id": "8899836055945577648",
"parameters_json": "{\"builder_name\": \"win\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"3a9d8ded1225252d66de1046c179cc5116f34ca5\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@3a9d8ded1225252d66de1046c179cc5116f34ca5\", \"title\": \"3a9d8ded1225252d66de1046c179cc5116f34ca5\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/3a9d8ded1225252d66de1046c179cc5116f34ca5\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"3a9d8ded1225252d66de1046c179cc5116f34ca5\"}}",
"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}, \"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"3a9d8ded1225252d66de1046c179cc5116f34ca5\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@3a9d8ded1225252d66de1046c179cc5116f34ca5\", \"title\": \"3a9d8ded1225252d66de1046c179cc5116f34ca5\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/3a9d8ded1225252d66de1046c179cc5116f34ca5\"}]}, \"bot_id\": \"luci-emscripten-releases-ci-win10-0-7kl5\", \"branch\": \"refs/heads/master\", \"buildername\": \"win\", \"got_revision\": \"3a9d8ded1225252d66de1046c179cc5116f34ca5\", \"path_config\": \"generic\", \"recipe\": \"emscripten_releases\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"3a9d8ded1225252d66de1046c179cc5116f34ca5\"}, \"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-7kl5\"], \"image\": [\"chrome-win10-1703-19091300-a244b1e46d2\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"n1-standard-8\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.emscripten-releases.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"4501-487d554\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-b\"]}}}",
"service_account": "emscripten-releases-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1570852830821196",
"status": "COMPLETED",
"status_changed_ts": "1570860234541406",
"tags": [
"builder:win",
"buildset:commit/git/3a9d8ded1225252d66de1046c179cc5116f34ca5",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/3a9d8ded1225252d66de1046c179cc5116f34ca5",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9065266118526042784",
"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/8899836055945577648/+/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:47d6e43a7fd14f10",
"user_agent:luci-scheduler"
],
"updated_ts": "1570860234561721",
"url": "https://ci.chromium.org/b/8899836055945577648",
"utcnow_ts": "1570860236521017"
}
}
[06:03:56.542] Invocation finished in 2h18m2.960942623s with status FAILED