[02:50:53.375] New invocation is queued and will start shortly
[02:50:54.498] Starting the invocation (attempt 1)
[02:50:54.553] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[02:50:54.553] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[02:50:54.553] Buildbucket request:
{
"bucket": "luci.emscripten-releases.ci",
"client_operation_id": "9078859124033563968",
"parameters_json": "{\"builder_name\":\"win\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/emscripten-releases\",\"revision\":\"c207b83593832c95c909e5cbda6d062e8fba5f34\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win",
"scheduler_invocation_id:9078859124033563968",
"scheduler_job_id:emscripten-releases/win",
"user_agent:luci-scheduler",
"buildset:commit/git/c207b83593832c95c909e5cbda6d062e8fba5f34",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/c207b83593832c95c909e5cbda6d062e8fba5f34",
"gitiles_ref:refs/heads/master"
]
}
[02:50:55.554] Buildbucket response:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary": true,
"canary_preference": "AUTO",
"created_by": "project:emscripten-releases",
"created_ts": "1557888654662392",
"id": "8913429062007815872",
"parameters_json": "{\"builder_name\": \"win\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"c207b83593832c95c909e5cbda6d062e8fba5f34\"}}",
"project": "emscripten-releases",
"result_details_json": "{\"properties\": {}}",
"service_account": "emscripten-releases-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1557888655050769",
"tags": [
"builder:win",
"buildset:commit/git/c207b83593832c95c909e5cbda6d062e8fba5f34",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/c207b83593832c95c909e5cbda6d062e8fba5f34",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9078859124033563968",
"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/8913429062007815872/+/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": "1557888655050859",
"url": "https://ci.chromium.org/b/8913429062007815872",
"utcnow_ts": "1557888655533189"
}
}
[02:50:55.554] Task URL: https://ci.chromium.org/b/8913429062007815872
[02:50:55.554] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:2:0) after 1m0s
[02:51:55.575] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:2:0)
[02:51:55.575] Timer tick, asking Buildbucket for the build status
[02:51:55.736] Build 8913429062007815872: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:51:55.736] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:3:0) after 1m0s
[02:52:40.257] Received PubSub notification, asking Buildbucket for the build status
[02:52:40.309] Build 8913429062007815872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:52:55.769] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:3:0)
[02:52:55.770] Timer tick, asking Buildbucket for the build status
[02:52:55.909] Build 8913429062007815872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:52:55.909] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:5:0) after 1m0s
[02:53:55.939] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:5:0)
[02:53:55.939] Timer tick, asking Buildbucket for the build status
[02:53:56.058] Build 8913429062007815872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:53:56.058] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:6:0) after 1m0s
[02:54:56.067] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:6:0)
[02:54:56.067] Timer tick, asking Buildbucket for the build status
[02:54:56.149] Build 8913429062007815872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:54:56.149] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:7:0) after 1m0s
[02:55:56.145] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:7:0)
[02:55:56.145] Timer tick, asking Buildbucket for the build status
[02:55:56.196] Build 8913429062007815872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:55:56.196] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:8:0) after 1m0s
[02:56:56.209] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:8:0)
[02:56:56.209] Timer tick, asking Buildbucket for the build status
[02:56:56.342] Build 8913429062007815872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:56:56.342] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:9:0) after 1m0s
[02:57:56.361] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:9:0)
[02:57:56.361] Timer tick, asking Buildbucket for the build status
[02:57:56.446] Build 8913429062007815872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:57:56.446] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:10:0) after 1m0s
[02:58:56.480] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:10:0)
[02:58:56.480] Timer tick, asking Buildbucket for the build status
[02:58:56.526] Build 8913429062007815872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:58:56.526] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:11:0) after 1m0s
[02:59:56.549] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:11:0)
[02:59:56.549] Timer tick, asking Buildbucket for the build status
[02:59:56.619] Build 8913429062007815872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:59:56.619] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:12:0) after 1m0s
[03:00:56.639] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:12:0)
[03:00:56.639] Timer tick, asking Buildbucket for the build status
[03:00:56.982] Build 8913429062007815872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:00:56.982] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:13:0) after 1m0s
[03:01:57.099] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:13:0)
[03:01:57.099] Timer tick, asking Buildbucket for the build status
[03:01:57.518] Build 8913429062007815872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:01:57.518] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:14:0) after 1m0s
[03:02:57.545] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:14:0)
[03:02:57.545] Timer tick, asking Buildbucket for the build status
[03:02:57.608] Build 8913429062007815872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:02:57.608] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:15:0) after 1m0s
[03:03:57.695] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:15:0)
[03:03:57.695] Timer tick, asking Buildbucket for the build status
[03:03:57.865] Build 8913429062007815872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:03:57.865] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:16:0) after 1m0s
[03:04:57.882] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:16:0)
[03:04:57.882] Timer tick, asking Buildbucket for the build status
[03:04:57.988] Build 8913429062007815872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:04:57.988] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:17:0) after 1m0s
[03:05:58.146] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:17:0)
[03:05:58.146] Timer tick, asking Buildbucket for the build status
[03:05:58.228] Build 8913429062007815872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:05:58.228] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:18:0) after 1m0s
[03:06:58.251] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:18:0)
[03:06:58.251] Timer tick, asking Buildbucket for the build status
[03:06:58.397] Build 8913429062007815872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:06:58.398] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:19:0) after 1m0s
[03:07:58.427] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:19:0)
[03:07:58.427] Timer tick, asking Buildbucket for the build status
[03:07:58.590] Build 8913429062007815872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:07:58.590] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:20:0) after 1m0s
[03:08:58.713] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:20:0)
[03:08:58.713] Timer tick, asking Buildbucket for the build status
[03:08:58.817] Build 8913429062007815872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:08:58.817] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:21:0) after 1m0s
[03:09:58.818] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:21:0)
[03:09:58.818] Timer tick, asking Buildbucket for the build status
[03:09:58.905] Build 8913429062007815872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:09:58.905] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:22:0) after 1m0s
[03:10:58.920] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:22:0)
[03:10:58.920] Timer tick, asking Buildbucket for the build status
[03:10:58.986] Build 8913429062007815872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:10:58.986] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:23:0) after 1m0s
[03:11:59.023] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:23:0)
[03:11:59.023] Timer tick, asking Buildbucket for the build status
[03:11:59.093] Build 8913429062007815872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:11:59.093] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:24:0) after 1m0s
[03:12:59.237] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:24:0)
[03:12:59.237] Timer tick, asking Buildbucket for the build status
[03:12:59.381] Build 8913429062007815872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:12:59.381] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:25:0) after 1m0s
[03:13:59.412] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:25:0)
[03:13:59.412] Timer tick, asking Buildbucket for the build status
[03:13:59.545] Build 8913429062007815872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:13:59.546] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:26:0) after 1m0s
[03:14:59.556] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:26:0)
[03:14:59.556] Timer tick, asking Buildbucket for the build status
[03:14:59.741] Build 8913429062007815872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:14:59.741] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:27:0) after 1m0s
[03:15:59.788] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:27:0)
[03:15:59.788] Timer tick, asking Buildbucket for the build status
[03:15:59.845] Build 8913429062007815872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:15:59.845] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:28:0) after 1m0s
[03:16:59.885] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:28:0)
[03:16:59.885] Timer tick, asking Buildbucket for the build status
[03:16:59.936] Build 8913429062007815872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:16:59.936] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:29:0) after 1m0s
[03:18:00.043] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:29:0)
[03:18:00.043] Timer tick, asking Buildbucket for the build status
[03:18:00.165] Build 8913429062007815872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:18:00.165] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:30:0) after 1m0s
[03:19:00.193] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:30:0)
[03:19:00.193] Timer tick, asking Buildbucket for the build status
[03:19:00.342] Build 8913429062007815872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:19:00.342] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:31:0) after 1m0s
[03:20:00.372] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:31:0)
[03:20:00.372] Timer tick, asking Buildbucket for the build status
[03:20:00.429] Build 8913429062007815872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:20:00.429] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:32:0) after 1m0s
[03:21:00.462] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:32:0)
[03:21:00.462] Timer tick, asking Buildbucket for the build status
[03:21:00.518] Build 8913429062007815872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:21:00.518] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:33:0) after 1m0s
[03:22:00.541] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:33:0)
[03:22:00.541] Timer tick, asking Buildbucket for the build status
[03:22:00.655] Build 8913429062007815872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:22:00.655] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:34:0) after 1m0s
[03:23:00.676] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:34:0)
[03:23:00.676] Timer tick, asking Buildbucket for the build status
[03:23:00.782] Build 8913429062007815872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:23:00.782] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:35:0) after 1m0s
[03:24:00.784] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:35:0)
[03:24:00.784] Timer tick, asking Buildbucket for the build status
[03:24:01.187] Build 8913429062007815872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:24:01.187] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:36:0) after 1m0s
[03:25:01.266] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:36:0)
[03:25:01.266] Timer tick, asking Buildbucket for the build status
[03:25:01.538] Build 8913429062007815872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:25:01.538] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:37:0) after 1m0s
[03:26:01.556] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:37:0)
[03:26:01.556] Timer tick, asking Buildbucket for the build status
[03:26:01.851] Build 8913429062007815872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:26:01.851] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:38:0) after 1m0s
[03:27:01.881] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:38:0)
[03:27:01.881] Timer tick, asking Buildbucket for the build status
[03:27:02.048] Build 8913429062007815872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:27:02.048] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:39:0) after 1m0s
[03:28:02.249] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:39:0)
[03:28:02.249] Timer tick, asking Buildbucket for the build status
[03:28:02.631] Build 8913429062007815872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:28:02.631] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:40:0) after 1m0s
[03:29:02.664] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:40:0)
[03:29:02.664] Timer tick, asking Buildbucket for the build status
[03:29:02.936] Build 8913429062007815872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:29:02.937] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:41:0) after 1m0s
[03:30:02.954] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:41:0)
[03:30:02.954] Timer tick, asking Buildbucket for the build status
[03:30:03.215] Build 8913429062007815872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:30:03.215] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:42:0) after 1m0s
[03:31:03.222] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:42:0)
[03:31:03.222] Timer tick, asking Buildbucket for the build status
[03:31:03.413] Build 8913429062007815872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:31:03.413] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:43:0) after 1m0s
[03:32:03.433] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:43:0)
[03:32:03.433] Timer tick, asking Buildbucket for the build status
[03:32:03.485] Build 8913429062007815872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:32:03.485] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:44:0) after 1m0s
[03:33:03.509] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:44:0)
[03:33:03.509] Timer tick, asking Buildbucket for the build status
[03:33:03.566] Build 8913429062007815872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:33:03.566] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:45:0) after 1m0s
[03:34:03.592] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:45:0)
[03:34:03.592] Timer tick, asking Buildbucket for the build status
[03:34:03.690] Build 8913429062007815872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:34:03.690] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:46:0) after 1m0s
[03:35:03.738] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:46:0)
[03:35:03.738] Timer tick, asking Buildbucket for the build status
[03:35:03.828] Build 8913429062007815872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:35:03.828] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:47:0) after 1m0s
[03:36:03.804] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:47:0)
[03:36:03.804] Timer tick, asking Buildbucket for the build status
[03:36:04.202] Build 8913429062007815872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:36:04.202] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:48:0) after 1m0s
[03:37:04.265] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:48:0)
[03:37:04.265] Timer tick, asking Buildbucket for the build status
[03:37:04.339] Build 8913429062007815872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:37:04.339] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:49:0) after 1m0s
[03:38:04.507] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:49:0)
[03:38:04.507] Timer tick, asking Buildbucket for the build status
[03:38:05.085] Build 8913429062007815872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:38:05.085] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:50:0) after 1m0s
[03:39:05.104] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:50:0)
[03:39:05.104] Timer tick, asking Buildbucket for the build status
[03:39:05.183] Build 8913429062007815872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:39:05.183] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:51:0) after 1m0s
[03:40:05.211] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:51:0)
[03:40:05.211] Timer tick, asking Buildbucket for the build status
[03:40:05.608] Build 8913429062007815872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:40:05.608] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:52:0) after 1m0s
[03:41:05.649] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:52:0)
[03:41:05.649] Timer tick, asking Buildbucket for the build status
[03:41:05.731] Build 8913429062007815872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:41:05.731] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:53:0) after 1m0s
[03:42:05.752] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:53:0)
[03:42:05.752] Timer tick, asking Buildbucket for the build status
[03:42:05.825] Build 8913429062007815872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:42:05.825] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:54:0) after 1m0s
[03:43:05.855] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:54:0)
[03:43:05.855] Timer tick, asking Buildbucket for the build status
[03:43:05.930] Build 8913429062007815872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:43:05.930] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:55:0) after 1m0s
[03:44:05.968] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:55:0)
[03:44:05.968] Timer tick, asking Buildbucket for the build status
[03:44:06.052] Build 8913429062007815872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:44:06.052] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:56:0) after 1m0s
[03:45:06.079] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:56:0)
[03:45:06.079] Timer tick, asking Buildbucket for the build status
[03:45:06.157] Build 8913429062007815872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:45:06.157] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:57:0) after 1m0s
[03:46:06.231] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:57:0)
[03:46:06.231] Timer tick, asking Buildbucket for the build status
[03:46:06.343] Build 8913429062007815872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:46:06.343] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:58:0) after 1m0s
[03:47:06.407] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:58:0)
[03:47:06.407] Timer tick, asking Buildbucket for the build status
[03:47:06.496] Build 8913429062007815872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:47:06.496] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:59:0) after 1m0s
[03:48:06.571] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:59:0)
[03:48:06.571] Timer tick, asking Buildbucket for the build status
[03:48:06.655] Build 8913429062007815872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:48:06.655] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:60:0) after 1m0s
[03:49:06.722] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:60:0)
[03:49:06.722] Timer tick, asking Buildbucket for the build status
[03:49:06.866] Build 8913429062007815872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:49:06.866] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:61:0) after 1m0s
[03:50:06.946] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:61:0)
[03:50:06.946] Timer tick, asking Buildbucket for the build status
[03:50:07.017] Build 8913429062007815872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:50:07.017] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:62:0) after 1m0s
[03:51:07.047] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:62:0)
[03:51:07.047] Timer tick, asking Buildbucket for the build status
[03:51:07.163] Build 8913429062007815872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:51:07.163] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:63:0) after 1m0s
[03:52:07.201] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:63:0)
[03:52:07.201] Timer tick, asking Buildbucket for the build status
[03:52:07.347] Build 8913429062007815872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:52:07.347] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:64:0) after 1m0s
[03:53:07.418] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:64:0)
[03:53:07.418] Timer tick, asking Buildbucket for the build status
[03:53:07.511] Build 8913429062007815872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:53:07.511] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:65:0) after 1m0s
[03:54:07.564] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:65:0)
[03:54:07.564] Timer tick, asking Buildbucket for the build status
[03:54:07.627] Build 8913429062007815872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:54:07.627] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:66:0) after 1m0s
[03:55:07.611] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:66:0)
[03:55:07.611] Timer tick, asking Buildbucket for the build status
[03:55:07.684] Build 8913429062007815872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:55:07.684] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:67:0) after 1m0s
[03:56:07.918] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:67:0)
[03:56:07.918] Timer tick, asking Buildbucket for the build status
[03:56:08.010] Build 8913429062007815872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:56:08.010] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:68:0) after 1m0s
[03:57:08.036] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:68:0)
[03:57:08.036] Timer tick, asking Buildbucket for the build status
[03:57:08.111] Build 8913429062007815872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:57:08.111] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:69:0) after 1m0s
[03:58:08.253] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:69:0)
[03:58:08.253] Timer tick, asking Buildbucket for the build status
[03:58:08.426] Build 8913429062007815872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:58:08.427] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:70:0) after 1m0s
[03:59:08.436] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:70:0)
[03:59:08.436] Timer tick, asking Buildbucket for the build status
[03:59:08.547] Build 8913429062007815872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:59:08.547] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:71:0) after 1m0s
[04:00:08.572] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:71:0)
[04:00:08.572] Timer tick, asking Buildbucket for the build status
[04:00:08.670] Build 8913429062007815872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:00:08.670] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:72:0) after 1m0s
[04:01:08.816] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:72:0)
[04:01:08.816] Timer tick, asking Buildbucket for the build status
[04:01:09.061] Build 8913429062007815872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:01:09.061] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:73:0) after 1m0s
[04:02:09.083] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:73:0)
[04:02:09.083] Timer tick, asking Buildbucket for the build status
[04:02:09.281] Build 8913429062007815872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:02:09.281] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:74:0) after 1m0s
[04:03:09.351] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:74:0)
[04:03:09.351] Timer tick, asking Buildbucket for the build status
[04:03:09.485] Build 8913429062007815872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:03:09.485] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:75:0) after 1m0s
[04:04:09.515] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:75:0)
[04:04:09.515] Timer tick, asking Buildbucket for the build status
[04:04:09.568] Build 8913429062007815872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:04:09.568] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:76:0) after 1m0s
[04:05:09.627] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:76:0)
[04:05:09.627] Timer tick, asking Buildbucket for the build status
[04:05:09.744] Build 8913429062007815872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:05:09.744] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:77:0) after 1m0s
[04:06:09.747] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:77:0)
[04:06:09.747] Timer tick, asking Buildbucket for the build status
[04:06:09.878] Build 8913429062007815872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:06:09.878] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:78:0) after 1m0s
[04:07:09.928] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:78:0)
[04:07:09.928] Timer tick, asking Buildbucket for the build status
[04:07:10.483] Build 8913429062007815872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:07:10.483] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:79:0) after 1m0s
[04:08:10.504] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:79:0)
[04:08:10.504] Timer tick, asking Buildbucket for the build status
[04:08:10.584] Build 8913429062007815872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:08:10.584] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:80:0) after 1m0s
[04:09:10.622] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:80:0)
[04:09:10.622] Timer tick, asking Buildbucket for the build status
[04:09:10.732] Build 8913429062007815872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:09:10.732] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:81:0) after 1m0s
[04:10:10.756] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:81:0)
[04:10:10.756] Timer tick, asking Buildbucket for the build status
[04:10:10.945] Build 8913429062007815872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:10:10.945] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:82:0) after 1m0s
[04:11:10.947] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:82:0)
[04:11:10.947] Timer tick, asking Buildbucket for the build status
[04:11:11.068] Build 8913429062007815872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:11:11.068] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:83:0) after 1m0s
[04:12:11.110] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:83:0)
[04:12:11.110] Timer tick, asking Buildbucket for the build status
[04:12:11.214] Build 8913429062007815872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:12:11.214] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:84:0) after 1m0s
[04:13:11.242] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:84:0)
[04:13:11.242] Timer tick, asking Buildbucket for the build status
[04:13:11.303] Build 8913429062007815872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:13:11.303] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:85:0) after 1m0s
[04:14:11.330] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:85:0)
[04:14:11.330] Timer tick, asking Buildbucket for the build status
[04:14:11.397] Build 8913429062007815872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:14:11.397] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:86:0) after 1m0s
[04:15:11.445] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:86:0)
[04:15:11.445] Timer tick, asking Buildbucket for the build status
[04:15:11.540] Build 8913429062007815872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:15:11.540] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:87:0) after 1m0s
[04:16:11.545] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:87:0)
[04:16:11.545] Timer tick, asking Buildbucket for the build status
[04:16:11.795] Build 8913429062007815872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:16:11.795] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:88:0) after 1m0s
[04:17:11.815] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:88:0)
[04:17:11.815] Timer tick, asking Buildbucket for the build status
[04:17:11.894] Build 8913429062007815872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:17:11.894] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:89:0) after 1m0s
[04:18:11.921] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:89:0)
[04:18:11.921] Timer tick, asking Buildbucket for the build status
[04:18:12.130] Build 8913429062007815872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:18:12.130] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:90:0) after 1m0s
[04:19:12.294] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:90:0)
[04:19:12.294] Timer tick, asking Buildbucket for the build status
[04:19:12.333] Build 8913429062007815872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:19:12.333] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:91:0) after 1m0s
[04:20:12.341] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:91:0)
[04:20:12.341] Timer tick, asking Buildbucket for the build status
[04:20:12.776] Build 8913429062007815872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:20:12.776] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:92:0) after 1m0s
[04:21:12.797] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:92:0)
[04:21:12.797] Timer tick, asking Buildbucket for the build status
[04:21:14.213] Build 8913429062007815872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:21:14.213] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:93:0) after 1m0s
[04:22:14.227] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:93:0)
[04:22:14.227] Timer tick, asking Buildbucket for the build status
[04:22:14.410] Build 8913429062007815872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:22:14.410] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:94:0) after 1m0s
[04:23:14.422] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:94:0)
[04:23:14.422] Timer tick, asking Buildbucket for the build status
[04:23:14.464] Build 8913429062007815872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:23:14.464] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:95:0) after 1m0s
[04:24:14.480] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:95:0)
[04:24:14.480] Timer tick, asking Buildbucket for the build status
[04:24:14.584] Build 8913429062007815872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:24:14.584] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:96:0) after 1m0s
[04:25:14.579] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:96:0)
[04:25:14.579] Timer tick, asking Buildbucket for the build status
[04:25:14.625] Build 8913429062007815872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:25:14.625] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:97:0) after 1m0s
[04:26:14.654] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:97:0)
[04:26:14.654] Timer tick, asking Buildbucket for the build status
[04:26:15.460] Build 8913429062007815872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:26:15.460] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:98:0) after 1m0s
[04:27:15.477] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:98:0)
[04:27:15.477] Timer tick, asking Buildbucket for the build status
[04:27:15.544] Build 8913429062007815872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:27:15.544] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:99:0) after 1m0s
[04:28:15.628] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:99:0)
[04:28:15.628] Timer tick, asking Buildbucket for the build status
[04:28:15.982] Build 8913429062007815872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:28:15.982] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:100:0) after 1m0s
[04:29:15.978] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:100:0)
[04:29:15.978] Timer tick, asking Buildbucket for the build status
[04:29:16.750] Build 8913429062007815872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:29:16.750] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:101:0) after 1m0s
[04:30:16.829] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:101:0)
[04:30:16.829] Timer tick, asking Buildbucket for the build status
[04:30:16.889] Build 8913429062007815872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:30:16.889] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:102:0) after 1m0s
[04:31:16.917] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:102:0)
[04:31:16.917] Timer tick, asking Buildbucket for the build status
[04:31:17.027] Build 8913429062007815872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:31:17.027] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:103:0) after 1m0s
[04:32:17.053] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:103:0)
[04:32:17.053] Timer tick, asking Buildbucket for the build status
[04:32:17.132] Build 8913429062007815872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:32:17.132] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:104:0) after 1m0s
[04:33:17.230] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:104:0)
[04:33:17.230] Timer tick, asking Buildbucket for the build status
[04:33:17.337] Build 8913429062007815872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:33:17.337] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:105:0) after 1m0s
[04:34:17.378] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:105:0)
[04:34:17.378] Timer tick, asking Buildbucket for the build status
[04:34:17.453] Build 8913429062007815872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:34:17.453] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:106:0) after 1m0s
[04:35:17.511] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:106:0)
[04:35:17.511] Timer tick, asking Buildbucket for the build status
[04:35:17.688] Build 8913429062007815872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:35:17.688] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:107:0) after 1m0s
[04:36:17.732] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:107:0)
[04:36:17.732] Timer tick, asking Buildbucket for the build status
[04:36:17.777] Build 8913429062007815872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:36:17.777] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:108:0) after 1m0s
[04:37:17.802] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:108:0)
[04:37:17.802] Timer tick, asking Buildbucket for the build status
[04:37:18.002] Build 8913429062007815872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:37:18.002] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:109:0) after 1m0s
[04:38:18.056] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:109:0)
[04:38:18.056] Timer tick, asking Buildbucket for the build status
[04:38:18.150] Build 8913429062007815872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:38:18.150] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:110:0) after 1m0s
[04:39:18.168] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:110:0)
[04:39:18.168] Timer tick, asking Buildbucket for the build status
[04:39:18.222] Build 8913429062007815872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:39:18.222] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:111:0) after 1m0s
[04:40:18.259] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:111:0)
[04:40:18.259] Timer tick, asking Buildbucket for the build status
[04:40:18.380] Build 8913429062007815872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:40:18.380] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:112:0) after 1m0s
[04:41:18.412] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:112:0)
[04:41:18.412] Timer tick, asking Buildbucket for the build status
[04:41:18.465] Build 8913429062007815872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:41:18.465] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:113:0) after 1m0s
[04:42:18.548] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:113:0)
[04:42:18.548] Timer tick, asking Buildbucket for the build status
[04:42:18.596] Build 8913429062007815872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:42:18.597] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:114:0) after 1m0s
[04:43:18.625] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:114:0)
[04:43:18.626] Timer tick, asking Buildbucket for the build status
[04:43:18.721] Build 8913429062007815872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:43:18.721] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:115:0) after 1m0s
[04:44:18.714] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:115:0)
[04:44:18.714] Timer tick, asking Buildbucket for the build status
[04:44:18.804] Build 8913429062007815872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:44:18.804] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:116:0) after 1m0s
[04:45:18.839] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:116:0)
[04:45:18.839] Timer tick, asking Buildbucket for the build status
[04:45:18.922] Build 8913429062007815872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:45:18.922] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:117:0) after 1m0s
[04:46:18.956] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:117:0)
[04:46:18.956] Timer tick, asking Buildbucket for the build status
[04:46:18.998] Build 8913429062007815872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:46:18.998] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:118:0) after 1m0s
[04:47:19.008] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:118:0)
[04:47:19.008] Timer tick, asking Buildbucket for the build status
[04:47:19.046] Build 8913429062007815872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:47:19.046] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:119:0) after 1m0s
[04:48:19.104] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:119:0)
[04:48:19.104] Timer tick, asking Buildbucket for the build status
[04:48:19.203] Build 8913429062007815872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:48:19.203] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:120:0) after 1m0s
[04:49:19.287] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:120:0)
[04:49:19.287] Timer tick, asking Buildbucket for the build status
[04:49:19.445] Build 8913429062007815872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:49:19.445] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:121:0) after 1m0s
[04:50:19.633] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:121:0)
[04:50:19.633] Timer tick, asking Buildbucket for the build status
[04:50:19.776] Build 8913429062007815872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:50:19.776] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:122:0) after 1m0s
[04:51:19.814] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:122:0)
[04:51:19.814] Timer tick, asking Buildbucket for the build status
[04:51:19.947] Build 8913429062007815872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:51:19.947] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:123:0) after 1m0s
[04:52:20.175] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:123:0)
[04:52:20.175] Timer tick, asking Buildbucket for the build status
[04:52:20.368] Build 8913429062007815872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:52:20.368] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:124:0) after 1m0s
[04:53:20.466] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:124:0)
[04:53:20.466] Timer tick, asking Buildbucket for the build status
[04:53:20.508] Build 8913429062007815872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:53:20.508] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:125:0) after 1m0s
[04:54:20.534] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:125:0)
[04:54:20.534] Timer tick, asking Buildbucket for the build status
[04:54:20.597] Build 8913429062007815872: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:54:20.597] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9078859124033563968:126:0) after 1m0s
[04:54:42.113] Received PubSub notification, asking Buildbucket for the build status
[04:54:42.272] Build 8913429062007815872: status "COMPLETED", result "CANCELED", failure_reason "", cancelation_reason "TIMEOUT"
[04:54:42.272] Buildbucket build:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary": true,
"canary_preference": "AUTO",
"cancelation_reason": "TIMEOUT",
"completed_ts": "1557896081261300",
"created_by": "project:emscripten-releases",
"created_ts": "1557888654662392",
"id": "8913429062007815872",
"parameters_json": "{\"builder_name\": \"win\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"c207b83593832c95c909e5cbda6d062e8fba5f34\"}}",
"project": "emscripten-releases",
"result": "CANCELED",
"result_details_json": "{\"properties\": {\"$recipe_engine/path\": {\"cache_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\cache\", \"temp_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\tmp\\\\rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-emscripten-releases-ci-win10-0-k3ps\", \"branch\": \"refs/heads/master\", \"buildername\": \"win\", \"got_revision\": \"c207b83593832c95c909e5cbda6d062e8fba5f34\", \"path_config\": \"generic\", \"recipe\": \"emscripten_releases\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"c207b83593832c95c909e5cbda6d062e8fba5f34\"}, \"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-k3ps\"], \"image\": [\"chrome-win10-1703-2018-04-20-61acaf8983f\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"n1-standard-8\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.emscripten-releases.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"4299-a0fe907\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-b\"]}}}",
"service_account": "emscripten-releases-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1557888759603128",
"status": "COMPLETED",
"status_changed_ts": "1557896081673785",
"tags": [
"builder:win",
"buildset:commit/git/c207b83593832c95c909e5cbda6d062e8fba5f34",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/c207b83593832c95c909e5cbda6d062e8fba5f34",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9078859124033563968",
"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/8913429062007815872/+/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:44d237eab1b83c10",
"user_agent:luci-scheduler"
],
"updated_ts": "1557896081675987",
"url": "https://ci.chromium.org/b/8913429062007815872",
"utcnow_ts": "1557896082259837"
}
}
[04:54:42.272] Invocation finished in 2h3m48.925631764s with status FAILED