[00:03:41.183] New invocation is queued and will start shortly
[00:03:42.670] Starting the invocation (attempt 1)
[00:03:42.720] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[00:03:42.721] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[00:03:42.721] Buildbucket request:
{
"bucket": "luci.emscripten-releases.ci",
"client_operation_id": "9076151734549463296",
"parameters_json": "{\"builder_name\":\"win\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/emscripten-releases\",\"revision\":\"f4f968572ce1e027bff5a5c830a7a86755ca2187\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win",
"scheduler_invocation_id:9076151734549463296",
"scheduler_job_id:emscripten-releases/win",
"user_agent:luci-scheduler",
"buildset:commit/git/f4f968572ce1e027bff5a5c830a7a86755ca2187",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/f4f968572ce1e027bff5a5c830a7a86755ca2187",
"gitiles_ref:refs/heads/master"
]
}
[00:03:44.114] Buildbucket response:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary_preference": "PROD",
"created_by": "project:emscripten-releases",
"created_ts": "1560470622897827",
"id": "8910721672083325184",
"parameters_json": "{\"builder_name\": \"win\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"f4f968572ce1e027bff5a5c830a7a86755ca2187\"}}",
"project": "emscripten-releases",
"result_details_json": "{\"properties\": {}}",
"service_account": "emscripten-releases-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1560470623851541",
"tags": [
"builder:win",
"buildset:commit/git/f4f968572ce1e027bff5a5c830a7a86755ca2187",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/f4f968572ce1e027bff5a5c830a7a86755ca2187",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9076151734549463296",
"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/8910721672083325184/+/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": "1560470623851635",
"url": "https://ci.chromium.org/b/8910721672083325184",
"utcnow_ts": "1560470624058931"
}
}
[00:03:44.114] Task URL: https://ci.chromium.org/b/8910721672083325184
[00:03:44.114] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9076151734549463296:2:0) after 1m0s
[00:04:06.647] Received PubSub notification, asking Buildbucket for the build status
[00:04:06.735] Build 8910721672083325184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:04:44.164] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9076151734549463296:2:0)
[00:04:44.164] Timer tick, asking Buildbucket for the build status
[00:04:44.324] Build 8910721672083325184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:04:44.324] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9076151734549463296:4:0) after 1m0s
[00:05:44.374] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9076151734549463296:4:0)
[00:05:44.374] Timer tick, asking Buildbucket for the build status
[00:05:44.588] Build 8910721672083325184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:05:44.588] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9076151734549463296:5:0) after 1m0s
[00:06:44.666] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9076151734549463296:5:0)
[00:06:44.667] Timer tick, asking Buildbucket for the build status
[00:06:44.838] Build 8910721672083325184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:06:44.838] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9076151734549463296:6:0) after 1m0s
[00:07:44.873] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9076151734549463296:6:0)
[00:07:44.873] Timer tick, asking Buildbucket for the build status
[00:07:44.978] Build 8910721672083325184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:07:44.978] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9076151734549463296:7:0) after 1m0s
[00:08:45.114] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9076151734549463296:7:0)
[00:08:45.114] Timer tick, asking Buildbucket for the build status
[00:08:45.838] Build 8910721672083325184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:08:45.838] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9076151734549463296:8:0) after 1m0s
[00:09:45.873] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9076151734549463296:8:0)
[00:09:45.873] Timer tick, asking Buildbucket for the build status
[00:09:46.595] Build 8910721672083325184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:09:46.595] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9076151734549463296:9:0) after 1m0s
[00:10:46.706] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9076151734549463296:9:0)
[00:10:46.706] Timer tick, asking Buildbucket for the build status
[00:10:48.186] Build 8910721672083325184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:10:48.186] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9076151734549463296:10:0) after 1m0s
[00:11:48.258] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9076151734549463296:10:0)
[00:11:48.258] Timer tick, asking Buildbucket for the build status
[00:11:48.451] Build 8910721672083325184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:11:48.451] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9076151734549463296:11:0) after 1m0s
[00:12:48.514] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9076151734549463296:11:0)
[00:12:48.514] Timer tick, asking Buildbucket for the build status
[00:12:48.740] Build 8910721672083325184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:12:48.740] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9076151734549463296:12:0) after 1m0s
[00:13:48.775] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9076151734549463296:12:0)
[00:13:48.775] Timer tick, asking Buildbucket for the build status
[00:13:49.246] Build 8910721672083325184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:13:49.246] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9076151734549463296:13:0) after 1m0s
[00:14:49.321] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9076151734549463296:13:0)
[00:14:49.321] Timer tick, asking Buildbucket for the build status
[00:14:50.006] Build 8910721672083325184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:14:50.006] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9076151734549463296:14:0) after 1m0s
[00:15:50.064] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9076151734549463296:14:0)
[00:15:50.064] Timer tick, asking Buildbucket for the build status
[00:15:50.474] Build 8910721672083325184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:15:50.474] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9076151734549463296:15:0) after 1m0s
[00:16:50.500] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9076151734549463296:15:0)
[00:16:50.500] Timer tick, asking Buildbucket for the build status
[00:16:50.676] Build 8910721672083325184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:16:50.676] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9076151734549463296:16:0) after 1m0s
[00:17:50.744] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9076151734549463296:16:0)
[00:17:50.744] Timer tick, asking Buildbucket for the build status
[00:17:51.109] Build 8910721672083325184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:17:51.109] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9076151734549463296:17:0) after 1m0s
[00:18:51.167] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9076151734549463296:17:0)
[00:18:51.167] Timer tick, asking Buildbucket for the build status
[00:18:51.366] Build 8910721672083325184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:18:51.366] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9076151734549463296:18:0) after 1m0s
[00:19:51.471] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9076151734549463296:18:0)
[00:19:51.471] Timer tick, asking Buildbucket for the build status
[00:19:51.584] Build 8910721672083325184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:19:51.584] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9076151734549463296:19:0) after 1m0s
[00:20:51.617] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9076151734549463296:19:0)
[00:20:51.617] Timer tick, asking Buildbucket for the build status
[00:20:51.749] Build 8910721672083325184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:20:51.749] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9076151734549463296:20:0) after 1m0s
[00:21:51.774] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9076151734549463296:20:0)
[00:21:51.774] Timer tick, asking Buildbucket for the build status
[00:21:52.567] Build 8910721672083325184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:21:52.567] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9076151734549463296:21:0) after 1m0s
[00:22:52.715] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9076151734549463296:21:0)
[00:22:52.715] Timer tick, asking Buildbucket for the build status
[00:22:52.931] Build 8910721672083325184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:22:52.931] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9076151734549463296:22:0) after 1m0s
[00:23:52.949] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9076151734549463296:22:0)
[00:23:52.949] Timer tick, asking Buildbucket for the build status
[00:23:53.034] Build 8910721672083325184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:23:53.034] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9076151734549463296:23:0) after 1m0s
[00:24:53.081] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9076151734549463296:23:0)
[00:24:53.081] Timer tick, asking Buildbucket for the build status
[00:24:53.359] Build 8910721672083325184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:24:53.360] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9076151734549463296:24:0) after 1m0s
[00:25:53.438] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9076151734549463296:24:0)
[00:25:53.438] Timer tick, asking Buildbucket for the build status
[00:25:53.863] Build 8910721672083325184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:25:53.863] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9076151734549463296:25:0) after 1m0s
[00:26:53.930] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9076151734549463296:25:0)
[00:26:53.930] Timer tick, asking Buildbucket for the build status
[00:26:54.038] Build 8910721672083325184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:26:54.038] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9076151734549463296:26:0) after 1m0s
[00:27:54.083] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9076151734549463296:26:0)
[00:27:54.083] Timer tick, asking Buildbucket for the build status
[00:27:54.212] Build 8910721672083325184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:27:54.212] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9076151734549463296:27:0) after 1m0s
[00:28:54.224] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9076151734549463296:27:0)
[00:28:54.224] Timer tick, asking Buildbucket for the build status
[00:28:54.290] Build 8910721672083325184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:28:54.290] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9076151734549463296:28:0) after 1m0s
[00:29:54.321] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9076151734549463296:28:0)
[00:29:54.321] Timer tick, asking Buildbucket for the build status
[00:29:54.458] Build 8910721672083325184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:29:54.459] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9076151734549463296:29:0) after 1m0s
[00:30:54.486] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9076151734549463296:29:0)
[00:30:54.487] Timer tick, asking Buildbucket for the build status
[00:30:54.597] Build 8910721672083325184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:30:54.597] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9076151734549463296:30:0) after 1m0s
[00:31:54.641] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9076151734549463296:30:0)
[00:31:54.641] Timer tick, asking Buildbucket for the build status
[00:31:54.681] Build 8910721672083325184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:31:54.681] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9076151734549463296:31:0) after 1m0s
[00:32:54.713] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9076151734549463296:31:0)
[00:32:54.713] Timer tick, asking Buildbucket for the build status
[00:32:54.918] Build 8910721672083325184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:32:54.918] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9076151734549463296:32:0) after 1m0s
[00:33:54.938] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9076151734549463296:32:0)
[00:33:54.938] Timer tick, asking Buildbucket for the build status
[00:33:55.382] Build 8910721672083325184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:33:55.382] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9076151734549463296:33:0) after 1m0s
[00:34:55.446] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9076151734549463296:33:0)
[00:34:55.446] Timer tick, asking Buildbucket for the build status
[00:34:55.670] Build 8910721672083325184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:34:55.670] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9076151734549463296:34:0) after 1m0s
[00:35:56.212] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9076151734549463296:34:0)
[00:35:56.212] Timer tick, asking Buildbucket for the build status
[00:35:56.855] Build 8910721672083325184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:35:56.855] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9076151734549463296:35:0) after 1m0s
[00:36:56.873] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9076151734549463296:35:0)
[00:36:56.873] Timer tick, asking Buildbucket for the build status
[00:36:57.512] Build 8910721672083325184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:36:57.512] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9076151734549463296:36:0) after 1m0s
[00:37:57.541] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9076151734549463296:36:0)
[00:37:57.541] Timer tick, asking Buildbucket for the build status
[00:37:57.775] Build 8910721672083325184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:37:57.775] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9076151734549463296:37:0) after 1m0s
[00:38:57.815] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9076151734549463296:37:0)
[00:38:57.815] Timer tick, asking Buildbucket for the build status
[00:38:57.880] Build 8910721672083325184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:38:57.880] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9076151734549463296:38:0) after 1m0s
[00:39:57.923] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9076151734549463296:38:0)
[00:39:57.923] Timer tick, asking Buildbucket for the build status
[00:39:58.016] Build 8910721672083325184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:39:58.017] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9076151734549463296:39:0) after 1m0s
[00:40:58.055] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9076151734549463296:39:0)
[00:40:58.055] Timer tick, asking Buildbucket for the build status
[00:40:58.205] Build 8910721672083325184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:40:58.205] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9076151734549463296:40:0) after 1m0s
[00:41:58.223] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9076151734549463296:40:0)
[00:41:58.223] Timer tick, asking Buildbucket for the build status
[00:41:58.817] Build 8910721672083325184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:41:58.817] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9076151734549463296:41:0) after 1m0s
[00:42:58.858] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9076151734549463296:41:0)
[00:42:58.858] Timer tick, asking Buildbucket for the build status
[00:42:58.958] Build 8910721672083325184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:42:58.958] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9076151734549463296:42:0) after 1m0s
[00:43:59.015] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9076151734549463296:42:0)
[00:43:59.015] Timer tick, asking Buildbucket for the build status
[00:43:59.107] Build 8910721672083325184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:43:59.107] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9076151734549463296:43:0) after 1m0s
[00:44:59.267] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9076151734549463296:43:0)
[00:44:59.267] Timer tick, asking Buildbucket for the build status
[00:44:59.488] Build 8910721672083325184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:44:59.488] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9076151734549463296:44:0) after 1m0s
[00:45:59.638] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9076151734549463296:44:0)
[00:45:59.638] Timer tick, asking Buildbucket for the build status
[00:45:59.732] Build 8910721672083325184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:45:59.732] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9076151734549463296:45:0) after 1m0s
[00:46:59.776] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9076151734549463296:45:0)
[00:46:59.776] Timer tick, asking Buildbucket for the build status
[00:46:59.898] Build 8910721672083325184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:46:59.898] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9076151734549463296:46:0) after 1m0s
[00:47:59.918] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9076151734549463296:46:0)
[00:47:59.918] Timer tick, asking Buildbucket for the build status
[00:48:00.014] Build 8910721672083325184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:48:00.014] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9076151734549463296:47:0) after 1m0s
[00:49:00.109] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9076151734549463296:47:0)
[00:49:00.109] Timer tick, asking Buildbucket for the build status
[00:49:00.411] Build 8910721672083325184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:49:00.411] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9076151734549463296:48:0) after 1m0s
[00:50:00.450] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9076151734549463296:48:0)
[00:50:00.450] Timer tick, asking Buildbucket for the build status
[00:50:00.545] Build 8910721672083325184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:50:00.545] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9076151734549463296:49:0) after 1m0s
[00:51:00.566] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9076151734549463296:49:0)
[00:51:00.566] Timer tick, asking Buildbucket for the build status
[00:51:01.573] Build 8910721672083325184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:51:01.573] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9076151734549463296:50:0) after 1m0s
[00:52:01.614] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9076151734549463296:50:0)
[00:52:01.614] Timer tick, asking Buildbucket for the build status
[00:52:01.668] Build 8910721672083325184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:52:01.668] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9076151734549463296:51:0) after 1m0s
[00:53:01.722] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9076151734549463296:51:0)
[00:53:01.722] Timer tick, asking Buildbucket for the build status
[00:53:02.032] Build 8910721672083325184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:53:02.032] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9076151734549463296:52:0) after 1m0s
[00:54:02.058] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9076151734549463296:52:0)
[00:54:02.058] Timer tick, asking Buildbucket for the build status
[00:54:02.136] Build 8910721672083325184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:54:02.136] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9076151734549463296:53:0) after 1m0s
[00:55:02.226] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9076151734549463296:53:0)
[00:55:02.226] Timer tick, asking Buildbucket for the build status
[00:55:02.350] Build 8910721672083325184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:55:02.350] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9076151734549463296:54:0) after 1m0s
[00:56:02.415] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9076151734549463296:54:0)
[00:56:02.415] Timer tick, asking Buildbucket for the build status
[00:56:02.540] Build 8910721672083325184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:56:02.540] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9076151734549463296:55:0) after 1m0s
[00:57:02.533] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9076151734549463296:55:0)
[00:57:02.533] Timer tick, asking Buildbucket for the build status
[00:57:03.197] Build 8910721672083325184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:57:03.197] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9076151734549463296:56:0) after 1m0s
[00:58:03.257] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9076151734549463296:56:0)
[00:58:03.257] Timer tick, asking Buildbucket for the build status
[00:58:03.375] Build 8910721672083325184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:58:03.375] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9076151734549463296:57:0) after 1m0s
[00:59:03.406] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9076151734549463296:57:0)
[00:59:03.406] Timer tick, asking Buildbucket for the build status
[00:59:03.610] Build 8910721672083325184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:59:03.610] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9076151734549463296:58:0) after 1m0s
[01:00:03.635] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9076151734549463296:58:0)
[01:00:03.635] Timer tick, asking Buildbucket for the build status
[01:00:03.857] Build 8910721672083325184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:00:03.857] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9076151734549463296:59:0) after 1m0s
[01:01:03.952] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9076151734549463296:59:0)
[01:01:03.952] Timer tick, asking Buildbucket for the build status
[01:01:04.457] Build 8910721672083325184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:01:04.457] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9076151734549463296:60:0) after 1m0s
[01:02:04.954] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9076151734549463296:60:0)
[01:02:04.954] Timer tick, asking Buildbucket for the build status
[01:02:05.001] Build 8910721672083325184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:02:05.001] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9076151734549463296:61:0) after 1m0s
[01:03:04.999] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9076151734549463296:61:0)
[01:03:04.999] Timer tick, asking Buildbucket for the build status
[01:03:05.045] Build 8910721672083325184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:03:05.046] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9076151734549463296:62:0) after 1m0s
[01:04:05.150] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9076151734549463296:62:0)
[01:04:05.150] Timer tick, asking Buildbucket for the build status
[01:04:05.346] Build 8910721672083325184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:04:05.346] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9076151734549463296:63:0) after 1m0s
[01:05:05.397] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9076151734549463296:63:0)
[01:05:05.397] Timer tick, asking Buildbucket for the build status
[01:05:06.864] Build 8910721672083325184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:05:06.864] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9076151734549463296:64:0) after 1m0s
[01:06:06.926] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9076151734549463296:64:0)
[01:06:06.926] Timer tick, asking Buildbucket for the build status
[01:06:06.996] Build 8910721672083325184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:06:06.996] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9076151734549463296:65:0) after 1m0s
[01:07:07.006] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9076151734549463296:65:0)
[01:07:07.006] Timer tick, asking Buildbucket for the build status
[01:07:07.446] Build 8910721672083325184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:07:07.446] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9076151734549463296:66:0) after 1m0s
[01:08:07.442] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9076151734549463296:66:0)
[01:08:07.442] Timer tick, asking Buildbucket for the build status
[01:08:07.654] Build 8910721672083325184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:08:07.654] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9076151734549463296:67:0) after 1m0s
[01:09:07.694] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9076151734549463296:67:0)
[01:09:07.694] Timer tick, asking Buildbucket for the build status
[01:09:07.830] Build 8910721672083325184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:09:07.830] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9076151734549463296:68:0) after 1m0s
[01:10:07.868] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9076151734549463296:68:0)
[01:10:07.868] Timer tick, asking Buildbucket for the build status
[01:10:07.972] Build 8910721672083325184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:10:07.972] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9076151734549463296:69:0) after 1m0s
[01:11:07.987] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9076151734549463296:69:0)
[01:11:07.987] Timer tick, asking Buildbucket for the build status
[01:11:08.091] Build 8910721672083325184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:11:08.091] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9076151734549463296:70:0) after 1m0s
[01:12:08.109] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9076151734549463296:70:0)
[01:12:08.109] Timer tick, asking Buildbucket for the build status
[01:12:08.157] Build 8910721672083325184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:12:08.157] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9076151734549463296:71:0) after 1m0s
[01:13:08.183] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9076151734549463296:71:0)
[01:13:08.183] Timer tick, asking Buildbucket for the build status
[01:13:08.235] Build 8910721672083325184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:13:08.235] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9076151734549463296:72:0) after 1m0s
[01:14:08.257] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9076151734549463296:72:0)
[01:14:08.257] Timer tick, asking Buildbucket for the build status
[01:14:08.483] Build 8910721672083325184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:14:08.483] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9076151734549463296:73:0) after 1m0s
[01:14:56.437] Received PubSub notification, asking Buildbucket for the build status
[01:14:56.524] Build 8910721672083325184: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[01:14:56.524] Buildbucket build:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary_preference": "PROD",
"completed_ts": "1560474895759543",
"created_by": "project:emscripten-releases",
"created_ts": "1560470622897827",
"failure_reason": "BUILD_FAILURE",
"id": "8910721672083325184",
"parameters_json": "{\"builder_name\": \"win\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"f4f968572ce1e027bff5a5c830a7a86755ca2187\"}}",
"project": "emscripten-releases",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"1 out of 2 aggregated steps failed: Step('Emscripten testsuite (upstream)') (retcode: 2)\"}, \"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-ogkf\", \"branch\": \"refs/heads/master\", \"buildername\": \"win\", \"got_revision\": \"f4f968572ce1e027bff5a5c830a7a86755ca2187\", \"path_config\": \"generic\", \"recipe\": \"emscripten_releases\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"f4f968572ce1e027bff5a5c830a7a86755ca2187\"}, \"swarming\": {\"bot_dimensions\": {\"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"host_class\": [\"default\"], \"id\": [\"luci-emscripten-releases-ci-win10-0-ogkf\"], \"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\": [\"4339-6285c57\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-b\"]}}, \"ui\": {\"info\": \"1 out of 2 aggregated steps failed: Step('Emscripten testsuite (upstream)') (retcode: 2)\"}}",
"service_account": "emscripten-releases-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1560470644227982",
"status": "COMPLETED",
"status_changed_ts": "1560470645952008",
"tags": [
"builder:win",
"buildset:commit/git/f4f968572ce1e027bff5a5c830a7a86755ca2187",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/f4f968572ce1e027bff5a5c830a7a86755ca2187",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9076151734549463296",
"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/8910721672083325184/+/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:456c1da666663810",
"user_agent:luci-scheduler"
],
"updated_ts": "1560474896015021",
"url": "https://ci.chromium.org/b/8910721672083325184",
"utcnow_ts": "1560474896517314"
}
}
[01:14:56.524] Invocation finished in 1h11m15.361414028s with status FAILED