[20:39:26.171] New invocation is queued and will start shortly
[20:39:27.361] Starting the invocation (attempt 1)
[20:39:27.433] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[20:39:27.433] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[20:39:27.433] Buildbucket request:
{
"bucket": "luci.emscripten-releases.ci",
"client_operation_id": "9076798763632805920",
"parameters_json": "{\"builder_name\":\"win\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/emscripten-releases\",\"revision\":\"7116b682dc0b52c498eb527db3605ef69a49b514\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win",
"scheduler_invocation_id:9076798763632805920",
"scheduler_job_id:emscripten-releases/win",
"user_agent:luci-scheduler",
"buildset:commit/git/7116b682dc0b52c498eb527db3605ef69a49b514",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/7116b682dc0b52c498eb527db3605ef69a49b514",
"gitiles_ref:refs/heads/master"
]
}
[20:39:28.353] Buildbucket response:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary_preference": "PROD",
"created_by": "project:emscripten-releases",
"created_ts": "1559853567562224",
"id": "8911368701498362144",
"parameters_json": "{\"builder_name\": \"win\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"7116b682dc0b52c498eb527db3605ef69a49b514\"}}",
"project": "emscripten-releases",
"result_details_json": "{\"properties\": {}}",
"service_account": "emscripten-releases-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1559853568111395",
"tags": [
"builder:win",
"buildset:commit/git/7116b682dc0b52c498eb527db3605ef69a49b514",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/7116b682dc0b52c498eb527db3605ef69a49b514",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9076798763632805920",
"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/8911368701498362144/+/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": "1559853568111485",
"url": "https://ci.chromium.org/b/8911368701498362144",
"utcnow_ts": "1559853568345698"
}
}
[20:39:28.353] Task URL: https://ci.chromium.org/b/8911368701498362144
[20:39:28.353] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9076798763632805920:2:0) after 1m0s
[20:39:52.004] Received PubSub notification, asking Buildbucket for the build status
[20:39:52.071] Build 8911368701498362144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:40:28.376] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9076798763632805920:2:0)
[20:40:28.376] Timer tick, asking Buildbucket for the build status
[20:40:28.523] Build 8911368701498362144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:40:28.523] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9076798763632805920:4:0) after 1m0s
[20:41:28.602] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9076798763632805920:4:0)
[20:41:28.602] Timer tick, asking Buildbucket for the build status
[20:41:28.784] Build 8911368701498362144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:41:28.784] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9076798763632805920:5:0) after 1m0s
[20:42:28.818] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9076798763632805920:5:0)
[20:42:28.818] Timer tick, asking Buildbucket for the build status
[20:42:29.119] Build 8911368701498362144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:42:29.120] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9076798763632805920:6:0) after 1m0s
[20:43:29.148] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9076798763632805920:6:0)
[20:43:29.148] Timer tick, asking Buildbucket for the build status
[20:43:29.338] Build 8911368701498362144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:43:29.338] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9076798763632805920:7:0) after 1m0s
[20:44:29.368] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9076798763632805920:7:0)
[20:44:29.368] Timer tick, asking Buildbucket for the build status
[20:44:29.446] Build 8911368701498362144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:44:29.446] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9076798763632805920:8:0) after 1m0s
[20:45:29.504] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9076798763632805920:8:0)
[20:45:29.504] Timer tick, asking Buildbucket for the build status
[20:45:29.958] Build 8911368701498362144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:45:29.958] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9076798763632805920:9:0) after 1m0s
[20:46:29.958] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9076798763632805920:9:0)
[20:46:29.958] Timer tick, asking Buildbucket for the build status
[20:46:30.203] Build 8911368701498362144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:46:30.203] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9076798763632805920:10:0) after 1m0s
[20:47:30.226] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9076798763632805920:10:0)
[20:47:30.226] Timer tick, asking Buildbucket for the build status
[20:47:30.311] Build 8911368701498362144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:47:30.312] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9076798763632805920:11:0) after 1m0s
[20:48:30.339] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9076798763632805920:11:0)
[20:48:30.339] Timer tick, asking Buildbucket for the build status
[20:48:30.932] Build 8911368701498362144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:48:30.932] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9076798763632805920:12:0) after 1m0s
[20:49:30.940] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9076798763632805920:12:0)
[20:49:30.940] Timer tick, asking Buildbucket for the build status
[20:49:31.109] Build 8911368701498362144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:49:31.109] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9076798763632805920:13:0) after 1m0s
[20:50:31.246] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9076798763632805920:13:0)
[20:50:31.246] Timer tick, asking Buildbucket for the build status
[20:50:32.077] Build 8911368701498362144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:50:32.077] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9076798763632805920:14:0) after 1m0s
[20:51:32.200] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9076798763632805920:14:0)
[20:51:32.200] Timer tick, asking Buildbucket for the build status
[20:51:34.456] Build 8911368701498362144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:51:34.456] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9076798763632805920:15:0) after 1m0s
[20:52:34.516] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9076798763632805920:15:0)
[20:52:34.516] Timer tick, asking Buildbucket for the build status
[20:52:34.640] Build 8911368701498362144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:52:34.640] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9076798763632805920:16:0) after 1m0s
[20:53:34.736] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9076798763632805920:16:0)
[20:53:34.736] Timer tick, asking Buildbucket for the build status
[20:53:35.246] Build 8911368701498362144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:53:35.246] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9076798763632805920:17:0) after 1m0s
[20:54:35.244] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9076798763632805920:17:0)
[20:54:35.244] Timer tick, asking Buildbucket for the build status
[20:54:35.544] Build 8911368701498362144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:54:35.544] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9076798763632805920:18:0) after 1m0s
[20:55:35.578] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9076798763632805920:18:0)
[20:55:35.578] Timer tick, asking Buildbucket for the build status
[20:55:35.627] Build 8911368701498362144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:55:35.627] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9076798763632805920:19:0) after 1m0s
[20:56:35.612] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9076798763632805920:19:0)
[20:56:35.612] Timer tick, asking Buildbucket for the build status
[20:56:35.723] Build 8911368701498362144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:56:35.723] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9076798763632805920:20:0) after 1m0s
[20:57:35.741] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9076798763632805920:20:0)
[20:57:35.741] Timer tick, asking Buildbucket for the build status
[20:57:36.116] Build 8911368701498362144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:57:36.116] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9076798763632805920:21:0) after 1m0s
[20:58:36.172] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9076798763632805920:21:0)
[20:58:36.172] Timer tick, asking Buildbucket for the build status
[20:58:36.527] Build 8911368701498362144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:58:36.527] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9076798763632805920:22:0) after 1m0s
[20:59:36.577] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9076798763632805920:22:0)
[20:59:36.577] Timer tick, asking Buildbucket for the build status
[20:59:36.648] Build 8911368701498362144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:59:36.648] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9076798763632805920:23:0) after 1m0s
[21:00:36.748] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9076798763632805920:23:0)
[21:00:36.748] Timer tick, asking Buildbucket for the build status
[21:00:40.378] Build 8911368701498362144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:00:40.378] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9076798763632805920:24:0) after 1m0s
[21:01:40.464] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9076798763632805920:24:0)
[21:01:40.464] Timer tick, asking Buildbucket for the build status
[21:01:41.013] Build 8911368701498362144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:01:41.013] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9076798763632805920:25:0) after 1m0s
[21:02:41.238] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9076798763632805920:25:0)
[21:02:41.238] Timer tick, asking Buildbucket for the build status
[21:02:41.329] Build 8911368701498362144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:02:41.329] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9076798763632805920:26:0) after 1m0s
[21:03:41.457] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9076798763632805920:26:0)
[21:03:41.457] Timer tick, asking Buildbucket for the build status
[21:03:42.641] Build 8911368701498362144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:03:42.641] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9076798763632805920:27:0) after 1m0s
[21:04:42.804] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9076798763632805920:27:0)
[21:04:42.804] Timer tick, asking Buildbucket for the build status
[21:04:42.907] Build 8911368701498362144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:04:42.907] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9076798763632805920:28:0) after 1m0s
[21:05:42.924] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9076798763632805920:28:0)
[21:05:42.924] Timer tick, asking Buildbucket for the build status
[21:05:43.025] Build 8911368701498362144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:05:43.025] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9076798763632805920:29:0) after 1m0s
[21:06:43.096] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9076798763632805920:29:0)
[21:06:43.096] Timer tick, asking Buildbucket for the build status
[21:06:43.145] Build 8911368701498362144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:06:43.145] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9076798763632805920:30:0) after 1m0s
[21:07:43.172] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9076798763632805920:30:0)
[21:07:43.172] Timer tick, asking Buildbucket for the build status
[21:07:43.241] Build 8911368701498362144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:07:43.241] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9076798763632805920:31:0) after 1m0s
[21:08:43.267] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9076798763632805920:31:0)
[21:08:43.267] Timer tick, asking Buildbucket for the build status
[21:08:43.317] Build 8911368701498362144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:08:43.317] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9076798763632805920:32:0) after 1m0s
[21:09:43.390] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9076798763632805920:32:0)
[21:09:43.390] Timer tick, asking Buildbucket for the build status
[21:09:43.435] Build 8911368701498362144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:09:43.435] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9076798763632805920:33:0) after 1m0s
[21:10:43.477] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9076798763632805920:33:0)
[21:10:43.477] Timer tick, asking Buildbucket for the build status
[21:10:43.674] Build 8911368701498362144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:10:43.674] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9076798763632805920:34:0) after 1m0s
[21:11:43.908] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9076798763632805920:34:0)
[21:11:43.909] Timer tick, asking Buildbucket for the build status
[21:11:44.750] Build 8911368701498362144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:11:44.750] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9076798763632805920:35:0) after 1m0s
[21:12:44.820] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9076798763632805920:35:0)
[21:12:44.820] Timer tick, asking Buildbucket for the build status
[21:12:44.900] Build 8911368701498362144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:12:44.900] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9076798763632805920:36:0) after 1m0s
[21:13:44.923] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9076798763632805920:36:0)
[21:13:44.923] Timer tick, asking Buildbucket for the build status
[21:13:45.092] Build 8911368701498362144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:13:45.092] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9076798763632805920:37:0) after 1m0s
[21:14:45.206] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9076798763632805920:37:0)
[21:14:45.206] Timer tick, asking Buildbucket for the build status
[21:14:45.318] Build 8911368701498362144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:14:45.318] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9076798763632805920:38:0) after 1m0s
[21:15:45.539] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9076798763632805920:38:0)
[21:15:45.539] Timer tick, asking Buildbucket for the build status
[21:15:45.581] Build 8911368701498362144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:15:45.582] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9076798763632805920:39:0) after 1m0s
[21:16:45.723] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9076798763632805920:39:0)
[21:16:45.723] Timer tick, asking Buildbucket for the build status
[21:16:46.144] Build 8911368701498362144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:16:46.144] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9076798763632805920:40:0) after 1m0s
[21:17:46.217] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9076798763632805920:40:0)
[21:17:46.217] Timer tick, asking Buildbucket for the build status
[21:17:46.458] Build 8911368701498362144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:17:46.458] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9076798763632805920:41:0) after 1m0s
[21:18:46.477] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9076798763632805920:41:0)
[21:18:46.477] Timer tick, asking Buildbucket for the build status
[21:18:46.804] Build 8911368701498362144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:18:46.804] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9076798763632805920:42:0) after 1m0s
[21:19:46.813] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9076798763632805920:42:0)
[21:19:46.813] Timer tick, asking Buildbucket for the build status
[21:19:46.994] Build 8911368701498362144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:19:46.994] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9076798763632805920:43:0) after 1m0s
[21:20:47.067] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9076798763632805920:43:0)
[21:20:47.067] Timer tick, asking Buildbucket for the build status
[21:20:47.133] Build 8911368701498362144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:20:47.133] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9076798763632805920:44:0) after 1m0s
[21:21:47.152] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9076798763632805920:44:0)
[21:21:47.152] Timer tick, asking Buildbucket for the build status
[21:21:47.255] Build 8911368701498362144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:21:47.255] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9076798763632805920:45:0) after 1m0s
[21:22:47.277] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9076798763632805920:45:0)
[21:22:47.277] Timer tick, asking Buildbucket for the build status
[21:22:47.366] Build 8911368701498362144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:22:47.366] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9076798763632805920:46:0) after 1m0s
[21:23:47.392] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9076798763632805920:46:0)
[21:23:47.392] Timer tick, asking Buildbucket for the build status
[21:23:47.431] Build 8911368701498362144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:23:47.431] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9076798763632805920:47:0) after 1m0s
[21:24:47.545] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9076798763632805920:47:0)
[21:24:47.545] Timer tick, asking Buildbucket for the build status
[21:24:47.705] Build 8911368701498362144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:24:47.705] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9076798763632805920:48:0) after 1m0s
[21:25:47.692] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9076798763632805920:48:0)
[21:25:47.692] Timer tick, asking Buildbucket for the build status
[21:25:47.898] Build 8911368701498362144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:25:47.898] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9076798763632805920:49:0) after 1m0s
[21:26:48.031] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9076798763632805920:49:0)
[21:26:48.031] Timer tick, asking Buildbucket for the build status
[21:26:48.514] Build 8911368701498362144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:26:48.514] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9076798763632805920:50:0) after 1m0s
[21:27:48.564] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9076798763632805920:50:0)
[21:27:48.564] Timer tick, asking Buildbucket for the build status
[21:27:49.159] Build 8911368701498362144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:27:49.159] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9076798763632805920:51:0) after 1m0s
[21:28:49.305] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9076798763632805920:51:0)
[21:28:49.305] Timer tick, asking Buildbucket for the build status
[21:28:50.020] Build 8911368701498362144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:28:50.020] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9076798763632805920:52:0) after 1m0s
[21:29:50.077] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9076798763632805920:52:0)
[21:29:50.077] Timer tick, asking Buildbucket for the build status
[21:29:50.189] Build 8911368701498362144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:29:50.189] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9076798763632805920:53:0) after 1m0s
[21:30:50.199] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9076798763632805920:53:0)
[21:30:50.199] Timer tick, asking Buildbucket for the build status
[21:30:50.737] Build 8911368701498362144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:30:50.737] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9076798763632805920:54:0) after 1m0s
[21:31:50.757] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9076798763632805920:54:0)
[21:31:50.757] Timer tick, asking Buildbucket for the build status
[21:31:50.952] Build 8911368701498362144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:31:50.952] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9076798763632805920:55:0) after 1m0s
[21:32:51.048] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9076798763632805920:55:0)
[21:32:51.048] Timer tick, asking Buildbucket for the build status
[21:32:51.232] Build 8911368701498362144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:32:51.232] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9076798763632805920:56:0) after 1m0s
[21:33:51.299] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9076798763632805920:56:0)
[21:33:51.299] Timer tick, asking Buildbucket for the build status
[21:33:51.607] Build 8911368701498362144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:33:51.607] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9076798763632805920:57:0) after 1m0s
[21:34:51.609] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9076798763632805920:57:0)
[21:34:51.609] Timer tick, asking Buildbucket for the build status
[21:34:51.779] Build 8911368701498362144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:34:51.779] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9076798763632805920:58:0) after 1m0s
[21:35:51.977] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9076798763632805920:58:0)
[21:35:51.977] Timer tick, asking Buildbucket for the build status
[21:35:52.141] Build 8911368701498362144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:35:52.141] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9076798763632805920:59:0) after 1m0s
[21:36:52.280] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9076798763632805920:59:0)
[21:36:52.280] Timer tick, asking Buildbucket for the build status
[21:36:52.396] Build 8911368701498362144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:36:52.396] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9076798763632805920:60:0) after 1m0s
[21:37:52.516] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9076798763632805920:60:0)
[21:37:52.516] Timer tick, asking Buildbucket for the build status
[21:37:52.762] Build 8911368701498362144: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:37:52.762] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9076798763632805920:61:0) after 1m0s
[21:38:43.623] Received PubSub notification, asking Buildbucket for the build status
[21:38:43.686] Build 8911368701498362144: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[21:38:43.687] Buildbucket build:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary_preference": "PROD",
"completed_ts": "1559857123159431",
"created_by": "project:emscripten-releases",
"created_ts": "1559853567562224",
"failure_reason": "BUILD_FAILURE",
"id": "8911368701498362144",
"parameters_json": "{\"builder_name\": \"win\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"7116b682dc0b52c498eb527db3605ef69a49b514\"}}",
"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-ynet\", \"branch\": \"refs/heads/master\", \"buildername\": \"win\", \"got_revision\": \"7116b682dc0b52c498eb527db3605ef69a49b514\", \"path_config\": \"generic\", \"recipe\": \"emscripten_releases\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"7116b682dc0b52c498eb527db3605ef69a49b514\"}, \"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-ynet\"], \"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\": [\"4316-effbcfc\"], \"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": "1559853591167142",
"status": "COMPLETED",
"status_changed_ts": "1559853591557330",
"tags": [
"builder:win",
"buildset:commit/git/7116b682dc0b52c498eb527db3605ef69a49b514",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/7116b682dc0b52c498eb527db3605ef69a49b514",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9076798763632805920",
"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/8911368701498362144/+/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:4547561ab1c08e10",
"user_agent:luci-scheduler"
],
"updated_ts": "1559857123272880",
"url": "https://ci.chromium.org/b/8911368701498362144",
"utcnow_ts": "1559857123674934"
}
}
[21:38:43.687] Invocation finished in 59m17.543786042s with status FAILED