[01:37:21.297] New invocation is queued and will start shortly
[01:37:22.847] Starting the invocation (attempt 1)
[01:37:22.886] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[01:37:22.887] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[01:37:22.887] Buildbucket request:
{
"bucket": "luci.emscripten-releases.ci",
"client_operation_id": "9033112282389222240",
"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\":\"e8c32628cdd23f65951d48bd7d3fb167ef17c515\"},\"id\":\"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@e8c32628cdd23f65951d48bd7d3fb167ef17c515\",\"title\":\"e8c32628cdd23f65951d48bd7d3fb167ef17c515\",\"url\":\"https://chromium.googlesource.com/emscripten-releases/+/e8c32628cdd23f65951d48bd7d3fb167ef17c515\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/emscripten-releases\",\"revision\":\"e8c32628cdd23f65951d48bd7d3fb167ef17c515\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win",
"scheduler_invocation_id:9033112282389222240",
"scheduler_job_id:emscripten-releases/win",
"user_agent:luci-scheduler",
"buildset:commit/git/e8c32628cdd23f65951d48bd7d3fb167ef17c515",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/e8c32628cdd23f65951d48bd7d3fb167ef17c515",
"gitiles_ref:refs/heads/master"
]
}
[01:37:23.528] Buildbucket response:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary_preference": "PROD",
"created_by": "project:emscripten-releases",
"created_ts": "1601516242997289",
"id": "8867682219941343904",
"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\": \"e8c32628cdd23f65951d48bd7d3fb167ef17c515\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@e8c32628cdd23f65951d48bd7d3fb167ef17c515\", \"title\": \"e8c32628cdd23f65951d48bd7d3fb167ef17c515\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/e8c32628cdd23f65951d48bd7d3fb167ef17c515\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"e8c32628cdd23f65951d48bd7d3fb167ef17c515\"}}",
"project": "emscripten-releases",
"result_details_json": "{\"properties\": {}}",
"service_account": "emscripten-releases-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1601516243346754",
"tags": [
"builder:win",
"buildset:commit/git/e8c32628cdd23f65951d48bd7d3fb167ef17c515",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/e8c32628cdd23f65951d48bd7d3fb167ef17c515",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9033112282389222240",
"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/8867682219941343904/+/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": "1601516243347179",
"url": "https://ci.chromium.org/b/8867682219941343904",
"utcnow_ts": "1601516243513671"
}
}
[01:37:23.528] Task URL: https://ci.chromium.org/b/8867682219941343904
[01:37:23.528] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:2:0) after 1m0s
[01:38:23.512] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:2:0)
[01:38:23.512] Timer tick, asking Buildbucket for the build status
[01:38:23.637] Build 8867682219941343904: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:38:23.637] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:3:0) after 1m0s
[01:39:23.890] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:3:0)
[01:39:23.890] Timer tick, asking Buildbucket for the build status
[01:39:23.970] Build 8867682219941343904: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:39:23.970] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:4:0) after 1m0s
[01:40:24.121] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:4:0)
[01:40:24.121] Timer tick, asking Buildbucket for the build status
[01:40:24.253] Build 8867682219941343904: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:40:24.253] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:5:0) after 1m0s
[01:41:24.304] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:5:0)
[01:41:24.304] Timer tick, asking Buildbucket for the build status
[01:41:24.418] Build 8867682219941343904: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:41:24.418] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:6:0) after 1m0s
[01:42:24.475] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:6:0)
[01:42:24.475] Timer tick, asking Buildbucket for the build status
[01:42:24.559] Build 8867682219941343904: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:42:24.559] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:7:0) after 1m0s
[01:43:24.606] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:7:0)
[01:43:24.606] Timer tick, asking Buildbucket for the build status
[01:43:24.734] Build 8867682219941343904: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:43:24.734] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:8:0) after 1m0s
[01:44:24.831] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:8:0)
[01:44:24.831] Timer tick, asking Buildbucket for the build status
[01:44:24.996] Build 8867682219941343904: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:44:24.996] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:9:0) after 1m0s
[01:45:25.206] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:9:0)
[01:45:25.206] Timer tick, asking Buildbucket for the build status
[01:45:25.388] Build 8867682219941343904: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:45:25.388] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:10:0) after 1m0s
[01:46:25.430] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:10:0)
[01:46:25.430] Timer tick, asking Buildbucket for the build status
[01:46:27.888] Build 8867682219941343904: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:46:27.888] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:11:0) after 1m0s
[01:47:28.062] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:11:0)
[01:47:28.062] Timer tick, asking Buildbucket for the build status
[01:47:28.221] Build 8867682219941343904: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:47:28.230] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:12:0) after 1m0s
[01:48:00.181] Received PubSub notification, asking Buildbucket for the build status
[01:48:00.375] Build 8867682219941343904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:48:29.646] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:12:0)
[01:48:29.646] Timer tick, asking Buildbucket for the build status
[01:48:30.082] Build 8867682219941343904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:48:30.082] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:14:0) after 1m0s
[01:49:30.111] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:14:0)
[01:49:30.111] Timer tick, asking Buildbucket for the build status
[01:49:30.260] Build 8867682219941343904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:49:30.260] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:15:0) after 1m0s
[01:50:30.318] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:15:0)
[01:50:30.318] Timer tick, asking Buildbucket for the build status
[01:50:30.748] Build 8867682219941343904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:50:30.748] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:16:0) after 1m0s
[01:51:30.784] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:16:0)
[01:51:30.784] Timer tick, asking Buildbucket for the build status
[01:51:30.935] Build 8867682219941343904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:51:30.935] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:17:0) after 1m0s
[01:52:30.906] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:17:0)
[01:52:30.906] Timer tick, asking Buildbucket for the build status
[01:52:31.047] Build 8867682219941343904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:52:31.047] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:18:0) after 1m0s
[01:53:31.092] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:18:0)
[01:53:31.092] Timer tick, asking Buildbucket for the build status
[01:53:31.184] Build 8867682219941343904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:53:31.184] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:19:0) after 1m0s
[01:54:31.252] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:19:0)
[01:54:31.252] Timer tick, asking Buildbucket for the build status
[01:54:31.407] Build 8867682219941343904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:54:31.407] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:20:0) after 1m0s
[01:55:31.415] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:20:0)
[01:55:31.415] Timer tick, asking Buildbucket for the build status
[01:55:31.497] Build 8867682219941343904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:55:31.497] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:21:0) after 1m0s
[01:56:31.720] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:21:0)
[01:56:31.720] Timer tick, asking Buildbucket for the build status
[01:56:31.993] Build 8867682219941343904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:56:31.993] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:22:0) after 1m0s
[01:57:32.057] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:22:0)
[01:57:32.057] Timer tick, asking Buildbucket for the build status
[01:57:32.211] Build 8867682219941343904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:57:32.211] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:23:0) after 1m0s
[01:58:32.267] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:23:0)
[01:58:32.267] Timer tick, asking Buildbucket for the build status
[01:58:32.350] Build 8867682219941343904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:58:32.350] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:24:0) after 1m0s
[01:59:32.467] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:24:0)
[01:59:32.467] Timer tick, asking Buildbucket for the build status
[01:59:32.525] Build 8867682219941343904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:59:32.525] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:25:0) after 1m0s
[02:00:32.640] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:25:0)
[02:00:32.640] Timer tick, asking Buildbucket for the build status
[02:00:32.958] Build 8867682219941343904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:00:32.958] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:26:0) after 1m0s
[02:01:33.010] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:26:0)
[02:01:33.010] Timer tick, asking Buildbucket for the build status
[02:01:33.089] Build 8867682219941343904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:01:33.089] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:27:0) after 1m0s
[02:02:33.143] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:27:0)
[02:02:33.143] Timer tick, asking Buildbucket for the build status
[02:02:33.478] Build 8867682219941343904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:02:33.478] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:28:0) after 1m0s
[02:03:33.563] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:28:0)
[02:03:33.563] Timer tick, asking Buildbucket for the build status
[02:03:33.629] Build 8867682219941343904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:03:33.629] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:29:0) after 1m0s
[02:04:33.649] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:29:0)
[02:04:33.649] Timer tick, asking Buildbucket for the build status
[02:04:33.719] Build 8867682219941343904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:04:33.719] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:30:0) after 1m0s
[02:05:33.747] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:30:0)
[02:05:33.747] Timer tick, asking Buildbucket for the build status
[02:05:33.812] Build 8867682219941343904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:05:33.812] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:31:0) after 1m0s
[02:06:33.805] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:31:0)
[02:06:33.805] Timer tick, asking Buildbucket for the build status
[02:06:34.091] Build 8867682219941343904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:06:34.091] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:32:0) after 1m0s
[02:07:34.147] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:32:0)
[02:07:34.147] Timer tick, asking Buildbucket for the build status
[02:07:34.401] Build 8867682219941343904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:07:34.401] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:33:0) after 1m0s
[02:08:34.414] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:33:0)
[02:08:34.414] Timer tick, asking Buildbucket for the build status
[02:08:34.529] Build 8867682219941343904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:08:34.529] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:34:0) after 1m0s
[02:09:34.499] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:34:0)
[02:09:34.499] Timer tick, asking Buildbucket for the build status
[02:09:34.639] Build 8867682219941343904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:09:34.639] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:35:0) after 1m0s
[02:10:34.686] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:35:0)
[02:10:34.686] Timer tick, asking Buildbucket for the build status
[02:10:34.794] Build 8867682219941343904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:10:34.794] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:36:0) after 1m0s
[02:11:34.845] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:36:0)
[02:11:34.845] Timer tick, asking Buildbucket for the build status
[02:11:34.929] Build 8867682219941343904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:11:34.929] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:37:0) after 1m0s
[02:12:34.941] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:37:0)
[02:12:34.941] Timer tick, asking Buildbucket for the build status
[02:12:35.112] Build 8867682219941343904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:12:35.112] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:38:0) after 1m0s
[02:13:35.172] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:38:0)
[02:13:35.172] Timer tick, asking Buildbucket for the build status
[02:13:35.618] Build 8867682219941343904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:13:35.618] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:39:0) after 1m0s
[02:14:35.853] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:39:0)
[02:14:35.853] Timer tick, asking Buildbucket for the build status
[02:14:36.035] Build 8867682219941343904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:14:36.035] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:40:0) after 1m0s
[02:15:36.126] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:40:0)
[02:15:36.126] Timer tick, asking Buildbucket for the build status
[02:15:37.633] Build 8867682219941343904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:15:37.633] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:41:0) after 1m0s
[02:16:37.673] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:41:0)
[02:16:37.673] Timer tick, asking Buildbucket for the build status
[02:16:37.799] Build 8867682219941343904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:16:37.799] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:42:0) after 1m0s
[02:17:37.818] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:42:0)
[02:17:37.818] Timer tick, asking Buildbucket for the build status
[02:17:38.198] Build 8867682219941343904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:17:38.198] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:43:0) after 1m0s
[02:18:38.255] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:43:0)
[02:18:38.255] Timer tick, asking Buildbucket for the build status
[02:18:38.344] Build 8867682219941343904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:18:38.344] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:44:0) after 1m0s
[02:19:38.392] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:44:0)
[02:19:38.392] Timer tick, asking Buildbucket for the build status
[02:19:38.473] Build 8867682219941343904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:19:38.473] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:45:0) after 1m0s
[02:20:38.456] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:45:0)
[02:20:38.456] Timer tick, asking Buildbucket for the build status
[02:20:38.563] Build 8867682219941343904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:20:38.563] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:46:0) after 1m0s
[02:21:38.589] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:46:0)
[02:21:38.589] Timer tick, asking Buildbucket for the build status
[02:21:38.701] Build 8867682219941343904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:21:38.701] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:47:0) after 1m0s
[02:22:38.793] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:47:0)
[02:22:38.793] Timer tick, asking Buildbucket for the build status
[02:22:39.177] Build 8867682219941343904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:22:39.177] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:48:0) after 1m0s
[02:23:39.196] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:48:0)
[02:23:39.196] Timer tick, asking Buildbucket for the build status
[02:23:39.282] Build 8867682219941343904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:23:39.282] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:49:0) after 1m0s
[02:24:39.485] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:49:0)
[02:24:39.485] Timer tick, asking Buildbucket for the build status
[02:24:39.688] Build 8867682219941343904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:24:39.688] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:50:0) after 1m0s
[02:25:39.730] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:50:0)
[02:25:39.730] Timer tick, asking Buildbucket for the build status
[02:25:39.808] Build 8867682219941343904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:25:39.808] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:51:0) after 1m0s
[02:26:39.842] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:51:0)
[02:26:39.842] Timer tick, asking Buildbucket for the build status
[02:26:39.921] Build 8867682219941343904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:26:39.921] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:52:0) after 1m0s
[02:27:39.991] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:52:0)
[02:27:39.991] Timer tick, asking Buildbucket for the build status
[02:27:40.140] Build 8867682219941343904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:27:40.140] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:53:0) after 1m0s
[02:28:40.176] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:53:0)
[02:28:40.176] Timer tick, asking Buildbucket for the build status
[02:28:40.380] Build 8867682219941343904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:28:40.380] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:54:0) after 1m0s
[02:29:40.393] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:54:0)
[02:29:40.393] Timer tick, asking Buildbucket for the build status
[02:29:40.503] Build 8867682219941343904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:29:40.503] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:55:0) after 1m0s
[02:30:40.561] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:55:0)
[02:30:40.561] Timer tick, asking Buildbucket for the build status
[02:30:42.153] Build 8867682219941343904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:30:42.153] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:56:0) after 1m0s
[02:31:42.175] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:56:0)
[02:31:42.175] Timer tick, asking Buildbucket for the build status
[02:31:42.296] Build 8867682219941343904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:31:42.296] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:57:0) after 1m0s
[02:32:42.345] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:57:0)
[02:32:42.345] Timer tick, asking Buildbucket for the build status
[02:32:42.723] Build 8867682219941343904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:32:42.723] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:58:0) after 1m0s
[02:33:42.725] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:58:0)
[02:33:42.725] Timer tick, asking Buildbucket for the build status
[02:33:42.815] Build 8867682219941343904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:33:42.815] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:59:0) after 1m0s
[02:34:42.836] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:59:0)
[02:34:42.836] Timer tick, asking Buildbucket for the build status
[02:34:42.919] Build 8867682219941343904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:34:42.919] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:60:0) after 1m0s
[02:35:42.974] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:60:0)
[02:35:42.974] Timer tick, asking Buildbucket for the build status
[02:35:43.058] Build 8867682219941343904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:35:43.058] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:61:0) after 1m0s
[02:36:43.087] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:61:0)
[02:36:43.087] Timer tick, asking Buildbucket for the build status
[02:36:43.224] Build 8867682219941343904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:36:43.224] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:62:0) after 1m0s
[02:37:43.240] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:62:0)
[02:37:43.240] Timer tick, asking Buildbucket for the build status
[02:37:43.427] Build 8867682219941343904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:37:43.427] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:63:0) after 1m0s
[02:38:43.518] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:63:0)
[02:38:43.518] Timer tick, asking Buildbucket for the build status
[02:38:43.733] Build 8867682219941343904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:38:43.733] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:64:0) after 1m0s
[02:39:43.715] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:64:0)
[02:39:43.715] Timer tick, asking Buildbucket for the build status
[02:39:43.785] Build 8867682219941343904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:39:43.785] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:65:0) after 1m0s
[02:40:43.810] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:65:0)
[02:40:43.810] Timer tick, asking Buildbucket for the build status
[02:40:43.875] Build 8867682219941343904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:40:43.875] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:66:0) after 1m0s
[02:41:44.346] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:66:0)
[02:41:44.346] Timer tick, asking Buildbucket for the build status
[02:41:44.454] Build 8867682219941343904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:41:44.454] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:67:0) after 1m0s
[02:42:44.939] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:67:0)
[02:42:44.939] Timer tick, asking Buildbucket for the build status
[02:42:45.057] Build 8867682219941343904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:42:45.057] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:68:0) after 1m0s
[02:43:45.158] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:68:0)
[02:43:45.158] Timer tick, asking Buildbucket for the build status
[02:43:45.234] Build 8867682219941343904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:43:45.234] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:69:0) after 1m0s
[02:44:45.363] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:69:0)
[02:44:45.363] Timer tick, asking Buildbucket for the build status
[02:44:45.426] Build 8867682219941343904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:44:45.426] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:70:0) after 1m0s
[02:45:45.530] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:70:0)
[02:45:45.530] Timer tick, asking Buildbucket for the build status
[02:45:45.633] Build 8867682219941343904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:45:45.633] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:71:0) after 1m0s
[02:46:45.700] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:71:0)
[02:46:45.700] Timer tick, asking Buildbucket for the build status
[02:46:45.773] Build 8867682219941343904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:46:45.773] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:72:0) after 1m0s
[02:47:45.877] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:72:0)
[02:47:45.877] Timer tick, asking Buildbucket for the build status
[02:47:46.046] Build 8867682219941343904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:47:46.046] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:73:0) after 1m0s
[02:48:46.173] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:73:0)
[02:48:46.173] Timer tick, asking Buildbucket for the build status
[02:48:46.268] Build 8867682219941343904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:48:46.268] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:74:0) after 1m0s
[02:49:46.329] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:74:0)
[02:49:46.329] Timer tick, asking Buildbucket for the build status
[02:49:46.388] Build 8867682219941343904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:49:46.388] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:75:0) after 1m0s
[02:50:46.510] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:75:0)
[02:50:46.510] Timer tick, asking Buildbucket for the build status
[02:50:46.589] Build 8867682219941343904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:50:46.589] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:76:0) after 1m0s
[02:51:46.679] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:76:0)
[02:51:46.679] Timer tick, asking Buildbucket for the build status
[02:51:46.758] Build 8867682219941343904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:51:46.758] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:77:0) after 1m0s
[02:52:46.889] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:77:0)
[02:52:46.889] Timer tick, asking Buildbucket for the build status
[02:52:46.994] Build 8867682219941343904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:52:46.994] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9033112282389222240:78:0) after 1m0s
[02:53:20.523] Received PubSub notification, asking Buildbucket for the build status
[02:53:20.601] Build 8867682219941343904: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[02:53:20.601] Buildbucket build:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary_preference": "PROD",
"completed_ts": "1601520799519383",
"created_by": "project:emscripten-releases",
"created_ts": "1601516242997289",
"id": "8867682219941343904",
"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\": \"e8c32628cdd23f65951d48bd7d3fb167ef17c515\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@e8c32628cdd23f65951d48bd7d3fb167ef17c515\", \"title\": \"e8c32628cdd23f65951d48bd7d3fb167ef17c515\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/e8c32628cdd23f65951d48bd7d3fb167ef17c515\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"e8c32628cdd23f65951d48bd7d3fb167ef17c515\"}}",
"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\": \"e8c32628cdd23f65951d48bd7d3fb167ef17c515\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@e8c32628cdd23f65951d48bd7d3fb167ef17c515\", \"title\": \"e8c32628cdd23f65951d48bd7d3fb167ef17c515\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/e8c32628cdd23f65951d48bd7d3fb167ef17c515\"}]}, \"bot_id\": \"luci-emscripten-releases-ci-win10-0-3526\", \"branch\": \"refs/heads/master\", \"buildername\": \"win\", \"got_revision\": \"e8c32628cdd23f65951d48bd7d3fb167ef17c515\", \"path_config\": \"generic\", \"recipe\": \"emscripten_releases\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"e8c32628cdd23f65951d48bd7d3fb167ef17c515\"}, \"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-3526\"], \"image\": [\"chrome-win10-1703-20092300-22ba5320847\"], \"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\": [\"5564-e41b668\"], \"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": "1601516879127992",
"status": "COMPLETED",
"status_changed_ts": "1601520799787524",
"tags": [
"builder:win",
"buildset:commit/git/e8c32628cdd23f65951d48bd7d3fb167ef17c515",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/e8c32628cdd23f65951d48bd7d3fb167ef17c515",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9033112282389222240",
"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/8867682219941343904/+/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:4efaa0130b296910",
"user_agent:luci-scheduler"
],
"updated_ts": "1601520799799122",
"url": "https://ci.chromium.org/b/8867682219941343904",
"utcnow_ts": "1601520800592664"
}
}
[02:53:20.601] Invocation finished in 1h15m59.321319032s with status SUCCEEDED