[20:25:38.439] New invocation is queued and will start shortly
[20:25:39.793] Starting the invocation (attempt 1)
[20:25:39.879] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[20:25:39.879] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[20:25:39.879] Buildbucket request:
{
"bucket": "luci.emscripten-releases.ci",
"client_operation_id": "9037208757246039008",
"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\":\"4deb3dcea5b7e163b93bb529a49c26cf2e78a49e\"},\"id\":\"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@4deb3dcea5b7e163b93bb529a49c26cf2e78a49e\",\"title\":\"4deb3dcea5b7e163b93bb529a49c26cf2e78a49e\",\"url\":\"https://chromium.googlesource.com/emscripten-releases/+/4deb3dcea5b7e163b93bb529a49c26cf2e78a49e\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/emscripten-releases\",\"revision\":\"4deb3dcea5b7e163b93bb529a49c26cf2e78a49e\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win",
"scheduler_invocation_id:9037208757246039008",
"scheduler_job_id:emscripten-releases/win",
"user_agent:luci-scheduler",
"buildset:commit/git/4deb3dcea5b7e163b93bb529a49c26cf2e78a49e",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/4deb3dcea5b7e163b93bb529a49c26cf2e78a49e",
"gitiles_ref:refs/heads/master"
]
}
[20:25:40.428] Buildbucket response:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary_preference": "PROD",
"created_by": "project:emscripten-releases",
"created_ts": "1597609539967316",
"id": "8871778694977799152",
"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\": \"4deb3dcea5b7e163b93bb529a49c26cf2e78a49e\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@4deb3dcea5b7e163b93bb529a49c26cf2e78a49e\", \"title\": \"4deb3dcea5b7e163b93bb529a49c26cf2e78a49e\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/4deb3dcea5b7e163b93bb529a49c26cf2e78a49e\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"4deb3dcea5b7e163b93bb529a49c26cf2e78a49e\"}}",
"project": "emscripten-releases",
"result_details_json": "{\"properties\": {}}",
"service_account": "emscripten-releases-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1597609540272065",
"tags": [
"builder:win",
"buildset:commit/git/4deb3dcea5b7e163b93bb529a49c26cf2e78a49e",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/4deb3dcea5b7e163b93bb529a49c26cf2e78a49e",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9037208757246039008",
"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/8871778694977799152/+/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": "1597609540272196",
"url": "https://ci.chromium.org/b/8871778694977799152",
"utcnow_ts": "1597609540415941"
}
}
[20:25:40.428] Task URL: https://ci.chromium.org/b/8871778694977799152
[20:25:40.428] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037208757246039008:2:0) after 1m0s
[20:26:09.467] Received PubSub notification, asking Buildbucket for the build status
[20:26:09.753] Build 8871778694977799152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:26:40.522] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037208757246039008:2:0)
[20:26:40.522] Timer tick, asking Buildbucket for the build status
[20:26:40.637] Build 8871778694977799152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:26:40.637] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037208757246039008:4:0) after 1m0s
[20:27:40.743] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037208757246039008:4:0)
[20:27:40.743] Timer tick, asking Buildbucket for the build status
[20:27:40.895] Build 8871778694977799152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:27:40.895] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037208757246039008:5:0) after 1m0s
[20:28:40.952] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037208757246039008:5:0)
[20:28:40.953] Timer tick, asking Buildbucket for the build status
[20:28:41.142] Build 8871778694977799152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:28:41.142] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037208757246039008:6:0) after 1m0s
[20:29:41.155] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037208757246039008:6:0)
[20:29:41.155] Timer tick, asking Buildbucket for the build status
[20:29:41.295] Build 8871778694977799152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:29:41.295] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037208757246039008:7:0) after 1m0s
[20:30:41.329] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037208757246039008:7:0)
[20:30:41.329] Timer tick, asking Buildbucket for the build status
[20:30:41.479] Build 8871778694977799152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:30:41.479] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037208757246039008:8:0) after 1m0s
[20:31:41.515] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037208757246039008:8:0)
[20:31:41.515] Timer tick, asking Buildbucket for the build status
[20:31:41.819] Build 8871778694977799152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:31:41.819] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037208757246039008:9:0) after 1m0s
[20:32:41.909] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037208757246039008:9:0)
[20:32:41.909] Timer tick, asking Buildbucket for the build status
[20:32:42.014] Build 8871778694977799152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:32:42.014] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037208757246039008:10:0) after 1m0s
[20:33:42.019] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037208757246039008:10:0)
[20:33:42.019] Timer tick, asking Buildbucket for the build status
[20:33:42.122] Build 8871778694977799152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:33:42.122] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037208757246039008:11:0) after 1m0s
[20:34:42.161] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037208757246039008:11:0)
[20:34:42.161] Timer tick, asking Buildbucket for the build status
[20:34:42.379] Build 8871778694977799152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:34:42.379] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037208757246039008:12:0) after 1m0s
[20:35:42.465] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037208757246039008:12:0)
[20:35:42.465] Timer tick, asking Buildbucket for the build status
[20:35:42.656] Build 8871778694977799152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:35:42.656] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037208757246039008:13:0) after 1m0s
[20:36:42.637] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037208757246039008:13:0)
[20:36:42.637] Timer tick, asking Buildbucket for the build status
[20:36:42.829] Build 8871778694977799152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:36:42.829] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037208757246039008:14:0) after 1m0s
[20:37:42.873] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037208757246039008:14:0)
[20:37:42.873] Timer tick, asking Buildbucket for the build status
[20:37:42.960] Build 8871778694977799152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:37:42.960] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037208757246039008:15:0) after 1m0s
[20:38:42.980] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037208757246039008:15:0)
[20:38:42.980] Timer tick, asking Buildbucket for the build status
[20:38:43.172] Build 8871778694977799152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:38:43.172] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037208757246039008:16:0) after 1m0s
[20:39:43.192] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037208757246039008:16:0)
[20:39:43.192] Timer tick, asking Buildbucket for the build status
[20:39:43.272] Build 8871778694977799152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:39:43.272] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037208757246039008:17:0) after 1m0s
[20:40:43.322] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037208757246039008:17:0)
[20:40:43.322] Timer tick, asking Buildbucket for the build status
[20:40:43.468] Build 8871778694977799152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:40:43.468] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037208757246039008:18:0) after 1m0s
[20:41:43.497] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037208757246039008:18:0)
[20:41:43.497] Timer tick, asking Buildbucket for the build status
[20:41:43.573] Build 8871778694977799152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:41:43.573] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037208757246039008:19:0) after 1m0s
[20:42:43.636] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037208757246039008:19:0)
[20:42:43.636] Timer tick, asking Buildbucket for the build status
[20:42:43.705] Build 8871778694977799152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:42:43.705] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037208757246039008:20:0) after 1m0s
[20:43:43.879] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037208757246039008:20:0)
[20:43:43.879] Timer tick, asking Buildbucket for the build status
[20:43:43.949] Build 8871778694977799152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:43:43.949] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037208757246039008:21:0) after 1m0s
[20:44:44.910] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037208757246039008:21:0)
[20:44:44.910] Timer tick, asking Buildbucket for the build status
[20:44:44.961] Build 8871778694977799152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:44:44.961] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037208757246039008:22:0) after 1m0s
[20:45:45.218] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037208757246039008:22:0)
[20:45:45.218] Timer tick, asking Buildbucket for the build status
[20:45:45.364] Build 8871778694977799152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:45:45.364] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037208757246039008:23:0) after 1m0s
[20:46:45.504] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037208757246039008:23:0)
[20:46:45.504] Timer tick, asking Buildbucket for the build status
[20:46:45.588] Build 8871778694977799152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:46:45.588] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037208757246039008:24:0) after 1m0s
[20:47:45.688] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037208757246039008:24:0)
[20:47:45.688] Timer tick, asking Buildbucket for the build status
[20:47:45.758] Build 8871778694977799152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:47:45.758] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037208757246039008:25:0) after 1m0s
[20:48:46.121] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037208757246039008:25:0)
[20:48:46.122] Timer tick, asking Buildbucket for the build status
[20:48:46.203] Build 8871778694977799152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:48:46.203] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037208757246039008:26:0) after 1m0s
[20:49:46.341] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037208757246039008:26:0)
[20:49:46.341] Timer tick, asking Buildbucket for the build status
[20:49:46.457] Build 8871778694977799152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:49:46.457] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037208757246039008:27:0) after 1m0s
[20:50:46.603] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037208757246039008:27:0)
[20:50:46.603] Timer tick, asking Buildbucket for the build status
[20:50:46.723] Build 8871778694977799152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:50:46.723] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037208757246039008:28:0) after 1m0s
[20:51:46.798] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037208757246039008:28:0)
[20:51:46.798] Timer tick, asking Buildbucket for the build status
[20:51:46.868] Build 8871778694977799152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:51:46.868] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037208757246039008:29:0) after 1m0s
[20:52:47.046] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037208757246039008:29:0)
[20:52:47.046] Timer tick, asking Buildbucket for the build status
[20:52:47.122] Build 8871778694977799152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:52:47.122] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037208757246039008:30:0) after 1m0s
[20:53:47.193] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037208757246039008:30:0)
[20:53:47.193] Timer tick, asking Buildbucket for the build status
[20:53:47.265] Build 8871778694977799152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:53:47.265] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037208757246039008:31:0) after 1m0s
[20:54:47.415] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037208757246039008:31:0)
[20:54:47.415] Timer tick, asking Buildbucket for the build status
[20:54:47.517] Build 8871778694977799152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:54:47.517] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037208757246039008:32:0) after 1m0s
[20:55:47.759] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037208757246039008:32:0)
[20:55:47.759] Timer tick, asking Buildbucket for the build status
[20:55:47.902] Build 8871778694977799152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:55:47.902] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037208757246039008:33:0) after 1m0s
[20:56:47.976] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037208757246039008:33:0)
[20:56:47.976] Timer tick, asking Buildbucket for the build status
[20:56:48.103] Build 8871778694977799152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:56:48.103] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037208757246039008:34:0) after 1m0s
[20:57:48.189] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037208757246039008:34:0)
[20:57:48.189] Timer tick, asking Buildbucket for the build status
[20:57:48.263] Build 8871778694977799152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:57:48.263] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037208757246039008:35:0) after 1m0s
[20:58:48.349] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037208757246039008:35:0)
[20:58:48.350] Timer tick, asking Buildbucket for the build status
[20:58:48.431] Build 8871778694977799152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:58:48.431] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037208757246039008:36:0) after 1m0s
[20:59:48.512] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037208757246039008:36:0)
[20:59:48.512] Timer tick, asking Buildbucket for the build status
[20:59:48.731] Build 8871778694977799152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:59:48.731] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037208757246039008:37:0) after 1m0s
[21:00:48.767] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037208757246039008:37:0)
[21:00:48.767] Timer tick, asking Buildbucket for the build status
[21:00:49.016] Build 8871778694977799152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:00:49.016] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037208757246039008:38:0) after 1m0s
[21:01:49.091] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037208757246039008:38:0)
[21:01:49.091] Timer tick, asking Buildbucket for the build status
[21:01:49.167] Build 8871778694977799152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:01:49.167] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037208757246039008:39:0) after 1m0s
[21:02:49.215] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037208757246039008:39:0)
[21:02:49.215] Timer tick, asking Buildbucket for the build status
[21:02:49.292] Build 8871778694977799152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:02:49.292] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037208757246039008:40:0) after 1m0s
[21:03:49.325] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037208757246039008:40:0)
[21:03:49.325] Timer tick, asking Buildbucket for the build status
[21:03:49.501] Build 8871778694977799152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:03:49.501] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037208757246039008:41:0) after 1m0s
[21:04:49.585] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037208757246039008:41:0)
[21:04:49.585] Timer tick, asking Buildbucket for the build status
[21:04:49.828] Build 8871778694977799152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:04:49.829] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037208757246039008:42:0) after 1m0s
[21:05:49.983] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037208757246039008:42:0)
[21:05:49.983] Timer tick, asking Buildbucket for the build status
[21:05:50.179] Build 8871778694977799152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:05:50.179] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037208757246039008:43:0) after 1m0s
[21:06:50.214] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037208757246039008:43:0)
[21:06:50.214] Timer tick, asking Buildbucket for the build status
[21:06:50.435] Build 8871778694977799152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:06:50.435] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037208757246039008:44:0) after 1m0s
[21:07:50.465] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037208757246039008:44:0)
[21:07:50.465] Timer tick, asking Buildbucket for the build status
[21:07:50.531] Build 8871778694977799152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:07:50.531] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037208757246039008:45:0) after 1m0s
[21:08:50.572] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037208757246039008:45:0)
[21:08:50.572] Timer tick, asking Buildbucket for the build status
[21:08:50.688] Build 8871778694977799152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:08:50.689] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037208757246039008:46:0) after 1m0s
[21:09:50.724] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037208757246039008:46:0)
[21:09:50.724] Timer tick, asking Buildbucket for the build status
[21:09:50.792] Build 8871778694977799152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:09:50.792] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037208757246039008:47:0) after 1m0s
[21:10:50.822] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037208757246039008:47:0)
[21:10:50.822] Timer tick, asking Buildbucket for the build status
[21:10:50.973] Build 8871778694977799152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:10:50.973] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037208757246039008:48:0) after 1m0s
[21:11:51.045] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037208757246039008:48:0)
[21:11:51.045] Timer tick, asking Buildbucket for the build status
[21:11:51.394] Build 8871778694977799152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:11:51.394] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037208757246039008:49:0) after 1m0s
[21:12:51.433] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037208757246039008:49:0)
[21:12:51.433] Timer tick, asking Buildbucket for the build status
[21:12:51.498] Build 8871778694977799152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:12:51.498] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037208757246039008:50:0) after 1m0s
[21:13:51.522] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037208757246039008:50:0)
[21:13:51.522] Timer tick, asking Buildbucket for the build status
[21:13:51.604] Build 8871778694977799152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:13:51.604] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037208757246039008:51:0) after 1m0s
[21:14:51.670] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037208757246039008:51:0)
[21:14:51.670] Timer tick, asking Buildbucket for the build status
[21:14:51.746] Build 8871778694977799152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:14:51.746] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037208757246039008:52:0) after 1m0s
[21:15:51.803] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037208757246039008:52:0)
[21:15:51.803] Timer tick, asking Buildbucket for the build status
[21:15:51.985] Build 8871778694977799152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:15:51.985] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037208757246039008:53:0) after 1m0s
[21:16:52.015] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037208757246039008:53:0)
[21:16:52.015] Timer tick, asking Buildbucket for the build status
[21:16:52.326] Build 8871778694977799152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:16:52.326] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037208757246039008:54:0) after 1m0s
[21:17:52.385] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037208757246039008:54:0)
[21:17:52.385] Timer tick, asking Buildbucket for the build status
[21:17:52.494] Build 8871778694977799152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:17:52.494] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037208757246039008:55:0) after 1m0s
[21:18:52.624] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037208757246039008:55:0)
[21:18:52.626] Timer tick, asking Buildbucket for the build status
[21:18:52.734] Build 8871778694977799152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:18:52.734] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037208757246039008:56:0) after 1m0s
[21:19:52.735] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037208757246039008:56:0)
[21:19:52.735] Timer tick, asking Buildbucket for the build status
[21:19:52.841] Build 8871778694977799152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:19:52.841] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037208757246039008:57:0) after 1m0s
[21:20:52.902] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037208757246039008:57:0)
[21:20:52.902] Timer tick, asking Buildbucket for the build status
[21:20:53.108] Build 8871778694977799152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:20:53.108] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037208757246039008:58:0) after 1m0s
[21:21:53.136] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037208757246039008:58:0)
[21:21:53.136] Timer tick, asking Buildbucket for the build status
[21:21:53.245] Build 8871778694977799152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:21:53.245] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037208757246039008:59:0) after 1m0s
[21:22:53.310] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037208757246039008:59:0)
[21:22:53.310] Timer tick, asking Buildbucket for the build status
[21:22:53.583] Build 8871778694977799152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:22:53.583] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037208757246039008:60:0) after 1m0s
[21:23:53.616] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037208757246039008:60:0)
[21:23:53.616] Timer tick, asking Buildbucket for the build status
[21:23:53.687] Build 8871778694977799152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:23:53.687] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037208757246039008:61:0) after 1m0s
[21:24:53.746] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037208757246039008:61:0)
[21:24:53.746] Timer tick, asking Buildbucket for the build status
[21:24:53.838] Build 8871778694977799152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:24:53.838] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037208757246039008:62:0) after 1m0s
[21:25:53.844] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037208757246039008:62:0)
[21:25:53.844] Timer tick, asking Buildbucket for the build status
[21:25:53.949] Build 8871778694977799152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:25:53.949] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037208757246039008:63:0) after 1m0s
[21:26:54.065] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037208757246039008:63:0)
[21:26:54.065] Timer tick, asking Buildbucket for the build status
[21:26:54.134] Build 8871778694977799152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:26:54.134] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037208757246039008:64:0) after 1m0s
[21:27:54.257] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037208757246039008:64:0)
[21:27:54.258] Timer tick, asking Buildbucket for the build status
[21:27:54.342] Build 8871778694977799152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:27:54.342] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037208757246039008:65:0) after 1m0s
[21:28:54.393] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037208757246039008:65:0)
[21:28:54.393] Timer tick, asking Buildbucket for the build status
[21:28:54.530] Build 8871778694977799152: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:28:54.530] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037208757246039008:66:0) after 1m0s
[21:29:28.338] Received PubSub notification, asking Buildbucket for the build status
[21:29:28.463] Build 8871778694977799152: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[21:29:28.463] Buildbucket build:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary_preference": "PROD",
"completed_ts": "1597613367507805",
"created_by": "project:emscripten-releases",
"created_ts": "1597609539967316",
"id": "8871778694977799152",
"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\": \"4deb3dcea5b7e163b93bb529a49c26cf2e78a49e\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@4deb3dcea5b7e163b93bb529a49c26cf2e78a49e\", \"title\": \"4deb3dcea5b7e163b93bb529a49c26cf2e78a49e\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/4deb3dcea5b7e163b93bb529a49c26cf2e78a49e\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"4deb3dcea5b7e163b93bb529a49c26cf2e78a49e\"}}",
"project": "emscripten-releases",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/goma\": {\"enable_ats\": true, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\"}, \"$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\": \"4deb3dcea5b7e163b93bb529a49c26cf2e78a49e\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@4deb3dcea5b7e163b93bb529a49c26cf2e78a49e\", \"title\": \"4deb3dcea5b7e163b93bb529a49c26cf2e78a49e\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/4deb3dcea5b7e163b93bb529a49c26cf2e78a49e\"}]}, \"bot_id\": \"luci-emscripten-releases-ci-win10-0-7fy1\", \"branch\": \"refs/heads/master\", \"buildername\": \"win\", \"got_revision\": \"4deb3dcea5b7e163b93bb529a49c26cf2e78a49e\", \"path_config\": \"generic\", \"recipe\": \"emscripten_releases\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"4deb3dcea5b7e163b93bb529a49c26cf2e78a49e\"}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"builder_0175bca77590805533ed6e20351c9bfa7effd036e42d11a5b619a6ce75a8e486_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"host_class\": [\"default\"], \"id\": [\"luci-emscripten-releases-ci-win10-0-7fy1\"], \"image\": [\"chrome-win10-1703-20081400-1358d8153ce\"], \"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\": [\"5488-fc78d20\"], \"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": "1597609555406478",
"status": "COMPLETED",
"status_changed_ts": "1597613367951791",
"tags": [
"builder:win",
"buildset:commit/git/4deb3dcea5b7e163b93bb529a49c26cf2e78a49e",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/4deb3dcea5b7e163b93bb529a49c26cf2e78a49e",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9037208757246039008",
"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/8871778694977799152/+/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:4e11c483eb936410",
"user_agent:luci-scheduler"
],
"updated_ts": "1597613367969066",
"url": "https://ci.chromium.org/b/8871778694977799152",
"utcnow_ts": "1597613368449517"
}
}
[21:29:28.463] Invocation finished in 1h3m50.043670936s with status SUCCEEDED