[22:41:35.206] New invocation is queued and will start shortly
[22:41:37.232] Starting the invocation (attempt 1)
[22:41:37.269] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[22:41:37.269] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[22:41:37.269] Buildbucket request:
{
"bucket": "luci.emscripten-releases.ci",
"client_operation_id": "9057403327762067072",
"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\":\"fc69c547ebab52798e5af1901e808c12c753d7a4\"},\"id\":\"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@fc69c547ebab52798e5af1901e808c12c753d7a4\",\"title\":\"fc69c547ebab52798e5af1901e808c12c753d7a4\",\"url\":\"https://chromium.googlesource.com/emscripten-releases/+/fc69c547ebab52798e5af1901e808c12c753d7a4\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/emscripten-releases\",\"revision\":\"fc69c547ebab52798e5af1901e808c12c753d7a4\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win",
"scheduler_invocation_id:9057403327762067072",
"scheduler_job_id:emscripten-releases/win",
"user_agent:luci-scheduler",
"buildset:commit/git/fc69c547ebab52798e5af1901e808c12c753d7a4",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/fc69c547ebab52798e5af1901e808c12c753d7a4",
"gitiles_ref:refs/heads/master"
]
}
[22:41:38.492] Buildbucket response:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary_preference": "PROD",
"created_by": "project:emscripten-releases",
"created_ts": "1578350497364782",
"id": "8891973264834532624",
"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\": \"fc69c547ebab52798e5af1901e808c12c753d7a4\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@fc69c547ebab52798e5af1901e808c12c753d7a4\", \"title\": \"fc69c547ebab52798e5af1901e808c12c753d7a4\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/fc69c547ebab52798e5af1901e808c12c753d7a4\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"fc69c547ebab52798e5af1901e808c12c753d7a4\"}}",
"project": "emscripten-releases",
"result_details_json": "{\"properties\": {}}",
"service_account": "emscripten-releases-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1578350497735692",
"tags": [
"builder:win",
"buildset:commit/git/fc69c547ebab52798e5af1901e808c12c753d7a4",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/fc69c547ebab52798e5af1901e808c12c753d7a4",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9057403327762067072",
"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/8891973264834532624/+/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": "1578350497735805",
"url": "https://ci.chromium.org/b/8891973264834532624",
"utcnow_ts": "1578350498459356"
}
}
[22:41:38.492] Task URL: https://ci.chromium.org/b/8891973264834532624
[22:41:38.492] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9057403327762067072:2:0) after 1m0s
[22:42:38.488] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9057403327762067072:2:0)
[22:42:38.488] Timer tick, asking Buildbucket for the build status
[22:42:38.927] Build 8891973264834532624: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:42:38.927] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9057403327762067072:3:0) after 1m0s
[22:43:38.961] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9057403327762067072:3:0)
[22:43:38.961] Timer tick, asking Buildbucket for the build status
[22:43:39.717] Build 8891973264834532624: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:43:39.717] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9057403327762067072:4:0) after 1m0s
[22:44:31.700] Received PubSub notification, asking Buildbucket for the build status
[22:44:31.918] Build 8891973264834532624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:44:39.801] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9057403327762067072:4:0)
[22:44:39.801] Timer tick, asking Buildbucket for the build status
[22:44:39.878] Build 8891973264834532624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:44:39.878] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9057403327762067072:6:0) after 1m0s
[22:45:39.913] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9057403327762067072:6:0)
[22:45:39.913] Timer tick, asking Buildbucket for the build status
[22:45:40.127] Build 8891973264834532624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:45:40.127] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9057403327762067072:7:0) after 1m0s
[22:46:40.153] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9057403327762067072:7:0)
[22:46:40.153] Timer tick, asking Buildbucket for the build status
[22:46:40.338] Build 8891973264834532624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:46:40.338] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9057403327762067072:8:0) after 1m0s
[22:47:40.435] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9057403327762067072:8:0)
[22:47:40.435] Timer tick, asking Buildbucket for the build status
[22:47:40.532] Build 8891973264834532624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:47:40.532] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9057403327762067072:9:0) after 1m0s
[22:48:40.521] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9057403327762067072:9:0)
[22:48:40.521] Timer tick, asking Buildbucket for the build status
[22:48:40.872] Build 8891973264834532624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:48:40.872] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9057403327762067072:10:0) after 1m0s
[22:49:40.953] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9057403327762067072:10:0)
[22:49:40.953] Timer tick, asking Buildbucket for the build status
[22:49:41.110] Build 8891973264834532624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:49:41.110] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9057403327762067072:11:0) after 1m0s
[22:50:41.171] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9057403327762067072:11:0)
[22:50:41.171] Timer tick, asking Buildbucket for the build status
[22:50:41.598] Build 8891973264834532624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:50:41.598] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9057403327762067072:12:0) after 1m0s
[22:51:41.633] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9057403327762067072:12:0)
[22:51:41.633] Timer tick, asking Buildbucket for the build status
[22:51:41.687] Build 8891973264834532624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:51:41.687] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9057403327762067072:13:0) after 1m0s
[22:52:41.657] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9057403327762067072:13:0)
[22:52:41.657] Timer tick, asking Buildbucket for the build status
[22:52:41.866] Build 8891973264834532624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:52:41.866] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9057403327762067072:14:0) after 1m0s
[22:53:41.887] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9057403327762067072:14:0)
[22:53:41.887] Timer tick, asking Buildbucket for the build status
[22:53:42.381] Build 8891973264834532624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:53:42.381] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9057403327762067072:15:0) after 1m0s
[22:54:42.430] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9057403327762067072:15:0)
[22:54:42.430] Timer tick, asking Buildbucket for the build status
[22:54:42.508] Build 8891973264834532624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:54:42.508] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9057403327762067072:16:0) after 1m0s
[22:55:42.729] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9057403327762067072:16:0)
[22:55:42.729] Timer tick, asking Buildbucket for the build status
[22:55:42.939] Build 8891973264834532624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:55:42.939] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9057403327762067072:17:0) after 1m0s
[22:56:43.107] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9057403327762067072:17:0)
[22:56:43.107] Timer tick, asking Buildbucket for the build status
[22:56:43.204] Build 8891973264834532624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:56:43.204] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9057403327762067072:18:0) after 1m0s
[22:57:43.447] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9057403327762067072:18:0)
[22:57:43.447] Timer tick, asking Buildbucket for the build status
[22:57:43.744] Build 8891973264834532624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:57:43.744] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9057403327762067072:19:0) after 1m0s
[22:58:44.272] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9057403327762067072:19:0)
[22:58:44.272] Timer tick, asking Buildbucket for the build status
[22:58:44.432] Build 8891973264834532624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:58:44.432] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9057403327762067072:20:0) after 1m0s
[22:59:44.778] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9057403327762067072:20:0)
[22:59:44.778] Timer tick, asking Buildbucket for the build status
[22:59:44.882] Build 8891973264834532624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:59:44.882] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9057403327762067072:21:0) after 1m0s
[23:00:45.026] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9057403327762067072:21:0)
[23:00:45.026] Timer tick, asking Buildbucket for the build status
[23:00:45.141] Build 8891973264834532624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:00:45.141] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9057403327762067072:22:0) after 1m0s
[23:01:45.196] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9057403327762067072:22:0)
[23:01:45.196] Timer tick, asking Buildbucket for the build status
[23:01:45.470] Build 8891973264834532624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:01:45.470] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9057403327762067072:23:0) after 1m0s
[23:02:45.489] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9057403327762067072:23:0)
[23:02:45.489] Timer tick, asking Buildbucket for the build status
[23:02:45.858] Build 8891973264834532624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:02:45.858] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9057403327762067072:24:0) after 1m0s
[23:03:45.864] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9057403327762067072:24:0)
[23:03:45.864] Timer tick, asking Buildbucket for the build status
[23:03:45.995] Build 8891973264834532624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:03:45.995] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9057403327762067072:25:0) after 1m0s
[23:04:46.062] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9057403327762067072:25:0)
[23:04:46.062] Timer tick, asking Buildbucket for the build status
[23:04:46.211] Build 8891973264834532624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:04:46.211] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9057403327762067072:26:0) after 1m0s
[23:05:46.326] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9057403327762067072:26:0)
[23:05:46.326] Timer tick, asking Buildbucket for the build status
[23:05:46.661] Build 8891973264834532624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:05:46.661] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9057403327762067072:27:0) after 1m0s
[23:06:46.871] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9057403327762067072:27:0)
[23:06:46.871] Timer tick, asking Buildbucket for the build status
[23:06:47.128] Build 8891973264834532624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:06:47.128] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9057403327762067072:28:0) after 1m0s
[23:07:47.229] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9057403327762067072:28:0)
[23:07:47.229] Timer tick, asking Buildbucket for the build status
[23:07:47.320] Build 8891973264834532624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:07:47.320] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9057403327762067072:29:0) after 1m0s
[23:08:47.396] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9057403327762067072:29:0)
[23:08:47.396] Timer tick, asking Buildbucket for the build status
[23:08:47.508] Build 8891973264834532624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:08:47.508] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9057403327762067072:30:0) after 1m0s
[23:09:47.646] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9057403327762067072:30:0)
[23:09:47.646] Timer tick, asking Buildbucket for the build status
[23:09:47.753] Build 8891973264834532624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:09:47.753] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9057403327762067072:31:0) after 1m0s
[23:10:47.923] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9057403327762067072:31:0)
[23:10:47.923] Timer tick, asking Buildbucket for the build status
[23:10:48.016] Build 8891973264834532624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:10:48.016] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9057403327762067072:32:0) after 1m0s
[23:11:48.023] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9057403327762067072:32:0)
[23:11:48.023] Timer tick, asking Buildbucket for the build status
[23:11:48.313] Build 8891973264834532624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:11:48.313] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9057403327762067072:33:0) after 1m0s
[23:12:48.392] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9057403327762067072:33:0)
[23:12:48.392] Timer tick, asking Buildbucket for the build status
[23:12:48.532] Build 8891973264834532624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:12:48.532] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9057403327762067072:34:0) after 1m0s
[23:13:48.668] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9057403327762067072:34:0)
[23:13:48.668] Timer tick, asking Buildbucket for the build status
[23:13:48.734] Build 8891973264834532624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:13:48.734] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9057403327762067072:35:0) after 1m0s
[23:14:48.941] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9057403327762067072:35:0)
[23:14:48.941] Timer tick, asking Buildbucket for the build status
[23:14:49.367] Build 8891973264834532624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:14:49.367] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9057403327762067072:36:0) after 1m0s
[23:15:49.683] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9057403327762067072:36:0)
[23:15:49.683] Timer tick, asking Buildbucket for the build status
[23:15:49.732] Build 8891973264834532624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:15:49.732] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9057403327762067072:37:0) after 1m0s
[23:16:49.899] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9057403327762067072:37:0)
[23:16:49.899] Timer tick, asking Buildbucket for the build status
[23:16:50.182] Build 8891973264834532624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:16:50.182] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9057403327762067072:38:0) after 1m0s
[23:17:50.556] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9057403327762067072:38:0)
[23:17:50.556] Timer tick, asking Buildbucket for the build status
[23:17:50.693] Build 8891973264834532624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:17:50.693] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9057403327762067072:39:0) after 1m0s
[23:18:51.017] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9057403327762067072:39:0)
[23:18:51.017] Timer tick, asking Buildbucket for the build status
[23:18:51.186] Build 8891973264834532624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:18:51.186] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9057403327762067072:40:0) after 1m0s
[23:19:51.312] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9057403327762067072:40:0)
[23:19:51.312] Timer tick, asking Buildbucket for the build status
[23:19:51.414] Build 8891973264834532624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:19:51.414] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9057403327762067072:41:0) after 1m0s
[23:20:51.788] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9057403327762067072:41:0)
[23:20:51.788] Timer tick, asking Buildbucket for the build status
[23:20:51.951] Build 8891973264834532624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:20:51.951] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9057403327762067072:42:0) after 1m0s
[23:21:52.066] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9057403327762067072:42:0)
[23:21:52.066] Timer tick, asking Buildbucket for the build status
[23:21:52.136] Build 8891973264834532624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:21:52.136] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9057403327762067072:43:0) after 1m0s
[23:22:52.258] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9057403327762067072:43:0)
[23:22:52.258] Timer tick, asking Buildbucket for the build status
[23:22:52.383] Build 8891973264834532624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:22:52.383] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9057403327762067072:44:0) after 1m0s
[23:23:52.469] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9057403327762067072:44:0)
[23:23:52.469] Timer tick, asking Buildbucket for the build status
[23:23:52.660] Build 8891973264834532624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:23:52.660] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9057403327762067072:45:0) after 1m0s
[23:24:52.681] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9057403327762067072:45:0)
[23:24:52.681] Timer tick, asking Buildbucket for the build status
[23:24:52.763] Build 8891973264834532624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:24:52.763] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9057403327762067072:46:0) after 1m0s
[23:25:52.959] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9057403327762067072:46:0)
[23:25:52.959] Timer tick, asking Buildbucket for the build status
[23:25:53.074] Build 8891973264834532624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:25:53.074] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9057403327762067072:47:0) after 1m0s
[23:26:53.349] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9057403327762067072:47:0)
[23:26:53.349] Timer tick, asking Buildbucket for the build status
[23:26:53.647] Build 8891973264834532624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:26:53.648] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9057403327762067072:48:0) after 1m0s
[23:27:53.756] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9057403327762067072:48:0)
[23:27:53.756] Timer tick, asking Buildbucket for the build status
[23:27:54.002] Build 8891973264834532624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:27:54.002] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9057403327762067072:49:0) after 1m0s
[23:28:54.171] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9057403327762067072:49:0)
[23:28:54.171] Timer tick, asking Buildbucket for the build status
[23:28:54.343] Build 8891973264834532624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:28:54.343] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9057403327762067072:50:0) after 1m0s
[23:29:54.376] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9057403327762067072:50:0)
[23:29:54.376] Timer tick, asking Buildbucket for the build status
[23:29:54.627] Build 8891973264834532624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:29:54.627] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9057403327762067072:51:0) after 1m0s
[23:30:54.756] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9057403327762067072:51:0)
[23:30:54.756] Timer tick, asking Buildbucket for the build status
[23:30:55.175] Build 8891973264834532624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:30:55.175] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9057403327762067072:52:0) after 1m0s
[23:31:55.199] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9057403327762067072:52:0)
[23:31:55.199] Timer tick, asking Buildbucket for the build status
[23:31:55.571] Build 8891973264834532624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:31:55.571] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9057403327762067072:53:0) after 1m0s
[23:32:55.623] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9057403327762067072:53:0)
[23:32:55.623] Timer tick, asking Buildbucket for the build status
[23:32:55.720] Build 8891973264834532624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:32:55.720] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9057403327762067072:54:0) after 1m0s
[23:33:55.774] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9057403327762067072:54:0)
[23:33:55.774] Timer tick, asking Buildbucket for the build status
[23:33:55.933] Build 8891973264834532624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:33:55.933] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9057403327762067072:55:0) after 1m0s
[23:34:55.942] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9057403327762067072:55:0)
[23:34:55.942] Timer tick, asking Buildbucket for the build status
[23:34:56.050] Build 8891973264834532624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:34:56.050] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9057403327762067072:56:0) after 1m0s
[23:35:56.066] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9057403327762067072:56:0)
[23:35:56.066] Timer tick, asking Buildbucket for the build status
[23:35:56.951] Build 8891973264834532624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:35:56.951] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9057403327762067072:57:0) after 1m0s
[23:36:56.955] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9057403327762067072:57:0)
[23:36:56.955] Timer tick, asking Buildbucket for the build status
[23:36:57.078] Build 8891973264834532624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:36:57.078] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9057403327762067072:58:0) after 1m0s
[23:37:57.158] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9057403327762067072:58:0)
[23:37:57.158] Timer tick, asking Buildbucket for the build status
[23:37:57.268] Build 8891973264834532624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:37:57.268] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9057403327762067072:59:0) after 1m0s
[23:38:57.449] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9057403327762067072:59:0)
[23:38:57.449] Timer tick, asking Buildbucket for the build status
[23:38:57.681] Build 8891973264834532624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:38:57.681] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9057403327762067072:60:0) after 1m0s
[23:39:57.671] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9057403327762067072:60:0)
[23:39:57.671] Timer tick, asking Buildbucket for the build status
[23:39:57.782] Build 8891973264834532624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:39:57.782] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9057403327762067072:61:0) after 1m0s
[23:40:57.795] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9057403327762067072:61:0)
[23:40:57.795] Timer tick, asking Buildbucket for the build status
[23:40:57.847] Build 8891973264834532624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:40:57.847] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9057403327762067072:62:0) after 1m0s
[23:41:57.882] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9057403327762067072:62:0)
[23:41:57.882] Timer tick, asking Buildbucket for the build status
[23:41:58.000] Build 8891973264834532624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:41:58.000] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9057403327762067072:63:0) after 1m0s
[23:42:58.103] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9057403327762067072:63:0)
[23:42:58.103] Timer tick, asking Buildbucket for the build status
[23:42:58.239] Build 8891973264834532624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:42:58.239] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9057403327762067072:64:0) after 1m0s
[23:43:58.320] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9057403327762067072:64:0)
[23:43:58.320] Timer tick, asking Buildbucket for the build status
[23:43:58.400] Build 8891973264834532624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:43:58.400] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9057403327762067072:65:0) after 1m0s
[23:44:58.477] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9057403327762067072:65:0)
[23:44:58.477] Timer tick, asking Buildbucket for the build status
[23:44:58.618] Build 8891973264834532624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:44:58.618] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9057403327762067072:66:0) after 1m0s
[23:45:58.725] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9057403327762067072:66:0)
[23:45:58.725] Timer tick, asking Buildbucket for the build status
[23:45:58.846] Build 8891973264834532624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:45:58.846] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9057403327762067072:67:0) after 1m0s
[23:46:58.946] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9057403327762067072:67:0)
[23:46:58.946] Timer tick, asking Buildbucket for the build status
[23:46:59.011] Build 8891973264834532624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:46:59.011] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9057403327762067072:68:0) after 1m0s
[23:47:59.158] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9057403327762067072:68:0)
[23:47:59.161] Timer tick, asking Buildbucket for the build status
[23:47:59.265] Build 8891973264834532624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:47:59.265] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9057403327762067072:69:0) after 1m0s
[23:48:59.308] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9057403327762067072:69:0)
[23:48:59.308] Timer tick, asking Buildbucket for the build status
[23:48:59.380] Build 8891973264834532624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:48:59.380] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9057403327762067072:70:0) after 1m0s
[23:49:59.395] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9057403327762067072:70:0)
[23:49:59.395] Timer tick, asking Buildbucket for the build status
[23:49:59.686] Build 8891973264834532624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:49:59.686] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9057403327762067072:71:0) after 1m0s
[23:50:54.536] Received PubSub notification, asking Buildbucket for the build status
[23:50:54.636] Build 8891973264834532624: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[23:50:54.636] Buildbucket build:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary_preference": "PROD",
"completed_ts": "1578354653592472",
"created_by": "project:emscripten-releases",
"created_ts": "1578350497364782",
"id": "8891973264834532624",
"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\": \"fc69c547ebab52798e5af1901e808c12c753d7a4\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@fc69c547ebab52798e5af1901e808c12c753d7a4\", \"title\": \"fc69c547ebab52798e5af1901e808c12c753d7a4\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/fc69c547ebab52798e5af1901e808c12c753d7a4\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"fc69c547ebab52798e5af1901e808c12c753d7a4\"}}",
"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\": \"fc69c547ebab52798e5af1901e808c12c753d7a4\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@fc69c547ebab52798e5af1901e808c12c753d7a4\", \"title\": \"fc69c547ebab52798e5af1901e808c12c753d7a4\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/fc69c547ebab52798e5af1901e808c12c753d7a4\"}]}, \"bot_id\": \"luci-emscripten-releases-ci-win10-0-f9h2\", \"branch\": \"refs/heads/master\", \"buildername\": \"win\", \"got_revision\": \"fc69c547ebab52798e5af1901e808c12c753d7a4\", \"path_config\": \"generic\", \"recipe\": \"emscripten_releases\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"fc69c547ebab52798e5af1901e808c12c753d7a4\"}, \"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-f9h2\"], \"image\": [\"chrome-win10-1703-19112100-3085293b610\"], \"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\": [\"4733-6bd8c41\"], \"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": "1578350670778083",
"status": "COMPLETED",
"status_changed_ts": "1578354654171207",
"tags": [
"builder:win",
"buildset:commit/git/fc69c547ebab52798e5af1901e808c12c753d7a4",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/fc69c547ebab52798e5af1901e808c12c753d7a4",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9057403327762067072",
"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/8891973264834532624/+/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:4995d762cd637a10",
"user_agent:luci-scheduler"
],
"updated_ts": "1578354654193330",
"url": "https://ci.chromium.org/b/8891973264834532624",
"utcnow_ts": "1578354654604092"
}
}
[23:50:54.636] Invocation finished in 1h9m19.448037545s with status SUCCEEDED