[08:34:04.544] New invocation is queued and will start shortly
[08:34:06.229] Starting the invocation (attempt 1)
[08:34:06.291] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[08:34:06.291] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[08:34:06.291] Buildbucket request:
{
"bucket": "luci.emscripten-releases.ci",
"client_operation_id": "9065519778560967808",
"parameters_json": "{\"builder_name\":\"win\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/emscripten-releases\",\"revision\":\"5cf2efd2244fa085244cd37dd583c0c384ef9eb1\"},\"id\":\"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@5cf2efd2244fa085244cd37dd583c0c384ef9eb1\",\"title\":\"5cf2efd2244fa085244cd37dd583c0c384ef9eb1\",\"url\":\"https://chromium.googlesource.com/emscripten-releases/+/5cf2efd2244fa085244cd37dd583c0c384ef9eb1\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/emscripten-releases\",\"revision\":\"5cf2efd2244fa085244cd37dd583c0c384ef9eb1\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win",
"scheduler_invocation_id:9065519778560967808",
"scheduler_job_id:emscripten-releases/win",
"user_agent:luci-scheduler",
"buildset:commit/git/5cf2efd2244fa085244cd37dd583c0c384ef9eb1",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/5cf2efd2244fa085244cd37dd583c0c384ef9eb1",
"gitiles_ref:refs/heads/master"
]
}
[08:34:07.271] Buildbucket response:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary_preference": "PROD",
"created_by": "project:emscripten-releases",
"created_ts": "1570610046422009",
"id": "8900089715920779200",
"parameters_json": "{\"builder_name\": \"win\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"5cf2efd2244fa085244cd37dd583c0c384ef9eb1\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@5cf2efd2244fa085244cd37dd583c0c384ef9eb1\", \"title\": \"5cf2efd2244fa085244cd37dd583c0c384ef9eb1\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/5cf2efd2244fa085244cd37dd583c0c384ef9eb1\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"5cf2efd2244fa085244cd37dd583c0c384ef9eb1\"}}",
"project": "emscripten-releases",
"result_details_json": "{\"properties\": {}}",
"service_account": "emscripten-releases-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1570610046931070",
"tags": [
"builder:win",
"buildset:commit/git/5cf2efd2244fa085244cd37dd583c0c384ef9eb1",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/5cf2efd2244fa085244cd37dd583c0c384ef9eb1",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9065519778560967808",
"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/8900089715920779200/+/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": "1570610046931264",
"url": "https://ci.chromium.org/b/8900089715920779200",
"utcnow_ts": "1570610047262383"
}
}
[08:34:07.271] Task URL: https://ci.chromium.org/b/8900089715920779200
[08:34:07.271] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065519778560967808:2:0) after 1m0s
[08:35:07.287] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065519778560967808:2:0)
[08:35:07.287] Timer tick, asking Buildbucket for the build status
[08:35:07.821] Build 8900089715920779200: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:35:07.821] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065519778560967808:3:0) after 1m0s
[08:35:28.362] Received PubSub notification, asking Buildbucket for the build status
[08:35:29.933] Build 8900089715920779200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:36:07.878] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065519778560967808:3:0)
[08:36:07.878] Timer tick, asking Buildbucket for the build status
[08:36:08.083] Build 8900089715920779200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:36:08.083] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065519778560967808:5:0) after 1m0s
[08:37:08.154] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065519778560967808:5:0)
[08:37:08.154] Timer tick, asking Buildbucket for the build status
[08:37:08.285] Build 8900089715920779200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:37:08.285] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065519778560967808:6:0) after 1m0s
[08:38:08.353] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065519778560967808:6:0)
[08:38:08.353] Timer tick, asking Buildbucket for the build status
[08:38:08.855] Build 8900089715920779200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:38:08.855] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065519778560967808:7:0) after 1m0s
[08:39:09.005] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065519778560967808:7:0)
[08:39:09.006] Timer tick, asking Buildbucket for the build status
[08:39:11.308] Build 8900089715920779200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:39:11.308] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065519778560967808:8:0) after 1m0s
[08:40:11.367] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065519778560967808:8:0)
[08:40:11.367] Timer tick, asking Buildbucket for the build status
[08:40:11.530] Build 8900089715920779200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:40:11.530] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065519778560967808:9:0) after 1m0s
[08:41:11.678] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065519778560967808:9:0)
[08:41:11.678] Timer tick, asking Buildbucket for the build status
[08:41:11.822] Build 8900089715920779200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:41:11.822] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065519778560967808:10:0) after 1m0s
[08:42:11.959] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065519778560967808:10:0)
[08:42:11.959] Timer tick, asking Buildbucket for the build status
[08:42:12.080] Build 8900089715920779200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:42:12.081] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065519778560967808:11:0) after 1m0s
[08:43:12.203] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065519778560967808:11:0)
[08:43:12.203] Timer tick, asking Buildbucket for the build status
[08:43:12.283] Build 8900089715920779200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:43:12.283] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065519778560967808:12:0) after 1m0s
[08:44:12.338] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065519778560967808:12:0)
[08:44:12.338] Timer tick, asking Buildbucket for the build status
[08:44:13.000] Build 8900089715920779200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:44:13.000] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065519778560967808:13:0) after 1m0s
[08:45:13.109] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065519778560967808:13:0)
[08:45:13.109] Timer tick, asking Buildbucket for the build status
[08:45:13.394] Build 8900089715920779200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:45:13.395] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065519778560967808:14:0) after 1m0s
[08:46:13.428] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065519778560967808:14:0)
[08:46:13.428] Timer tick, asking Buildbucket for the build status
[08:46:13.566] Build 8900089715920779200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:46:13.566] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065519778560967808:15:0) after 1m0s
[08:47:13.683] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065519778560967808:15:0)
[08:47:13.683] Timer tick, asking Buildbucket for the build status
[08:47:13.795] Build 8900089715920779200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:47:13.795] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065519778560967808:16:0) after 1m0s
[08:48:13.825] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065519778560967808:16:0)
[08:48:13.825] Timer tick, asking Buildbucket for the build status
[08:48:14.876] Build 8900089715920779200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:48:14.876] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065519778560967808:17:0) after 1m0s
[08:49:14.889] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065519778560967808:17:0)
[08:49:14.889] Timer tick, asking Buildbucket for the build status
[08:49:15.047] Build 8900089715920779200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:49:15.047] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065519778560967808:18:0) after 1m0s
[08:50:15.131] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065519778560967808:18:0)
[08:50:15.131] Timer tick, asking Buildbucket for the build status
[08:50:15.296] Build 8900089715920779200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:50:15.296] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065519778560967808:19:0) after 1m0s
[08:51:15.318] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065519778560967808:19:0)
[08:51:15.318] Timer tick, asking Buildbucket for the build status
[08:51:15.544] Build 8900089715920779200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:51:15.544] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065519778560967808:20:0) after 1m0s
[08:52:15.604] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065519778560967808:20:0)
[08:52:15.604] Timer tick, asking Buildbucket for the build status
[08:52:15.993] Build 8900089715920779200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:52:15.993] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065519778560967808:21:0) after 1m0s
[08:53:16.099] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065519778560967808:21:0)
[08:53:16.099] Timer tick, asking Buildbucket for the build status
[08:53:16.264] Build 8900089715920779200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:53:16.264] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065519778560967808:22:0) after 1m0s
[08:54:16.301] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065519778560967808:22:0)
[08:54:16.301] Timer tick, asking Buildbucket for the build status
[08:54:16.741] Build 8900089715920779200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:54:16.741] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065519778560967808:23:0) after 1m0s
[08:55:16.855] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065519778560967808:23:0)
[08:55:16.855] Timer tick, asking Buildbucket for the build status
[08:55:17.771] Build 8900089715920779200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:55:17.771] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065519778560967808:24:0) after 1m0s
[08:56:17.796] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065519778560967808:24:0)
[08:56:17.796] Timer tick, asking Buildbucket for the build status
[08:56:17.878] Build 8900089715920779200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:56:17.878] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065519778560967808:25:0) after 1m0s
[08:57:17.904] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065519778560967808:25:0)
[08:57:17.904] Timer tick, asking Buildbucket for the build status
[08:57:18.063] Build 8900089715920779200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:57:18.063] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065519778560967808:26:0) after 1m0s
[08:58:18.159] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065519778560967808:26:0)
[08:58:18.159] Timer tick, asking Buildbucket for the build status
[08:58:18.374] Build 8900089715920779200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:58:18.374] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065519778560967808:27:0) after 1m0s
[08:59:18.440] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065519778560967808:27:0)
[08:59:18.440] Timer tick, asking Buildbucket for the build status
[08:59:18.602] Build 8900089715920779200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:59:18.602] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065519778560967808:28:0) after 1m0s
[09:00:18.717] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065519778560967808:28:0)
[09:00:18.717] Timer tick, asking Buildbucket for the build status
[09:00:20.189] Build 8900089715920779200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:00:20.189] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065519778560967808:29:0) after 1m0s
[09:01:20.265] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065519778560967808:29:0)
[09:01:20.265] Timer tick, asking Buildbucket for the build status
[09:01:20.534] Build 8900089715920779200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:01:20.534] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065519778560967808:30:0) after 1m0s
[09:02:20.591] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065519778560967808:30:0)
[09:02:20.591] Timer tick, asking Buildbucket for the build status
[09:02:21.087] Build 8900089715920779200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:02:21.087] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065519778560967808:31:0) after 1m0s
[09:03:21.122] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065519778560967808:31:0)
[09:03:21.122] Timer tick, asking Buildbucket for the build status
[09:03:21.295] Build 8900089715920779200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:03:21.295] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065519778560967808:32:0) after 1m0s
[09:04:21.490] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065519778560967808:32:0)
[09:04:21.490] Timer tick, asking Buildbucket for the build status
[09:04:21.594] Build 8900089715920779200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:04:21.594] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065519778560967808:33:0) after 1m0s
[09:05:21.645] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065519778560967808:33:0)
[09:05:21.645] Timer tick, asking Buildbucket for the build status
[09:05:21.923] Build 8900089715920779200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:05:21.923] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065519778560967808:34:0) after 1m0s
[09:06:21.938] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065519778560967808:34:0)
[09:06:21.938] Timer tick, asking Buildbucket for the build status
[09:06:22.460] Build 8900089715920779200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:06:22.460] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065519778560967808:35:0) after 1m0s
[09:07:22.440] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065519778560967808:35:0)
[09:07:22.440] Timer tick, asking Buildbucket for the build status
[09:07:22.537] Build 8900089715920779200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:07:22.537] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065519778560967808:36:0) after 1m0s
[09:08:22.615] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065519778560967808:36:0)
[09:08:22.615] Timer tick, asking Buildbucket for the build status
[09:08:22.895] Build 8900089715920779200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:08:22.895] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065519778560967808:37:0) after 1m0s
[09:09:22.923] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065519778560967808:37:0)
[09:09:22.923] Timer tick, asking Buildbucket for the build status
[09:09:23.823] Build 8900089715920779200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:09:23.823] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065519778560967808:38:0) after 1m0s
[09:10:23.846] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065519778560967808:38:0)
[09:10:23.846] Timer tick, asking Buildbucket for the build status
[09:10:23.985] Build 8900089715920779200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:10:23.985] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065519778560967808:39:0) after 1m0s
[09:11:24.174] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065519778560967808:39:0)
[09:11:24.174] Timer tick, asking Buildbucket for the build status
[09:11:24.393] Build 8900089715920779200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:11:24.393] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065519778560967808:40:0) after 1m0s
[09:12:24.449] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065519778560967808:40:0)
[09:12:24.449] Timer tick, asking Buildbucket for the build status
[09:12:24.600] Build 8900089715920779200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:12:24.600] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065519778560967808:41:0) after 1m0s
[09:13:24.755] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065519778560967808:41:0)
[09:13:24.755] Timer tick, asking Buildbucket for the build status
[09:13:25.290] Build 8900089715920779200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:13:25.290] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065519778560967808:42:0) after 1m0s
[09:14:25.525] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065519778560967808:42:0)
[09:14:25.525] Timer tick, asking Buildbucket for the build status
[09:14:25.771] Build 8900089715920779200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:14:25.771] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065519778560967808:43:0) after 1m0s
[09:15:25.865] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065519778560967808:43:0)
[09:15:25.865] Timer tick, asking Buildbucket for the build status
[09:15:26.170] Build 8900089715920779200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:15:26.170] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065519778560967808:44:0) after 1m0s
[09:16:26.336] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065519778560967808:44:0)
[09:16:26.336] Timer tick, asking Buildbucket for the build status
[09:16:26.557] Build 8900089715920779200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:16:26.557] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065519778560967808:45:0) after 1m0s
[09:17:26.693] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065519778560967808:45:0)
[09:17:26.694] Timer tick, asking Buildbucket for the build status
[09:17:27.025] Build 8900089715920779200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:17:27.025] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065519778560967808:46:0) after 1m0s
[09:18:27.323] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065519778560967808:46:0)
[09:18:27.323] Timer tick, asking Buildbucket for the build status
[09:18:27.423] Build 8900089715920779200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:18:27.423] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065519778560967808:47:0) after 1m0s
[09:19:27.520] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065519778560967808:47:0)
[09:19:27.520] Timer tick, asking Buildbucket for the build status
[09:19:27.950] Build 8900089715920779200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:19:27.950] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065519778560967808:48:0) after 1m0s
[09:20:28.244] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065519778560967808:48:0)
[09:20:28.244] Timer tick, asking Buildbucket for the build status
[09:20:28.388] Build 8900089715920779200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:20:28.388] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065519778560967808:49:0) after 1m0s
[09:21:28.536] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065519778560967808:49:0)
[09:21:28.536] Timer tick, asking Buildbucket for the build status
[09:21:28.783] Build 8900089715920779200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:21:28.783] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065519778560967808:50:0) after 1m0s
[09:22:28.992] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065519778560967808:50:0)
[09:22:28.992] Timer tick, asking Buildbucket for the build status
[09:22:29.113] Build 8900089715920779200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:22:29.113] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065519778560967808:51:0) after 1m0s
[09:23:29.175] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065519778560967808:51:0)
[09:23:29.175] Timer tick, asking Buildbucket for the build status
[09:23:29.521] Build 8900089715920779200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:23:29.521] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065519778560967808:52:0) after 1m0s
[09:24:29.555] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065519778560967808:52:0)
[09:24:29.555] Timer tick, asking Buildbucket for the build status
[09:24:29.808] Build 8900089715920779200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:24:29.808] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065519778560967808:53:0) after 1m0s
[09:25:29.806] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065519778560967808:53:0)
[09:25:29.806] Timer tick, asking Buildbucket for the build status
[09:25:30.161] Build 8900089715920779200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:25:30.161] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065519778560967808:54:0) after 1m0s
[09:26:30.183] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065519778560967808:54:0)
[09:26:30.183] Timer tick, asking Buildbucket for the build status
[09:26:30.366] Build 8900089715920779200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:26:30.366] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065519778560967808:55:0) after 1m0s
[09:27:30.489] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065519778560967808:55:0)
[09:27:30.489] Timer tick, asking Buildbucket for the build status
[09:27:31.456] Build 8900089715920779200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:27:31.456] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065519778560967808:56:0) after 1m0s
[09:28:31.498] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065519778560967808:56:0)
[09:28:31.498] Timer tick, asking Buildbucket for the build status
[09:28:31.590] Build 8900089715920779200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:28:31.590] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065519778560967808:57:0) after 1m0s
[09:29:31.591] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065519778560967808:57:0)
[09:29:31.591] Timer tick, asking Buildbucket for the build status
[09:29:32.063] Build 8900089715920779200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:29:32.063] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065519778560967808:58:0) after 1m0s
[09:30:32.128] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065519778560967808:58:0)
[09:30:32.128] Timer tick, asking Buildbucket for the build status
[09:30:32.524] Build 8900089715920779200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:30:32.524] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065519778560967808:59:0) after 1m0s
[09:31:32.577] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065519778560967808:59:0)
[09:31:32.577] Timer tick, asking Buildbucket for the build status
[09:31:33.909] Build 8900089715920779200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:31:33.909] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065519778560967808:60:0) after 1m0s
[09:32:34.006] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065519778560967808:60:0)
[09:32:34.006] Timer tick, asking Buildbucket for the build status
[09:32:34.193] Build 8900089715920779200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:32:34.193] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065519778560967808:61:0) after 1m0s
[09:33:34.244] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065519778560967808:61:0)
[09:33:34.244] Timer tick, asking Buildbucket for the build status
[09:33:34.542] Build 8900089715920779200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:33:34.542] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065519778560967808:62:0) after 1m0s
[09:34:34.626] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065519778560967808:62:0)
[09:34:34.626] Timer tick, asking Buildbucket for the build status
[09:34:34.943] Build 8900089715920779200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:34:34.943] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065519778560967808:63:0) after 1m0s
[09:35:35.248] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065519778560967808:63:0)
[09:35:35.248] Timer tick, asking Buildbucket for the build status
[09:35:35.408] Build 8900089715920779200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:35:35.409] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065519778560967808:64:0) after 1m0s
[09:36:35.433] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065519778560967808:64:0)
[09:36:35.433] Timer tick, asking Buildbucket for the build status
[09:36:35.807] Build 8900089715920779200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:36:35.808] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065519778560967808:65:0) after 1m0s
[09:37:36.122] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9065519778560967808:65:0)
[09:37:36.122] Timer tick, asking Buildbucket for the build status
[09:37:36.441] Build 8900089715920779200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:37:36.441] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9065519778560967808:66:0) after 1m0s
[09:38:34.200] Received PubSub notification, asking Buildbucket for the build status
[09:38:34.410] Build 8900089715920779200: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[09:38:34.410] Buildbucket build:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary_preference": "PROD",
"completed_ts": "1570613913108394",
"created_by": "project:emscripten-releases",
"created_ts": "1570610046422009",
"id": "8900089715920779200",
"parameters_json": "{\"builder_name\": \"win\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"5cf2efd2244fa085244cd37dd583c0c384ef9eb1\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@5cf2efd2244fa085244cd37dd583c0c384ef9eb1\", \"title\": \"5cf2efd2244fa085244cd37dd583c0c384ef9eb1\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/5cf2efd2244fa085244cd37dd583c0c384ef9eb1\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"5cf2efd2244fa085244cd37dd583c0c384ef9eb1\"}}",
"project": "emscripten-releases",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$recipe_engine/path\": {\"cache_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\cache\", \"temp_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\tmp\\\\rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"5cf2efd2244fa085244cd37dd583c0c384ef9eb1\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@5cf2efd2244fa085244cd37dd583c0c384ef9eb1\", \"title\": \"5cf2efd2244fa085244cd37dd583c0c384ef9eb1\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/5cf2efd2244fa085244cd37dd583c0c384ef9eb1\"}]}, \"bot_id\": \"luci-emscripten-releases-ci-win10-0-nor8\", \"branch\": \"refs/heads/master\", \"buildername\": \"win\", \"got_revision\": \"5cf2efd2244fa085244cd37dd583c0c384ef9eb1\", \"path_config\": \"generic\", \"recipe\": \"emscripten_releases\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"5cf2efd2244fa085244cd37dd583c0c384ef9eb1\"}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"builder_0175bca77590805533ed6e20351c9bfa7effd036e42d11a5b619a6ce75a8e486_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"host_class\": [\"default\"], \"id\": [\"luci-emscripten-releases-ci-win10-0-nor8\"], \"image\": [\"chrome-win10-1703-19091300-a244b1e46d2\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"n1-standard-8\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.emscripten-releases.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"4494-2b7cf0c\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-b\"]}}}",
"service_account": "emscripten-releases-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1570610127010866",
"status": "COMPLETED",
"status_changed_ts": "1570613913710401",
"tags": [
"builder:win",
"buildset:commit/git/5cf2efd2244fa085244cd37dd583c0c384ef9eb1",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/5cf2efd2244fa085244cd37dd583c0c384ef9eb1",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9065519778560967808",
"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/8900089715920779200/+/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:47c879322a3c3410",
"user_agent:luci-scheduler"
],
"updated_ts": "1570613913723450",
"url": "https://ci.chromium.org/b/8900089715920779200",
"utcnow_ts": "1570613914390061"
}
}
[09:38:34.410] Invocation finished in 1h4m29.888435009s with status SUCCEEDED