[19:49:51.205] New invocation is queued and will start shortly
[19:49:52.529] Starting the invocation (attempt 1)
[19:49:52.661] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[19:49:52.661] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[19:49:52.661] Buildbucket request:
{
"bucket": "luci.emscripten-releases.ci",
"client_operation_id": "9037301605750346400",
"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\":\"9ba99b361b2f71037b31f2a75e64ebe0f8a34a9e\"},\"id\":\"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@9ba99b361b2f71037b31f2a75e64ebe0f8a34a9e\",\"title\":\"9ba99b361b2f71037b31f2a75e64ebe0f8a34a9e\",\"url\":\"https://chromium.googlesource.com/emscripten-releases/+/9ba99b361b2f71037b31f2a75e64ebe0f8a34a9e\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/emscripten-releases\",\"revision\":\"9ba99b361b2f71037b31f2a75e64ebe0f8a34a9e\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win",
"scheduler_invocation_id:9037301605750346400",
"scheduler_job_id:emscripten-releases/win",
"user_agent:luci-scheduler",
"buildset:commit/git/9ba99b361b2f71037b31f2a75e64ebe0f8a34a9e",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/9ba99b361b2f71037b31f2a75e64ebe0f8a34a9e",
"gitiles_ref:refs/heads/master"
]
}
[19:49:53.210] Buildbucket response:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary_preference": "PROD",
"created_by": "project:emscripten-releases",
"created_ts": "1597520992744300",
"id": "8871871543470798128",
"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\": \"9ba99b361b2f71037b31f2a75e64ebe0f8a34a9e\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@9ba99b361b2f71037b31f2a75e64ebe0f8a34a9e\", \"title\": \"9ba99b361b2f71037b31f2a75e64ebe0f8a34a9e\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/9ba99b361b2f71037b31f2a75e64ebe0f8a34a9e\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"9ba99b361b2f71037b31f2a75e64ebe0f8a34a9e\"}}",
"project": "emscripten-releases",
"result_details_json": "{\"properties\": {}}",
"service_account": "emscripten-releases-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1597520993016931",
"tags": [
"builder:win",
"buildset:commit/git/9ba99b361b2f71037b31f2a75e64ebe0f8a34a9e",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/9ba99b361b2f71037b31f2a75e64ebe0f8a34a9e",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9037301605750346400",
"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/8871871543470798128/+/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": "1597520993017039",
"url": "https://ci.chromium.org/b/8871871543470798128",
"utcnow_ts": "1597520993202891"
}
}
[19:49:53.210] Task URL: https://ci.chromium.org/b/8871871543470798128
[19:49:53.210] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037301605750346400:2:0) after 1m0s
[19:50:02.263] Received PubSub notification, asking Buildbucket for the build status
[19:50:02.533] Build 8871871543470798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:50:53.262] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037301605750346400:2:0)
[19:50:53.262] Timer tick, asking Buildbucket for the build status
[19:50:53.407] Build 8871871543470798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:50:53.407] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037301605750346400:4:0) after 1m0s
[19:51:53.470] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037301605750346400:4:0)
[19:51:53.470] Timer tick, asking Buildbucket for the build status
[19:51:53.608] Build 8871871543470798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:51:53.608] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037301605750346400:5:0) after 1m0s
[19:52:53.603] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037301605750346400:5:0)
[19:52:53.603] Timer tick, asking Buildbucket for the build status
[19:52:53.683] Build 8871871543470798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:52:53.683] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037301605750346400:6:0) after 1m0s
[19:53:53.726] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037301605750346400:6:0)
[19:53:53.726] Timer tick, asking Buildbucket for the build status
[19:53:53.844] Build 8871871543470798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:53:53.844] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037301605750346400:7:0) after 1m0s
[19:54:53.857] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037301605750346400:7:0)
[19:54:53.857] Timer tick, asking Buildbucket for the build status
[19:54:54.007] Build 8871871543470798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:54:54.007] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037301605750346400:8:0) after 1m0s
[19:55:54.057] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037301605750346400:8:0)
[19:55:54.057] Timer tick, asking Buildbucket for the build status
[19:55:54.250] Build 8871871543470798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:55:54.250] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037301605750346400:9:0) after 1m0s
[19:56:54.347] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037301605750346400:9:0)
[19:56:54.347] Timer tick, asking Buildbucket for the build status
[19:56:54.472] Build 8871871543470798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:56:54.472] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037301605750346400:10:0) after 1m0s
[19:57:54.626] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037301605750346400:10:0)
[19:57:54.626] Timer tick, asking Buildbucket for the build status
[19:57:54.910] Build 8871871543470798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:57:54.910] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037301605750346400:11:0) after 1m0s
[19:58:54.953] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037301605750346400:11:0)
[19:58:54.953] Timer tick, asking Buildbucket for the build status
[19:58:55.018] Build 8871871543470798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:58:55.018] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037301605750346400:12:0) after 1m0s
[19:59:55.122] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037301605750346400:12:0)
[19:59:55.122] Timer tick, asking Buildbucket for the build status
[19:59:55.303] Build 8871871543470798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:59:55.303] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037301605750346400:13:0) after 1m0s
[20:00:55.547] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037301605750346400:13:0)
[20:00:55.547] Timer tick, asking Buildbucket for the build status
[20:00:55.632] Build 8871871543470798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:00:55.632] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037301605750346400:14:0) after 1m0s
[20:01:55.803] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037301605750346400:14:0)
[20:01:55.803] Timer tick, asking Buildbucket for the build status
[20:01:55.922] Build 8871871543470798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:01:55.922] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037301605750346400:15:0) after 1m0s
[20:02:56.062] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037301605750346400:15:0)
[20:02:56.062] Timer tick, asking Buildbucket for the build status
[20:02:56.188] Build 8871871543470798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:02:56.188] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037301605750346400:16:0) after 1m0s
[20:03:56.309] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037301605750346400:16:0)
[20:03:56.309] Timer tick, asking Buildbucket for the build status
[20:03:56.408] Build 8871871543470798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:03:56.408] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037301605750346400:17:0) after 1m0s
[20:04:56.407] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037301605750346400:17:0)
[20:04:56.407] Timer tick, asking Buildbucket for the build status
[20:04:56.916] Build 8871871543470798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:04:56.916] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037301605750346400:18:0) after 1m0s
[20:05:56.931] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037301605750346400:18:0)
[20:05:56.931] Timer tick, asking Buildbucket for the build status
[20:05:57.082] Build 8871871543470798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:05:57.082] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037301605750346400:19:0) after 1m0s
[20:06:57.181] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037301605750346400:19:0)
[20:06:57.181] Timer tick, asking Buildbucket for the build status
[20:06:57.327] Build 8871871543470798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:06:57.327] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037301605750346400:20:0) after 1m0s
[20:07:57.347] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037301605750346400:20:0)
[20:07:57.347] Timer tick, asking Buildbucket for the build status
[20:07:57.472] Build 8871871543470798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:07:57.472] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037301605750346400:21:0) after 1m0s
[20:08:57.501] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037301605750346400:21:0)
[20:08:57.501] Timer tick, asking Buildbucket for the build status
[20:08:57.797] Build 8871871543470798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:08:57.797] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037301605750346400:22:0) after 1m0s
[20:09:57.847] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037301605750346400:22:0)
[20:09:57.847] Timer tick, asking Buildbucket for the build status
[20:09:57.994] Build 8871871543470798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:09:57.994] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037301605750346400:23:0) after 1m0s
[20:10:58.069] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037301605750346400:23:0)
[20:10:58.069] Timer tick, asking Buildbucket for the build status
[20:10:59.998] Build 8871871543470798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:10:59.998] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037301605750346400:24:0) after 1m0s
[20:12:00.205] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037301605750346400:24:0)
[20:12:00.205] Timer tick, asking Buildbucket for the build status
[20:12:00.342] Build 8871871543470798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:12:00.342] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037301605750346400:25:0) after 1m0s
[20:13:00.438] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037301605750346400:25:0)
[20:13:00.438] Timer tick, asking Buildbucket for the build status
[20:13:00.593] Build 8871871543470798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:13:00.593] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037301605750346400:26:0) after 1m0s
[20:14:00.751] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037301605750346400:26:0)
[20:14:00.751] Timer tick, asking Buildbucket for the build status
[20:14:00.838] Build 8871871543470798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:14:00.838] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037301605750346400:27:0) after 1m0s
[20:15:02.040] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037301605750346400:27:0)
[20:15:02.040] Timer tick, asking Buildbucket for the build status
[20:15:02.230] Build 8871871543470798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:15:02.230] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037301605750346400:28:0) after 1m0s
[20:16:02.256] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037301605750346400:28:0)
[20:16:02.256] Timer tick, asking Buildbucket for the build status
[20:16:02.331] Build 8871871543470798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:16:02.331] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037301605750346400:29:0) after 1m0s
[20:17:02.590] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037301605750346400:29:0)
[20:17:02.590] Timer tick, asking Buildbucket for the build status
[20:17:02.704] Build 8871871543470798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:17:02.704] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037301605750346400:30:0) after 1m0s
[20:18:02.808] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037301605750346400:30:0)
[20:18:02.808] Timer tick, asking Buildbucket for the build status
[20:18:02.922] Build 8871871543470798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:18:02.922] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037301605750346400:31:0) after 1m0s
[20:19:03.041] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037301605750346400:31:0)
[20:19:03.041] Timer tick, asking Buildbucket for the build status
[20:19:03.123] Build 8871871543470798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:19:03.123] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037301605750346400:32:0) after 1m0s
[20:20:03.241] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037301605750346400:32:0)
[20:20:03.241] Timer tick, asking Buildbucket for the build status
[20:20:03.611] Build 8871871543470798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:20:03.611] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037301605750346400:33:0) after 1m0s
[20:21:03.635] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037301605750346400:33:0)
[20:21:03.635] Timer tick, asking Buildbucket for the build status
[20:21:03.815] Build 8871871543470798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:21:03.815] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037301605750346400:34:0) after 1m0s
[20:22:04.064] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037301605750346400:34:0)
[20:22:04.064] Timer tick, asking Buildbucket for the build status
[20:22:04.379] Build 8871871543470798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:22:04.379] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037301605750346400:35:0) after 1m0s
[20:23:04.622] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037301605750346400:35:0)
[20:23:04.622] Timer tick, asking Buildbucket for the build status
[20:23:04.702] Build 8871871543470798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:23:04.702] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037301605750346400:36:0) after 1m0s
[20:24:04.762] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037301605750346400:36:0)
[20:24:04.762] Timer tick, asking Buildbucket for the build status
[20:24:05.332] Build 8871871543470798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:24:05.332] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037301605750346400:37:0) after 1m0s
[20:25:05.418] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037301605750346400:37:0)
[20:25:05.418] Timer tick, asking Buildbucket for the build status
[20:25:05.723] Build 8871871543470798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:25:05.723] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037301605750346400:38:0) after 1m0s
[20:26:05.813] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037301605750346400:38:0)
[20:26:05.813] Timer tick, asking Buildbucket for the build status
[20:26:05.912] Build 8871871543470798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:26:05.912] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037301605750346400:39:0) after 1m0s
[20:27:05.968] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037301605750346400:39:0)
[20:27:05.968] Timer tick, asking Buildbucket for the build status
[20:27:06.139] Build 8871871543470798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:27:06.139] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037301605750346400:40:0) after 1m0s
[20:28:06.265] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037301605750346400:40:0)
[20:28:06.265] Timer tick, asking Buildbucket for the build status
[20:28:06.346] Build 8871871543470798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:28:06.346] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037301605750346400:41:0) after 1m0s
[20:29:06.445] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037301605750346400:41:0)
[20:29:06.445] Timer tick, asking Buildbucket for the build status
[20:29:06.553] Build 8871871543470798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:29:06.553] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037301605750346400:42:0) after 1m0s
[20:30:06.688] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037301605750346400:42:0)
[20:30:06.688] Timer tick, asking Buildbucket for the build status
[20:30:07.219] Build 8871871543470798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:30:07.219] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037301605750346400:43:0) after 1m0s
[20:31:07.687] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037301605750346400:43:0)
[20:31:07.687] Timer tick, asking Buildbucket for the build status
[20:31:07.843] Build 8871871543470798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:31:07.843] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037301605750346400:44:0) after 1m0s
[20:32:08.001] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037301605750346400:44:0)
[20:32:08.001] Timer tick, asking Buildbucket for the build status
[20:32:08.142] Build 8871871543470798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:32:08.142] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037301605750346400:45:0) after 1m0s
[20:33:08.228] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037301605750346400:45:0)
[20:33:08.228] Timer tick, asking Buildbucket for the build status
[20:33:09.030] Build 8871871543470798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:33:09.030] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037301605750346400:46:0) after 1m0s
[20:34:09.375] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037301605750346400:46:0)
[20:34:09.375] Timer tick, asking Buildbucket for the build status
[20:34:09.459] Build 8871871543470798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:34:09.459] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037301605750346400:47:0) after 1m0s
[20:35:09.519] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037301605750346400:47:0)
[20:35:09.519] Timer tick, asking Buildbucket for the build status
[20:35:10.793] Build 8871871543470798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:35:10.793] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037301605750346400:48:0) after 1m0s
[20:36:10.921] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037301605750346400:48:0)
[20:36:10.921] Timer tick, asking Buildbucket for the build status
[20:36:11.033] Build 8871871543470798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:36:11.033] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037301605750346400:49:0) after 1m0s
[20:37:11.083] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037301605750346400:49:0)
[20:37:11.083] Timer tick, asking Buildbucket for the build status
[20:37:11.162] Build 8871871543470798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:37:11.162] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037301605750346400:50:0) after 1m0s
[20:38:11.204] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037301605750346400:50:0)
[20:38:11.204] Timer tick, asking Buildbucket for the build status
[20:38:11.628] Build 8871871543470798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:38:11.628] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037301605750346400:51:0) after 1m0s
[20:39:11.645] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037301605750346400:51:0)
[20:39:11.645] Timer tick, asking Buildbucket for the build status
[20:39:11.838] Build 8871871543470798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:39:11.838] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037301605750346400:52:0) after 1m0s
[20:40:11.957] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037301605750346400:52:0)
[20:40:11.957] Timer tick, asking Buildbucket for the build status
[20:40:12.089] Build 8871871543470798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:40:12.089] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037301605750346400:53:0) after 1m0s
[20:41:12.274] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037301605750346400:53:0)
[20:41:12.274] Timer tick, asking Buildbucket for the build status
[20:41:12.613] Build 8871871543470798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:41:12.613] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037301605750346400:54:0) after 1m0s
[20:42:12.860] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037301605750346400:54:0)
[20:42:12.860] Timer tick, asking Buildbucket for the build status
[20:42:12.936] Build 8871871543470798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:42:12.936] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037301605750346400:55:0) after 1m0s
[20:43:12.938] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037301605750346400:55:0)
[20:43:12.938] Timer tick, asking Buildbucket for the build status
[20:43:12.999] Build 8871871543470798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:43:12.999] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037301605750346400:56:0) after 1m0s
[20:44:13.188] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037301605750346400:56:0)
[20:44:13.188] Timer tick, asking Buildbucket for the build status
[20:44:15.259] Build 8871871543470798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:44:15.259] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037301605750346400:57:0) after 1m0s
[20:45:15.355] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037301605750346400:57:0)
[20:45:15.355] Timer tick, asking Buildbucket for the build status
[20:45:15.463] Build 8871871543470798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:45:15.463] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037301605750346400:58:0) after 1m0s
[20:46:15.597] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037301605750346400:58:0)
[20:46:15.597] Timer tick, asking Buildbucket for the build status
[20:46:15.688] Build 8871871543470798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:46:15.688] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037301605750346400:59:0) after 1m0s
[20:47:15.848] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037301605750346400:59:0)
[20:47:15.848] Timer tick, asking Buildbucket for the build status
[20:47:15.951] Build 8871871543470798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:47:15.951] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037301605750346400:60:0) after 1m0s
[20:48:16.110] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037301605750346400:60:0)
[20:48:16.110] Timer tick, asking Buildbucket for the build status
[20:48:16.194] Build 8871871543470798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:48:16.194] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037301605750346400:61:0) after 1m0s
[20:49:16.303] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037301605750346400:61:0)
[20:49:16.303] Timer tick, asking Buildbucket for the build status
[20:49:16.402] Build 8871871543470798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:49:16.402] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037301605750346400:62:0) after 1m0s
[20:50:16.512] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037301605750346400:62:0)
[20:50:16.512] Timer tick, asking Buildbucket for the build status
[20:50:17.011] Build 8871871543470798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:50:17.011] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037301605750346400:63:0) after 1m0s
[20:51:17.091] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037301605750346400:63:0)
[20:51:17.091] Timer tick, asking Buildbucket for the build status
[20:51:17.187] Build 8871871543470798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:51:17.187] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037301605750346400:64:0) after 1m0s
[20:52:17.214] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037301605750346400:64:0)
[20:52:17.214] Timer tick, asking Buildbucket for the build status
[20:52:17.285] Build 8871871543470798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:52:17.285] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037301605750346400:65:0) after 1m0s
[20:53:17.307] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037301605750346400:65:0)
[20:53:17.307] Timer tick, asking Buildbucket for the build status
[20:53:17.503] Build 8871871543470798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:53:17.503] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037301605750346400:66:0) after 1m0s
[20:54:17.578] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9037301605750346400:66:0)
[20:54:17.578] Timer tick, asking Buildbucket for the build status
[20:54:17.693] Build 8871871543470798128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:54:17.693] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9037301605750346400:67:0) after 1m0s
[20:54:30.702] Received PubSub notification, asking Buildbucket for the build status
[20:54:30.790] Build 8871871543470798128: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[20:54:30.790] Buildbucket build:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary_preference": "PROD",
"completed_ts": "1597524867677086",
"created_by": "project:emscripten-releases",
"created_ts": "1597520992744300",
"id": "8871871543470798128",
"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\": \"9ba99b361b2f71037b31f2a75e64ebe0f8a34a9e\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@9ba99b361b2f71037b31f2a75e64ebe0f8a34a9e\", \"title\": \"9ba99b361b2f71037b31f2a75e64ebe0f8a34a9e\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/9ba99b361b2f71037b31f2a75e64ebe0f8a34a9e\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"9ba99b361b2f71037b31f2a75e64ebe0f8a34a9e\"}}",
"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\": \"9ba99b361b2f71037b31f2a75e64ebe0f8a34a9e\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@9ba99b361b2f71037b31f2a75e64ebe0f8a34a9e\", \"title\": \"9ba99b361b2f71037b31f2a75e64ebe0f8a34a9e\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/9ba99b361b2f71037b31f2a75e64ebe0f8a34a9e\"}]}, \"bot_id\": \"luci-emscripten-releases-ci-win10-0-fr0h\", \"branch\": \"refs/heads/master\", \"buildername\": \"win\", \"got_revision\": \"9ba99b361b2f71037b31f2a75e64ebe0f8a34a9e\", \"path_config\": \"generic\", \"recipe\": \"emscripten_releases\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"9ba99b361b2f71037b31f2a75e64ebe0f8a34a9e\"}, \"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-fr0h\"], \"image\": [\"chrome-win10-1703-20081400-1358d8153ce\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"n1-standard-8\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.emscripten-releases.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"5488-fc78d20\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-b\"]}}}",
"service_account": "emscripten-releases-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1597521000347175",
"status": "COMPLETED",
"status_changed_ts": "1597524868119886",
"tags": [
"builder:win",
"buildset:commit/git/9ba99b361b2f71037b31f2a75e64ebe0f8a34a9e",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/9ba99b361b2f71037b31f2a75e64ebe0f8a34a9e",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9037301605750346400",
"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/8871871543470798128/+/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:4e0c7d647f815810",
"user_agent:luci-scheduler"
],
"updated_ts": "1597524868149509",
"url": "https://ci.chromium.org/b/8871871543470798128",
"utcnow_ts": "1597524870781642"
}
}
[20:54:30.790] Invocation finished in 1h4m39.603653736s with status SUCCEEDED