[06:31:28.678] New invocation is queued and will start shortly
[06:31:30.010] Starting the invocation (attempt 1)
[06:31:30.056] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[06:31:30.056] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[06:31:30.056] Buildbucket request:
{
"bucket": "luci.emscripten-releases.ci",
"client_operation_id": "9075221367146423504",
"parameters_json": "{\"builder_name\":\"win\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/emscripten-releases\",\"revision\":\"17ac703bcae6cb9aed512ca6bc5b062d9c20a451\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win",
"scheduler_invocation_id:9075221367146423504",
"scheduler_job_id:emscripten-releases/win",
"user_agent:luci-scheduler",
"buildset:commit/git/17ac703bcae6cb9aed512ca6bc5b062d9c20a451",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/17ac703bcae6cb9aed512ca6bc5b062d9c20a451",
"gitiles_ref:refs/heads/master"
]
}
[06:31:30.772] Buildbucket response:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary_preference": "PROD",
"created_by": "project:emscripten-releases",
"created_ts": "1561357890150607",
"id": "8909791304936267792",
"parameters_json": "{\"builder_name\": \"win\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"17ac703bcae6cb9aed512ca6bc5b062d9c20a451\"}}",
"project": "emscripten-releases",
"result_details_json": "{\"properties\": {}}",
"service_account": "emscripten-releases-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1561357890445263",
"tags": [
"builder:win",
"buildset:commit/git/17ac703bcae6cb9aed512ca6bc5b062d9c20a451",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/17ac703bcae6cb9aed512ca6bc5b062d9c20a451",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9075221367146423504",
"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/8909791304936267792/+/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": "1561357890445352",
"url": "https://ci.chromium.org/b/8909791304936267792",
"utcnow_ts": "1561357890764354"
}
}
[06:31:30.772] Task URL: https://ci.chromium.org/b/8909791304936267792
[06:31:30.772] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:2:0) after 1m0s
[06:31:36.847] Received PubSub notification, asking Buildbucket for the build status
[06:31:37.094] Build 8909791304936267792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:32:30.887] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:2:0)
[06:32:30.887] Timer tick, asking Buildbucket for the build status
[06:32:31.175] Build 8909791304936267792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:32:31.175] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:4:0) after 1m0s
[06:33:31.274] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:4:0)
[06:33:31.274] Timer tick, asking Buildbucket for the build status
[06:33:31.384] Build 8909791304936267792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:33:31.384] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:5:0) after 1m0s
[06:34:31.462] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:5:0)
[06:34:31.462] Timer tick, asking Buildbucket for the build status
[06:34:32.156] Build 8909791304936267792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:34:32.156] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:6:0) after 1m0s
[06:35:32.174] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:6:0)
[06:35:32.175] Timer tick, asking Buildbucket for the build status
[06:35:32.245] Build 8909791304936267792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:35:32.245] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:7:0) after 1m0s
[06:36:32.278] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:7:0)
[06:36:32.278] Timer tick, asking Buildbucket for the build status
[06:36:32.448] Build 8909791304936267792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:36:32.449] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:8:0) after 1m0s
[06:37:32.465] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:8:0)
[06:37:32.465] Timer tick, asking Buildbucket for the build status
[06:37:32.599] Build 8909791304936267792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:37:32.599] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:9:0) after 1m0s
[06:38:32.670] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:9:0)
[06:38:32.670] Timer tick, asking Buildbucket for the build status
[06:38:33.119] Build 8909791304936267792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:38:33.119] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:10:0) after 1m0s
[06:39:33.153] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:10:0)
[06:39:33.153] Timer tick, asking Buildbucket for the build status
[06:39:33.298] Build 8909791304936267792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:39:33.298] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:11:0) after 1m0s
[06:40:33.360] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:11:0)
[06:40:33.360] Timer tick, asking Buildbucket for the build status
[06:40:33.996] Build 8909791304936267792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:40:33.996] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:12:0) after 1m0s
[06:41:34.044] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:12:0)
[06:41:34.044] Timer tick, asking Buildbucket for the build status
[06:41:34.248] Build 8909791304936267792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:41:34.248] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:13:0) after 1m0s
[06:42:34.266] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:13:0)
[06:42:34.266] Timer tick, asking Buildbucket for the build status
[06:42:34.354] Build 8909791304936267792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:42:34.354] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:14:0) after 1m0s
[06:43:34.400] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:14:0)
[06:43:34.400] Timer tick, asking Buildbucket for the build status
[06:43:34.523] Build 8909791304936267792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:43:34.523] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:15:0) after 1m0s
[06:44:34.527] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:15:0)
[06:44:34.527] Timer tick, asking Buildbucket for the build status
[06:44:34.700] Build 8909791304936267792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:44:34.700] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:16:0) after 1m0s
[06:45:34.719] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:16:0)
[06:45:34.719] Timer tick, asking Buildbucket for the build status
[06:45:34.910] Build 8909791304936267792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:45:34.910] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:17:0) after 1m0s
[06:46:34.933] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:17:0)
[06:46:34.933] Timer tick, asking Buildbucket for the build status
[06:46:34.982] Build 8909791304936267792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:46:34.982] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:18:0) after 1m0s
[06:47:34.981] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:18:0)
[06:47:34.981] Timer tick, asking Buildbucket for the build status
[06:47:35.124] Build 8909791304936267792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:47:35.124] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:19:0) after 1m0s
[06:48:35.176] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:19:0)
[06:48:35.176] Timer tick, asking Buildbucket for the build status
[06:48:35.284] Build 8909791304936267792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:48:35.284] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:20:0) after 1m0s
[06:49:35.333] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:20:0)
[06:49:35.333] Timer tick, asking Buildbucket for the build status
[06:49:35.443] Build 8909791304936267792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:49:35.443] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:21:0) after 1m0s
[06:50:35.461] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:21:0)
[06:50:35.461] Timer tick, asking Buildbucket for the build status
[06:50:35.539] Build 8909791304936267792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:50:35.539] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:22:0) after 1m0s
[06:51:35.631] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:22:0)
[06:51:35.631] Timer tick, asking Buildbucket for the build status
[06:51:35.724] Build 8909791304936267792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:51:35.724] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:23:0) after 1m0s
[06:52:35.747] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:23:0)
[06:52:35.747] Timer tick, asking Buildbucket for the build status
[06:52:35.890] Build 8909791304936267792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:52:35.890] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:24:0) after 1m0s
[06:53:35.966] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:24:0)
[06:53:35.966] Timer tick, asking Buildbucket for the build status
[06:53:36.133] Build 8909791304936267792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:53:36.133] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:25:0) after 1m0s
[06:54:36.158] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:25:0)
[06:54:36.158] Timer tick, asking Buildbucket for the build status
[06:54:36.229] Build 8909791304936267792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:54:36.229] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:26:0) after 1m0s
[06:55:36.275] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:26:0)
[06:55:36.275] Timer tick, asking Buildbucket for the build status
[06:55:36.508] Build 8909791304936267792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:55:36.508] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:27:0) after 1m0s
[06:56:36.567] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:27:0)
[06:56:36.567] Timer tick, asking Buildbucket for the build status
[06:56:36.718] Build 8909791304936267792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:56:36.718] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:28:0) after 1m0s
[06:57:36.734] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:28:0)
[06:57:36.734] Timer tick, asking Buildbucket for the build status
[06:57:37.012] Build 8909791304936267792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:57:37.012] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:29:0) after 1m0s
[06:58:37.028] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:29:0)
[06:58:37.028] Timer tick, asking Buildbucket for the build status
[06:58:37.145] Build 8909791304936267792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:58:37.145] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:30:0) after 1m0s
[06:59:37.164] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:30:0)
[06:59:37.164] Timer tick, asking Buildbucket for the build status
[06:59:38.335] Build 8909791304936267792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:59:38.335] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:31:0) after 1m0s
[07:00:38.475] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:31:0)
[07:00:38.475] Timer tick, asking Buildbucket for the build status
[07:00:38.875] Build 8909791304936267792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:00:38.875] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:32:0) after 1m0s
[07:01:39.056] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:32:0)
[07:01:39.056] Timer tick, asking Buildbucket for the build status
[07:01:39.173] Build 8909791304936267792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:01:39.173] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:33:0) after 1m0s
[07:02:39.392] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:33:0)
[07:02:39.392] Timer tick, asking Buildbucket for the build status
[07:02:39.516] Build 8909791304936267792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:02:39.516] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:34:0) after 1m0s
[07:03:39.511] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:34:0)
[07:03:39.511] Timer tick, asking Buildbucket for the build status
[07:03:39.576] Build 8909791304936267792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:03:39.576] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:35:0) after 1m0s
[07:04:39.635] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:35:0)
[07:04:39.635] Timer tick, asking Buildbucket for the build status
[07:04:39.740] Build 8909791304936267792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:04:39.740] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:36:0) after 1m0s
[07:05:39.828] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:36:0)
[07:05:39.828] Timer tick, asking Buildbucket for the build status
[07:05:40.936] Build 8909791304936267792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:05:40.936] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:37:0) after 1m0s
[07:06:41.383] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:37:0)
[07:06:41.383] Timer tick, asking Buildbucket for the build status
[07:06:41.583] Build 8909791304936267792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:06:41.583] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:38:0) after 1m0s
[07:07:41.783] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:38:0)
[07:07:41.783] Timer tick, asking Buildbucket for the build status
[07:07:41.829] Build 8909791304936267792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:07:41.829] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:39:0) after 1m0s
[07:08:41.981] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:39:0)
[07:08:41.981] Timer tick, asking Buildbucket for the build status
[07:08:42.096] Build 8909791304936267792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:08:42.096] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:40:0) after 1m0s
[07:09:42.189] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:40:0)
[07:09:42.189] Timer tick, asking Buildbucket for the build status
[07:09:42.279] Build 8909791304936267792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:09:42.279] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:41:0) after 1m0s
[07:10:42.350] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:41:0)
[07:10:42.350] Timer tick, asking Buildbucket for the build status
[07:10:42.469] Build 8909791304936267792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:10:42.469] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:42:0) after 1m0s
[07:11:42.675] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:42:0)
[07:11:42.675] Timer tick, asking Buildbucket for the build status
[07:11:42.761] Build 8909791304936267792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:11:42.761] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:43:0) after 1m0s
[07:12:43.147] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:43:0)
[07:12:43.147] Timer tick, asking Buildbucket for the build status
[07:12:43.314] Build 8909791304936267792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:12:43.314] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:44:0) after 1m0s
[07:13:43.510] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:44:0)
[07:13:43.510] Timer tick, asking Buildbucket for the build status
[07:13:43.733] Build 8909791304936267792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:13:43.733] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:45:0) after 1m0s
[07:14:43.881] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:45:0)
[07:14:43.881] Timer tick, asking Buildbucket for the build status
[07:14:44.236] Build 8909791304936267792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:14:44.236] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:46:0) after 1m0s
[07:15:44.301] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:46:0)
[07:15:44.301] Timer tick, asking Buildbucket for the build status
[07:15:44.423] Build 8909791304936267792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:15:44.423] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:47:0) after 1m0s
[07:16:44.443] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:47:0)
[07:16:44.443] Timer tick, asking Buildbucket for the build status
[07:16:44.660] Build 8909791304936267792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:16:44.660] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:48:0) after 1m0s
[07:17:44.781] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:48:0)
[07:17:44.781] Timer tick, asking Buildbucket for the build status
[07:17:44.977] Build 8909791304936267792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:17:44.977] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:49:0) after 1m0s
[07:18:45.016] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:49:0)
[07:18:45.016] Timer tick, asking Buildbucket for the build status
[07:18:45.322] Build 8909791304936267792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:18:45.322] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:50:0) after 1m0s
[07:19:45.477] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:50:0)
[07:19:45.477] Timer tick, asking Buildbucket for the build status
[07:19:45.692] Build 8909791304936267792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:19:45.692] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:51:0) after 1m0s
[07:20:45.767] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:51:0)
[07:20:45.768] Timer tick, asking Buildbucket for the build status
[07:20:45.846] Build 8909791304936267792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:20:45.846] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:52:0) after 1m0s
[07:21:45.883] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:52:0)
[07:21:45.883] Timer tick, asking Buildbucket for the build status
[07:21:45.964] Build 8909791304936267792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:21:45.964] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:53:0) after 1m0s
[07:22:46.076] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:53:0)
[07:22:46.076] Timer tick, asking Buildbucket for the build status
[07:22:46.600] Build 8909791304936267792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:22:46.600] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:54:0) after 1m0s
[07:23:46.648] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:54:0)
[07:23:46.648] Timer tick, asking Buildbucket for the build status
[07:23:46.719] Build 8909791304936267792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:23:46.719] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:55:0) after 1m0s
[07:24:46.842] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:55:0)
[07:24:46.842] Timer tick, asking Buildbucket for the build status
[07:24:46.974] Build 8909791304936267792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:24:46.974] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:56:0) after 1m0s
[07:25:46.992] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:56:0)
[07:25:46.992] Timer tick, asking Buildbucket for the build status
[07:25:47.893] Build 8909791304936267792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:25:47.893] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:57:0) after 1m0s
[07:26:47.931] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:57:0)
[07:26:47.931] Timer tick, asking Buildbucket for the build status
[07:26:48.034] Build 8909791304936267792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:26:48.034] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:58:0) after 1m0s
[07:27:48.055] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:58:0)
[07:27:48.055] Timer tick, asking Buildbucket for the build status
[07:27:48.206] Build 8909791304936267792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:27:48.206] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:59:0) after 1m0s
[07:28:48.286] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:59:0)
[07:28:48.286] Timer tick, asking Buildbucket for the build status
[07:28:48.500] Build 8909791304936267792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:28:48.500] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:60:0) after 1m0s
[07:29:48.599] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:60:0)
[07:29:48.599] Timer tick, asking Buildbucket for the build status
[07:29:48.687] Build 8909791304936267792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:29:48.687] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:61:0) after 1m0s
[07:30:48.678] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:61:0)
[07:30:48.678] Timer tick, asking Buildbucket for the build status
[07:30:48.725] Build 8909791304936267792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:30:48.725] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:62:0) after 1m0s
[07:31:48.758] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:62:0)
[07:31:48.758] Timer tick, asking Buildbucket for the build status
[07:31:48.843] Build 8909791304936267792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:31:48.843] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:63:0) after 1m0s
[07:32:48.922] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:63:0)
[07:32:48.922] Timer tick, asking Buildbucket for the build status
[07:32:49.002] Build 8909791304936267792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:32:49.002] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:64:0) after 1m0s
[07:33:49.039] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:64:0)
[07:33:49.039] Timer tick, asking Buildbucket for the build status
[07:33:49.311] Build 8909791304936267792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:33:49.311] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:65:0) after 1m0s
[07:34:49.332] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:65:0)
[07:34:49.332] Timer tick, asking Buildbucket for the build status
[07:34:49.416] Build 8909791304936267792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:34:49.416] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:66:0) after 1m0s
[07:35:49.440] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:66:0)
[07:35:49.440] Timer tick, asking Buildbucket for the build status
[07:35:49.954] Build 8909791304936267792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:35:49.954] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:67:0) after 1m0s
[07:36:50.082] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:67:0)
[07:36:50.082] Timer tick, asking Buildbucket for the build status
[07:36:50.287] Build 8909791304936267792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:36:50.287] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:68:0) after 1m0s
[07:37:50.317] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:68:0)
[07:37:50.317] Timer tick, asking Buildbucket for the build status
[07:37:50.405] Build 8909791304936267792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:37:50.405] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:69:0) after 1m0s
[07:38:50.444] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:69:0)
[07:38:50.444] Timer tick, asking Buildbucket for the build status
[07:38:50.548] Build 8909791304936267792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:38:50.548] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:70:0) after 1m0s
[07:39:50.619] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:70:0)
[07:39:50.619] Timer tick, asking Buildbucket for the build status
[07:39:50.749] Build 8909791304936267792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:39:50.749] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:71:0) after 1m0s
[07:40:50.767] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:71:0)
[07:40:50.767] Timer tick, asking Buildbucket for the build status
[07:40:51.478] Build 8909791304936267792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:40:51.478] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:72:0) after 1m0s
[07:41:51.542] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:72:0)
[07:41:51.542] Timer tick, asking Buildbucket for the build status
[07:41:51.693] Build 8909791304936267792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:41:51.693] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:73:0) after 1m0s
[07:42:51.717] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:73:0)
[07:42:51.717] Timer tick, asking Buildbucket for the build status
[07:42:52.192] Build 8909791304936267792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:42:52.192] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:74:0) after 1m0s
[07:43:52.264] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:74:0)
[07:43:52.264] Timer tick, asking Buildbucket for the build status
[07:43:52.583] Build 8909791304936267792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:43:52.583] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:75:0) after 1m0s
[07:44:52.630] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:75:0)
[07:44:52.630] Timer tick, asking Buildbucket for the build status
[07:44:52.743] Build 8909791304936267792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:44:52.743] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:76:0) after 1m0s
[07:45:52.764] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:76:0)
[07:45:52.764] Timer tick, asking Buildbucket for the build status
[07:45:52.995] Build 8909791304936267792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:45:52.995] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:77:0) after 1m0s
[07:46:53.020] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:77:0)
[07:46:53.020] Timer tick, asking Buildbucket for the build status
[07:46:53.134] Build 8909791304936267792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:46:53.134] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9075221367146423504:78:0) after 1m0s
[07:47:21.304] Received PubSub notification, asking Buildbucket for the build status
[07:47:21.441] Build 8909791304936267792: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[07:47:21.441] Buildbucket build:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary_preference": "PROD",
"completed_ts": "1561362434629385",
"created_by": "project:emscripten-releases",
"created_ts": "1561357890150607",
"failure_reason": "BUILD_FAILURE",
"id": "8909791304936267792",
"parameters_json": "{\"builder_name\": \"win\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"17ac703bcae6cb9aed512ca6bc5b062d9c20a451\"}}",
"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-zam6\", \"branch\": \"refs/heads/master\", \"buildername\": \"win\", \"got_revision\": \"17ac703bcae6cb9aed512ca6bc5b062d9c20a451\", \"path_config\": \"generic\", \"recipe\": \"emscripten_releases\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"17ac703bcae6cb9aed512ca6bc5b062d9c20a451\"}, \"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-zam6\"], \"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": "1561357895345609",
"status": "COMPLETED",
"status_changed_ts": "1561357896292001",
"tags": [
"builder:win",
"buildset:commit/git/17ac703bcae6cb9aed512ca6bc5b062d9c20a451",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/17ac703bcae6cb9aed512ca6bc5b062d9c20a451",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9075221367146423504",
"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/8909791304936267792/+/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:45a1003d8ec27f10",
"user_agent:luci-scheduler"
],
"updated_ts": "1561362434833671",
"url": "https://ci.chromium.org/b/8909791304936267792",
"utcnow_ts": "1561362441431076"
}
}
[07:47:21.441] Invocation finished in 1h15m52.781875595s with status FAILED