[04:29:09.076] New invocation is queued and will start shortly
[04:29:10.432] Starting the invocation (attempt 1)
[04:29:10.477] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[04:29:10.477] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[04:29:10.477] Buildbucket request:
{
"bucket": "luci.emscripten-releases.ci",
"client_operation_id": "9037903113120328096",
"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\":\"fc893f9e1d4c1cea7a7589f7db6e80efdfe4c84d\"},\"id\":\"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@fc893f9e1d4c1cea7a7589f7db6e80efdfe4c84d\",\"title\":\"fc893f9e1d4c1cea7a7589f7db6e80efdfe4c84d\",\"url\":\"https://chromium.googlesource.com/emscripten-releases/+/fc893f9e1d4c1cea7a7589f7db6e80efdfe4c84d\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/emscripten-releases\",\"revision\":\"fc893f9e1d4c1cea7a7589f7db6e80efdfe4c84d\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win",
"scheduler_invocation_id:9037903113120328096",
"scheduler_job_id:emscripten-releases/win",
"user_agent:luci-scheduler",
"buildset:commit/git/fc893f9e1d4c1cea7a7589f7db6e80efdfe4c84d",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/fc893f9e1d4c1cea7a7589f7db6e80efdfe4c84d",
"gitiles_ref:refs/heads/master"
]
}
[04:29:11.225] Buildbucket response:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary_preference": "PROD",
"created_by": "project:emscripten-releases",
"created_ts": "1596947350578366",
"id": "8872473050878162096",
"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\": \"fc893f9e1d4c1cea7a7589f7db6e80efdfe4c84d\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@fc893f9e1d4c1cea7a7589f7db6e80efdfe4c84d\", \"title\": \"fc893f9e1d4c1cea7a7589f7db6e80efdfe4c84d\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/fc893f9e1d4c1cea7a7589f7db6e80efdfe4c84d\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"fc893f9e1d4c1cea7a7589f7db6e80efdfe4c84d\"}}",
"project": "emscripten-releases",
"result_details_json": "{\"properties\": {}}",
"service_account": "emscripten-releases-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1596947351023519",
"tags": [
"builder:win",
"buildset:commit/git/fc893f9e1d4c1cea7a7589f7db6e80efdfe4c84d",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/fc893f9e1d4c1cea7a7589f7db6e80efdfe4c84d",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9037903113120328096",
"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/8872473050878162096/+/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": "1596947351023634",
"url": "https://ci.chromium.org/b/8872473050878162096",
"utcnow_ts": "1596947351214654"
}
}
[04:29:11.225] Task URL: https://ci.chromium.org/b/8872473050878162096
[04:29:11.225] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037903113120328096:2:0) after 1m0s
[04:30:03.845] Received PubSub notification, asking Buildbucket for the build status
[04:30:04.168] Build 8872473050878162096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:30:11.282] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037903113120328096:2:0)
[04:30:11.283] Timer tick, asking Buildbucket for the build status
[04:30:11.363] Build 8872473050878162096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:30:11.363] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037903113120328096:4:0) after 1m0s
[04:31:11.384] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037903113120328096:4:0)
[04:31:11.384] Timer tick, asking Buildbucket for the build status
[04:31:11.596] Build 8872473050878162096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:31:11.596] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037903113120328096:5:0) after 1m0s
[04:32:11.656] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037903113120328096:5:0)
[04:32:11.656] Timer tick, asking Buildbucket for the build status
[04:32:11.769] Build 8872473050878162096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:32:11.769] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037903113120328096:6:0) after 1m0s
[04:33:11.799] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037903113120328096:6:0)
[04:33:11.799] Timer tick, asking Buildbucket for the build status
[04:33:11.988] Build 8872473050878162096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:33:11.988] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037903113120328096:7:0) after 1m0s
[04:34:12.008] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037903113120328096:7:0)
[04:34:12.008] Timer tick, asking Buildbucket for the build status
[04:34:12.091] Build 8872473050878162096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:34:12.092] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037903113120328096:8:0) after 1m0s
[04:35:12.100] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037903113120328096:8:0)
[04:35:12.100] Timer tick, asking Buildbucket for the build status
[04:35:12.298] Build 8872473050878162096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:35:12.298] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037903113120328096:9:0) after 1m0s
[04:36:12.368] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037903113120328096:9:0)
[04:36:12.368] Timer tick, asking Buildbucket for the build status
[04:36:12.471] Build 8872473050878162096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:36:12.471] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037903113120328096:10:0) after 1m0s
[04:37:12.574] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037903113120328096:10:0)
[04:37:12.574] Timer tick, asking Buildbucket for the build status
[04:37:12.815] Build 8872473050878162096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:37:12.815] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037903113120328096:11:0) after 1m0s
[04:38:12.846] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037903113120328096:11:0)
[04:38:12.846] Timer tick, asking Buildbucket for the build status
[04:38:12.966] Build 8872473050878162096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:38:12.966] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037903113120328096:12:0) after 1m0s
[04:39:13.063] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037903113120328096:12:0)
[04:39:13.063] Timer tick, asking Buildbucket for the build status
[04:39:13.181] Build 8872473050878162096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:39:13.181] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037903113120328096:13:0) after 1m0s
[04:40:13.205] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037903113120328096:13:0)
[04:40:13.205] Timer tick, asking Buildbucket for the build status
[04:40:13.321] Build 8872473050878162096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:40:13.321] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037903113120328096:14:0) after 1m0s
[04:41:13.348] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037903113120328096:14:0)
[04:41:13.348] Timer tick, asking Buildbucket for the build status
[04:41:13.493] Build 8872473050878162096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:41:13.493] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037903113120328096:15:0) after 1m0s
[04:42:13.534] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037903113120328096:15:0)
[04:42:13.534] Timer tick, asking Buildbucket for the build status
[04:42:13.608] Build 8872473050878162096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:42:13.608] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037903113120328096:16:0) after 1m0s
[04:43:13.630] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037903113120328096:16:0)
[04:43:13.630] Timer tick, asking Buildbucket for the build status
[04:43:13.723] Build 8872473050878162096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:43:13.723] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037903113120328096:17:0) after 1m0s
[04:44:13.751] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037903113120328096:17:0)
[04:44:13.752] Timer tick, asking Buildbucket for the build status
[04:44:14.034] Build 8872473050878162096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:44:14.034] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037903113120328096:18:0) after 1m0s
[04:45:14.033] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037903113120328096:18:0)
[04:45:14.033] Timer tick, asking Buildbucket for the build status
[04:45:14.155] Build 8872473050878162096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:45:14.155] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037903113120328096:19:0) after 1m0s
[04:46:14.341] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037903113120328096:19:0)
[04:46:14.341] Timer tick, asking Buildbucket for the build status
[04:46:14.815] Build 8872473050878162096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:46:14.815] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037903113120328096:20:0) after 1m0s
[04:47:14.863] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037903113120328096:20:0)
[04:47:14.863] Timer tick, asking Buildbucket for the build status
[04:47:15.043] Build 8872473050878162096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:47:15.043] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037903113120328096:21:0) after 1m0s
[04:48:15.071] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037903113120328096:21:0)
[04:48:15.071] Timer tick, asking Buildbucket for the build status
[04:48:15.152] Build 8872473050878162096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:48:15.152] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037903113120328096:22:0) after 1m0s
[04:49:15.182] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037903113120328096:22:0)
[04:49:15.182] Timer tick, asking Buildbucket for the build status
[04:49:15.344] Build 8872473050878162096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:49:15.344] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037903113120328096:23:0) after 1m0s
[04:50:15.372] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037903113120328096:23:0)
[04:50:15.372] Timer tick, asking Buildbucket for the build status
[04:50:15.474] Build 8872473050878162096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:50:15.474] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037903113120328096:24:0) after 1m0s
[04:51:15.538] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037903113120328096:24:0)
[04:51:15.538] Timer tick, asking Buildbucket for the build status
[04:51:15.688] Build 8872473050878162096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:51:15.688] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037903113120328096:25:0) after 1m0s
[04:52:15.712] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037903113120328096:25:0)
[04:52:15.712] Timer tick, asking Buildbucket for the build status
[04:52:16.003] Build 8872473050878162096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:52:16.003] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037903113120328096:26:0) after 1m0s
[04:53:15.977] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037903113120328096:26:0)
[04:53:15.977] Timer tick, asking Buildbucket for the build status
[04:53:16.104] Build 8872473050878162096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:53:16.104] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037903113120328096:27:0) after 1m0s
[04:54:16.212] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037903113120328096:27:0)
[04:54:16.212] Timer tick, asking Buildbucket for the build status
[04:54:16.488] Build 8872473050878162096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:54:16.488] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037903113120328096:28:0) after 1m0s
[04:55:16.639] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037903113120328096:28:0)
[04:55:16.639] Timer tick, asking Buildbucket for the build status
[04:55:16.778] Build 8872473050878162096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:55:16.778] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037903113120328096:29:0) after 1m0s
[04:56:16.765] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037903113120328096:29:0)
[04:56:16.765] Timer tick, asking Buildbucket for the build status
[04:56:16.908] Build 8872473050878162096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:56:16.908] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037903113120328096:30:0) after 1m0s
[04:57:16.956] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037903113120328096:30:0)
[04:57:16.956] Timer tick, asking Buildbucket for the build status
[04:57:17.173] Build 8872473050878162096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:57:17.173] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037903113120328096:31:0) after 1m0s
[04:58:17.199] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037903113120328096:31:0)
[04:58:17.199] Timer tick, asking Buildbucket for the build status
[04:58:17.261] Build 8872473050878162096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:58:17.261] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037903113120328096:32:0) after 1m0s
[04:59:17.337] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037903113120328096:32:0)
[04:59:17.337] Timer tick, asking Buildbucket for the build status
[04:59:17.605] Build 8872473050878162096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:59:17.606] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037903113120328096:33:0) after 1m0s
[05:00:17.715] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037903113120328096:33:0)
[05:00:17.715] Timer tick, asking Buildbucket for the build status
[05:00:17.985] Build 8872473050878162096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:00:17.985] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037903113120328096:34:0) after 1m0s
[05:01:18.091] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037903113120328096:34:0)
[05:01:18.091] Timer tick, asking Buildbucket for the build status
[05:01:18.206] Build 8872473050878162096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:01:18.206] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037903113120328096:35:0) after 1m0s
[05:02:18.273] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037903113120328096:35:0)
[05:02:18.273] Timer tick, asking Buildbucket for the build status
[05:02:18.358] Build 8872473050878162096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:02:18.358] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037903113120328096:36:0) after 1m0s
[05:03:18.486] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037903113120328096:36:0)
[05:03:18.486] Timer tick, asking Buildbucket for the build status
[05:03:18.576] Build 8872473050878162096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:03:18.576] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037903113120328096:37:0) after 1m0s
[05:04:18.712] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037903113120328096:37:0)
[05:04:18.712] Timer tick, asking Buildbucket for the build status
[05:04:18.824] Build 8872473050878162096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:04:18.824] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037903113120328096:38:0) after 1m0s
[05:05:18.980] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037903113120328096:38:0)
[05:05:18.980] Timer tick, asking Buildbucket for the build status
[05:05:19.077] Build 8872473050878162096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:05:19.077] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037903113120328096:39:0) after 1m0s
[05:06:19.125] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037903113120328096:39:0)
[05:06:19.125] Timer tick, asking Buildbucket for the build status
[05:06:19.399] Build 8872473050878162096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:06:19.399] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037903113120328096:40:0) after 1m0s
[05:07:19.391] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037903113120328096:40:0)
[05:07:19.391] Timer tick, asking Buildbucket for the build status
[05:07:19.527] Build 8872473050878162096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:07:19.527] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037903113120328096:41:0) after 1m0s
[05:08:19.625] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037903113120328096:41:0)
[05:08:19.625] Timer tick, asking Buildbucket for the build status
[05:08:19.748] Build 8872473050878162096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:08:19.748] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037903113120328096:42:0) after 1m0s
[05:09:19.902] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037903113120328096:42:0)
[05:09:19.902] Timer tick, asking Buildbucket for the build status
[05:09:20.043] Build 8872473050878162096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:09:20.043] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037903113120328096:43:0) after 1m0s
[05:10:20.017] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037903113120328096:43:0)
[05:10:20.017] Timer tick, asking Buildbucket for the build status
[05:10:20.584] Build 8872473050878162096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:10:20.584] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037903113120328096:44:0) after 1m0s
[05:11:20.639] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037903113120328096:44:0)
[05:11:20.639] Timer tick, asking Buildbucket for the build status
[05:11:20.730] Build 8872473050878162096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:11:20.730] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037903113120328096:45:0) after 1m0s
[05:12:20.729] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037903113120328096:45:0)
[05:12:20.729] Timer tick, asking Buildbucket for the build status
[05:12:23.687] Build 8872473050878162096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:12:23.687] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037903113120328096:46:0) after 1m0s
[05:13:23.731] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037903113120328096:46:0)
[05:13:23.731] Timer tick, asking Buildbucket for the build status
[05:13:23.814] Build 8872473050878162096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:13:23.814] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037903113120328096:47:0) after 1m0s
[05:14:23.834] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037903113120328096:47:0)
[05:14:23.834] Timer tick, asking Buildbucket for the build status
[05:14:24.112] Build 8872473050878162096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:14:24.112] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037903113120328096:48:0) after 1m0s
[05:15:24.275] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037903113120328096:48:0)
[05:15:24.275] Timer tick, asking Buildbucket for the build status
[05:15:24.612] Build 8872473050878162096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:15:24.612] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037903113120328096:49:0) after 1m0s
[05:16:24.716] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037903113120328096:49:0)
[05:16:24.716] Timer tick, asking Buildbucket for the build status
[05:16:24.782] Build 8872473050878162096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:16:24.782] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037903113120328096:50:0) after 1m0s
[05:17:24.875] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037903113120328096:50:0)
[05:17:24.875] Timer tick, asking Buildbucket for the build status
[05:17:24.965] Build 8872473050878162096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:17:24.965] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037903113120328096:51:0) after 1m0s
[05:18:25.099] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037903113120328096:51:0)
[05:18:25.099] Timer tick, asking Buildbucket for the build status
[05:18:25.258] Build 8872473050878162096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:18:25.258] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037903113120328096:52:0) after 1m0s
[05:19:25.283] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037903113120328096:52:0)
[05:19:25.283] Timer tick, asking Buildbucket for the build status
[05:19:25.408] Build 8872473050878162096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:19:25.408] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037903113120328096:53:0) after 1m0s
[05:20:25.467] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037903113120328096:53:0)
[05:20:25.467] Timer tick, asking Buildbucket for the build status
[05:20:25.694] Build 8872473050878162096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:20:25.694] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037903113120328096:54:0) after 1m0s
[05:21:25.840] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037903113120328096:54:0)
[05:21:25.840] Timer tick, asking Buildbucket for the build status
[05:21:25.914] Build 8872473050878162096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:21:25.914] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037903113120328096:55:0) after 1m0s
[05:22:26.093] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037903113120328096:55:0)
[05:22:26.093] Timer tick, asking Buildbucket for the build status
[05:22:26.224] Build 8872473050878162096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:22:26.224] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037903113120328096:56:0) after 1m0s
[05:23:26.301] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037903113120328096:56:0)
[05:23:26.301] Timer tick, asking Buildbucket for the build status
[05:23:26.504] Build 8872473050878162096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:23:26.504] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037903113120328096:57:0) after 1m0s
[05:24:26.583] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037903113120328096:57:0)
[05:24:26.583] Timer tick, asking Buildbucket for the build status
[05:24:26.767] Build 8872473050878162096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:24:26.767] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037903113120328096:58:0) after 1m0s
[05:25:26.842] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037903113120328096:58:0)
[05:25:26.842] Timer tick, asking Buildbucket for the build status
[05:25:26.996] Build 8872473050878162096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:25:26.996] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037903113120328096:59:0) after 1m0s
[05:26:27.006] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037903113120328096:59:0)
[05:26:27.006] Timer tick, asking Buildbucket for the build status
[05:26:27.092] Build 8872473050878162096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:26:27.092] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037903113120328096:60:0) after 1m0s
[05:27:27.122] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037903113120328096:60:0)
[05:27:27.122] Timer tick, asking Buildbucket for the build status
[05:27:27.406] Build 8872473050878162096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:27:27.406] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037903113120328096:61:0) after 1m0s
[05:28:27.431] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037903113120328096:61:0)
[05:28:27.431] Timer tick, asking Buildbucket for the build status
[05:28:27.514] Build 8872473050878162096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:28:27.514] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037903113120328096:62:0) after 1m0s
[05:29:27.504] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037903113120328096:62:0)
[05:29:27.504] Timer tick, asking Buildbucket for the build status
[05:29:27.682] Build 8872473050878162096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:29:27.682] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037903113120328096:63:0) after 1m0s
[05:30:27.902] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037903113120328096:63:0)
[05:30:27.902] Timer tick, asking Buildbucket for the build status
[05:30:27.984] Build 8872473050878162096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:30:27.984] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037903113120328096:64:0) after 1m0s
[05:31:28.101] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037903113120328096:64:0)
[05:31:28.101] Timer tick, asking Buildbucket for the build status
[05:31:28.172] Build 8872473050878162096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:31:28.172] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037903113120328096:65:0) after 1m0s
[05:32:28.259] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037903113120328096:65:0)
[05:32:28.259] Timer tick, asking Buildbucket for the build status
[05:32:28.335] Build 8872473050878162096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:32:28.335] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037903113120328096:66:0) after 1m0s
[05:33:28.445] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037903113120328096:66:0)
[05:33:28.445] Timer tick, asking Buildbucket for the build status
[05:33:28.540] Build 8872473050878162096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:33:28.540] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037903113120328096:67:0) after 1m0s
[05:34:28.572] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037903113120328096:67:0)
[05:34:28.572] Timer tick, asking Buildbucket for the build status
[05:34:28.694] Build 8872473050878162096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:34:28.694] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037903113120328096:68:0) after 1m0s
[05:35:28.741] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037903113120328096:68:0)
[05:35:28.741] Timer tick, asking Buildbucket for the build status
[05:35:28.826] Build 8872473050878162096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:35:28.826] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037903113120328096:69:0) after 1m0s
[05:36:28.865] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037903113120328096:69:0)
[05:36:28.865] Timer tick, asking Buildbucket for the build status
[05:36:29.067] Build 8872473050878162096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:36:29.067] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037903113120328096:70:0) after 1m0s
[05:37:29.072] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037903113120328096:70:0)
[05:37:29.072] Timer tick, asking Buildbucket for the build status
[05:37:29.134] Build 8872473050878162096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:37:29.134] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037903113120328096:71:0) after 1m0s
[05:38:29.190] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037903113120328096:71:0)
[05:38:29.190] Timer tick, asking Buildbucket for the build status
[05:38:29.277] Build 8872473050878162096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:38:29.277] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037903113120328096:72:0) after 1m0s
[05:39:29.335] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037903113120328096:72:0)
[05:39:29.335] Timer tick, asking Buildbucket for the build status
[05:39:29.446] Build 8872473050878162096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:39:29.446] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037903113120328096:73:0) after 1m0s
[05:39:53.882] Received PubSub notification, asking Buildbucket for the build status
[05:39:54.183] Build 8872473050878162096: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[05:39:54.184] Buildbucket build:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary_preference": "PROD",
"completed_ts": "1596951593050991",
"created_by": "project:emscripten-releases",
"created_ts": "1596947350578366",
"id": "8872473050878162096",
"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\": \"fc893f9e1d4c1cea7a7589f7db6e80efdfe4c84d\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@fc893f9e1d4c1cea7a7589f7db6e80efdfe4c84d\", \"title\": \"fc893f9e1d4c1cea7a7589f7db6e80efdfe4c84d\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/fc893f9e1d4c1cea7a7589f7db6e80efdfe4c84d\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"fc893f9e1d4c1cea7a7589f7db6e80efdfe4c84d\"}}",
"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\": \"fc893f9e1d4c1cea7a7589f7db6e80efdfe4c84d\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@fc893f9e1d4c1cea7a7589f7db6e80efdfe4c84d\", \"title\": \"fc893f9e1d4c1cea7a7589f7db6e80efdfe4c84d\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/fc893f9e1d4c1cea7a7589f7db6e80efdfe4c84d\"}]}, \"bot_id\": \"luci-emscripten-releases-ci-win10-0-gt3a\", \"branch\": \"refs/heads/master\", \"buildername\": \"win\", \"got_revision\": \"fc893f9e1d4c1cea7a7589f7db6e80efdfe4c84d\", \"path_config\": \"generic\", \"recipe\": \"emscripten_releases\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"fc893f9e1d4c1cea7a7589f7db6e80efdfe4c84d\"}, \"swarming\": {\"bot_dimensions\": {\"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-gt3a\"], \"image\": [\"chrome-win10-1703-20073100-2eb11ddbc6e\"], \"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\": [\"5472-d0af7f3\"], \"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": "1596947401406814",
"status": "COMPLETED",
"status_changed_ts": "1596951593366383",
"tags": [
"builder:win",
"buildset:commit/git/fc893f9e1d4c1cea7a7589f7db6e80efdfe4c84d",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/fc893f9e1d4c1cea7a7589f7db6e80efdfe4c84d",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9037903113120328096",
"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/8872473050878162096/+/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:4dea4c516ab66b10",
"user_agent:luci-scheduler"
],
"updated_ts": "1596951593381656",
"url": "https://ci.chromium.org/b/8872473050878162096",
"utcnow_ts": "1596951594130561"
}
}
[05:39:54.184] Invocation finished in 1h10m45.126825839s with status SUCCEEDED