[13:45:37.760] New invocation is queued and will start shortly
[13:45:39.106] Starting the invocation (attempt 1)
[13:45:39.152] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[13:45:39.152] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[13:45:39.152] Buildbucket request:
{
"bucket": "luci.emscripten-releases.ci",
"client_operation_id": "9043847502331756000",
"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\":\"81fa10921ced83d4fd0376b4a56acbaeb25d31a6\"},\"id\":\"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@81fa10921ced83d4fd0376b4a56acbaeb25d31a6\",\"title\":\"81fa10921ced83d4fd0376b4a56acbaeb25d31a6\",\"url\":\"https://chromium.googlesource.com/emscripten-releases/+/81fa10921ced83d4fd0376b4a56acbaeb25d31a6\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/emscripten-releases\",\"revision\":\"81fa10921ced83d4fd0376b4a56acbaeb25d31a6\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win",
"scheduler_invocation_id:9043847502331756000",
"scheduler_job_id:emscripten-releases/win",
"user_agent:luci-scheduler",
"buildset:commit/git/81fa10921ced83d4fd0376b4a56acbaeb25d31a6",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/81fa10921ced83d4fd0376b4a56acbaeb25d31a6",
"gitiles_ref:refs/heads/master"
]
}
[13:45:39.981] Buildbucket response:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary_preference": "PROD",
"created_by": "project:emscripten-releases",
"created_ts": "1591278339268490",
"id": "8878417440082321200",
"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\": \"81fa10921ced83d4fd0376b4a56acbaeb25d31a6\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@81fa10921ced83d4fd0376b4a56acbaeb25d31a6\", \"title\": \"81fa10921ced83d4fd0376b4a56acbaeb25d31a6\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/81fa10921ced83d4fd0376b4a56acbaeb25d31a6\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"81fa10921ced83d4fd0376b4a56acbaeb25d31a6\"}}",
"project": "emscripten-releases",
"result_details_json": "{\"properties\": {}}",
"service_account": "emscripten-releases-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1591278339690242",
"tags": [
"builder:win",
"buildset:commit/git/81fa10921ced83d4fd0376b4a56acbaeb25d31a6",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/81fa10921ced83d4fd0376b4a56acbaeb25d31a6",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9043847502331756000",
"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/8878417440082321200/+/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": "1591278339690352",
"url": "https://ci.chromium.org/b/8878417440082321200",
"utcnow_ts": "1591278339969843"
}
}
[13:45:39.981] Task URL: https://ci.chromium.org/b/8878417440082321200
[13:45:39.981] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:2:0) after 1m0s
[13:46:30.135] Received PubSub notification, asking Buildbucket for the build status
[13:46:30.258] Build 8878417440082321200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:46:40.022] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:2:0)
[13:46:40.022] Timer tick, asking Buildbucket for the build status
[13:46:40.265] Build 8878417440082321200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:46:40.265] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:4:0) after 1m0s
[13:47:40.300] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:4:0)
[13:47:40.300] Timer tick, asking Buildbucket for the build status
[13:47:40.451] Build 8878417440082321200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:47:40.451] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:5:0) after 1m0s
[13:48:40.524] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:5:0)
[13:48:40.524] Timer tick, asking Buildbucket for the build status
[13:48:40.685] Build 8878417440082321200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:48:40.685] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:6:0) after 1m0s
[13:49:40.813] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:6:0)
[13:49:40.814] Timer tick, asking Buildbucket for the build status
[13:49:40.941] Build 8878417440082321200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:49:40.941] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:7:0) after 1m0s
[13:50:40.962] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:7:0)
[13:50:40.962] Timer tick, asking Buildbucket for the build status
[13:50:41.254] Build 8878417440082321200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:50:41.254] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:8:0) after 1m0s
[13:51:41.278] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:8:0)
[13:51:41.278] Timer tick, asking Buildbucket for the build status
[13:51:41.489] Build 8878417440082321200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:51:41.489] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:9:0) after 1m0s
[13:52:41.519] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:9:0)
[13:52:41.519] Timer tick, asking Buildbucket for the build status
[13:52:41.996] Build 8878417440082321200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:52:41.996] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:10:0) after 1m0s
[13:53:42.002] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:10:0)
[13:53:42.002] Timer tick, asking Buildbucket for the build status
[13:53:42.389] Build 8878417440082321200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:53:42.389] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:11:0) after 1m0s
[13:54:42.447] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:11:0)
[13:54:42.447] Timer tick, asking Buildbucket for the build status
[13:54:42.702] Build 8878417440082321200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:54:42.702] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:12:0) after 1m0s
[13:55:42.734] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:12:0)
[13:55:42.734] Timer tick, asking Buildbucket for the build status
[13:55:42.958] Build 8878417440082321200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:55:42.958] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:13:0) after 1m0s
[13:56:42.969] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:13:0)
[13:56:42.969] Timer tick, asking Buildbucket for the build status
[13:56:43.239] Build 8878417440082321200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:56:43.239] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:14:0) after 1m0s
[13:57:43.344] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:14:0)
[13:57:43.344] Timer tick, asking Buildbucket for the build status
[13:57:43.465] Build 8878417440082321200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:57:43.465] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:15:0) after 1m0s
[13:58:43.448] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:15:0)
[13:58:43.448] Timer tick, asking Buildbucket for the build status
[13:58:43.640] Build 8878417440082321200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:58:43.640] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:16:0) after 1m0s
[13:59:43.678] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:16:0)
[13:59:43.679] Timer tick, asking Buildbucket for the build status
[13:59:43.875] Build 8878417440082321200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:59:43.875] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:17:0) after 1m0s
[14:00:43.906] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:17:0)
[14:00:43.906] Timer tick, asking Buildbucket for the build status
[14:00:44.065] Build 8878417440082321200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:00:44.065] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:18:0) after 1m0s
[14:01:44.123] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:18:0)
[14:01:44.123] Timer tick, asking Buildbucket for the build status
[14:01:44.370] Build 8878417440082321200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:01:44.370] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:19:0) after 1m0s
[14:02:44.473] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:19:0)
[14:02:44.473] Timer tick, asking Buildbucket for the build status
[14:02:44.898] Build 8878417440082321200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:02:44.898] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:20:0) after 1m0s
[14:03:44.930] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:20:0)
[14:03:44.930] Timer tick, asking Buildbucket for the build status
[14:03:45.933] Build 8878417440082321200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:03:45.933] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:21:0) after 1m0s
[14:04:45.978] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:21:0)
[14:04:45.978] Timer tick, asking Buildbucket for the build status
[14:04:46.272] Build 8878417440082321200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:04:46.272] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:22:0) after 1m0s
[14:05:46.368] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:22:0)
[14:05:46.368] Timer tick, asking Buildbucket for the build status
[14:05:46.537] Build 8878417440082321200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:05:46.537] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:23:0) after 1m0s
[14:06:46.580] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:23:0)
[14:06:46.580] Timer tick, asking Buildbucket for the build status
[14:06:47.450] Build 8878417440082321200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:06:47.450] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:24:0) after 1m0s
[14:07:47.566] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:24:0)
[14:07:47.566] Timer tick, asking Buildbucket for the build status
[14:07:47.858] Build 8878417440082321200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:07:47.858] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:25:0) after 1m0s
[14:08:47.896] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:25:0)
[14:08:47.896] Timer tick, asking Buildbucket for the build status
[14:08:48.610] Build 8878417440082321200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:08:48.610] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:26:0) after 1m0s
[14:09:48.699] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:26:0)
[14:09:48.699] Timer tick, asking Buildbucket for the build status
[14:09:49.168] Build 8878417440082321200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:09:49.168] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:27:0) after 1m0s
[14:10:49.251] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:27:0)
[14:10:49.251] Timer tick, asking Buildbucket for the build status
[14:10:49.444] Build 8878417440082321200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:10:49.444] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:28:0) after 1m0s
[14:11:49.539] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:28:0)
[14:11:49.539] Timer tick, asking Buildbucket for the build status
[14:11:49.788] Build 8878417440082321200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:11:49.788] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:29:0) after 1m0s
[14:12:49.792] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:29:0)
[14:12:49.792] Timer tick, asking Buildbucket for the build status
[14:12:50.058] Build 8878417440082321200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:12:50.058] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:30:0) after 1m0s
[14:13:50.097] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:30:0)
[14:13:50.097] Timer tick, asking Buildbucket for the build status
[14:13:50.306] Build 8878417440082321200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:13:50.306] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:31:0) after 1m0s
[14:14:50.397] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:31:0)
[14:14:50.397] Timer tick, asking Buildbucket for the build status
[14:14:50.597] Build 8878417440082321200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:14:50.597] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:32:0) after 1m0s
[14:15:50.650] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:32:0)
[14:15:50.650] Timer tick, asking Buildbucket for the build status
[14:15:50.779] Build 8878417440082321200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:15:50.779] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:33:0) after 1m0s
[14:16:50.894] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:33:0)
[14:16:50.894] Timer tick, asking Buildbucket for the build status
[14:16:51.997] Build 8878417440082321200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:16:51.997] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:34:0) after 1m0s
[14:17:52.078] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:34:0)
[14:17:52.078] Timer tick, asking Buildbucket for the build status
[14:17:52.450] Build 8878417440082321200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:17:52.450] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:35:0) after 1m0s
[14:18:52.521] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:35:0)
[14:18:52.522] Timer tick, asking Buildbucket for the build status
[14:18:52.636] Build 8878417440082321200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:18:52.636] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:36:0) after 1m0s
[14:19:52.664] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:36:0)
[14:19:52.664] Timer tick, asking Buildbucket for the build status
[14:19:52.870] Build 8878417440082321200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:19:52.870] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:37:0) after 1m0s
[14:20:52.899] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:37:0)
[14:20:52.899] Timer tick, asking Buildbucket for the build status
[14:20:53.266] Build 8878417440082321200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:20:53.266] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:38:0) after 1m0s
[14:21:53.411] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:38:0)
[14:21:53.411] Timer tick, asking Buildbucket for the build status
[14:21:53.731] Build 8878417440082321200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:21:53.731] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:39:0) after 1m0s
[14:22:53.933] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:39:0)
[14:22:53.933] Timer tick, asking Buildbucket for the build status
[14:22:54.375] Build 8878417440082321200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:22:54.375] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:40:0) after 1m0s
[14:23:54.508] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:40:0)
[14:23:54.508] Timer tick, asking Buildbucket for the build status
[14:23:54.728] Build 8878417440082321200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:23:54.728] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:41:0) after 1m0s
[14:24:54.721] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:41:0)
[14:24:54.721] Timer tick, asking Buildbucket for the build status
[14:24:54.962] Build 8878417440082321200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:24:54.962] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:42:0) after 1m0s
[14:25:54.968] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:42:0)
[14:25:54.968] Timer tick, asking Buildbucket for the build status
[14:25:55.205] Build 8878417440082321200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:25:55.205] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:43:0) after 1m0s
[14:26:55.263] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:43:0)
[14:26:55.263] Timer tick, asking Buildbucket for the build status
[14:26:55.565] Build 8878417440082321200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:26:55.565] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:44:0) after 1m0s
[14:27:55.774] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:44:0)
[14:27:55.774] Timer tick, asking Buildbucket for the build status
[14:27:56.082] Build 8878417440082321200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:27:56.082] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:45:0) after 1m0s
[14:28:56.381] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:45:0)
[14:28:56.381] Timer tick, asking Buildbucket for the build status
[14:28:56.536] Build 8878417440082321200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:28:56.536] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:46:0) after 1m0s
[14:29:56.838] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:46:0)
[14:29:56.838] Timer tick, asking Buildbucket for the build status
[14:29:57.068] Build 8878417440082321200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:29:57.069] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:47:0) after 1m0s
[14:30:57.193] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:47:0)
[14:30:57.193] Timer tick, asking Buildbucket for the build status
[14:30:57.532] Build 8878417440082321200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:30:57.532] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:48:0) after 1m0s
[14:31:57.616] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:48:0)
[14:31:57.616] Timer tick, asking Buildbucket for the build status
[14:31:57.747] Build 8878417440082321200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:31:57.747] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:49:0) after 1m0s
[14:32:57.771] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:49:0)
[14:32:57.771] Timer tick, asking Buildbucket for the build status
[14:32:58.026] Build 8878417440082321200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:32:58.026] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:50:0) after 1m0s
[14:33:58.220] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:50:0)
[14:33:58.220] Timer tick, asking Buildbucket for the build status
[14:33:58.566] Build 8878417440082321200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:33:58.566] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:51:0) after 1m0s
[14:34:58.703] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:51:0)
[14:34:58.703] Timer tick, asking Buildbucket for the build status
[14:35:00.263] Build 8878417440082321200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:35:00.263] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:52:0) after 1m0s
[14:36:01.497] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:52:0)
[14:36:01.497] Timer tick, asking Buildbucket for the build status
[14:36:01.920] Build 8878417440082321200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:36:01.920] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:53:0) after 1m0s
[14:37:03.063] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:53:0)
[14:37:03.063] Timer tick, asking Buildbucket for the build status
[14:37:03.288] Build 8878417440082321200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:37:03.288] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:54:0) after 1m0s
[14:38:03.339] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:54:0)
[14:38:03.339] Timer tick, asking Buildbucket for the build status
[14:38:03.769] Build 8878417440082321200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:38:03.769] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:55:0) after 1m0s
[14:39:03.819] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:55:0)
[14:39:03.819] Timer tick, asking Buildbucket for the build status
[14:39:04.085] Build 8878417440082321200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:39:04.085] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:56:0) after 1m0s
[14:40:04.143] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:56:0)
[14:40:04.143] Timer tick, asking Buildbucket for the build status
[14:40:04.315] Build 8878417440082321200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:40:04.315] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:57:0) after 1m0s
[14:41:04.673] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:57:0)
[14:41:04.673] Timer tick, asking Buildbucket for the build status
[14:41:04.800] Build 8878417440082321200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:41:04.800] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:58:0) after 1m0s
[14:42:05.002] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:58:0)
[14:42:05.002] Timer tick, asking Buildbucket for the build status
[14:42:06.201] Build 8878417440082321200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:42:06.201] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:59:0) after 1m0s
[14:43:06.369] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:59:0)
[14:43:06.369] Timer tick, asking Buildbucket for the build status
[14:43:06.492] Build 8878417440082321200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:43:06.492] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:60:0) after 1m0s
[14:44:06.535] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:60:0)
[14:44:06.535] Timer tick, asking Buildbucket for the build status
[14:44:06.890] Build 8878417440082321200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:44:06.890] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:61:0) after 1m0s
[14:45:07.017] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:61:0)
[14:45:07.017] Timer tick, asking Buildbucket for the build status
[14:45:07.240] Build 8878417440082321200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:45:07.240] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:62:0) after 1m0s
[14:46:07.306] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:62:0)
[14:46:07.306] Timer tick, asking Buildbucket for the build status
[14:46:07.585] Build 8878417440082321200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:46:07.585] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:63:0) after 1m0s
[14:47:07.651] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:63:0)
[14:47:07.651] Timer tick, asking Buildbucket for the build status
[14:47:07.967] Build 8878417440082321200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:47:07.967] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:64:0) after 1m0s
[14:48:08.026] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:64:0)
[14:48:08.026] Timer tick, asking Buildbucket for the build status
[14:48:08.944] Build 8878417440082321200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:48:08.944] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:65:0) after 1m0s
[14:49:09.095] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:65:0)
[14:49:09.095] Timer tick, asking Buildbucket for the build status
[14:49:09.838] Build 8878417440082321200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:49:09.838] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:66:0) after 1m0s
[14:50:09.978] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:66:0)
[14:50:09.978] Timer tick, asking Buildbucket for the build status
[14:50:10.145] Build 8878417440082321200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:50:10.145] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:67:0) after 1m0s
[14:51:10.280] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:67:0)
[14:51:10.280] Timer tick, asking Buildbucket for the build status
[14:51:10.566] Build 8878417440082321200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:51:10.566] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:68:0) after 1m0s
[14:52:10.738] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:68:0)
[14:52:10.738] Timer tick, asking Buildbucket for the build status
[14:52:11.108] Build 8878417440082321200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:52:11.108] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:69:0) after 1m0s
[14:53:11.198] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:69:0)
[14:53:11.198] Timer tick, asking Buildbucket for the build status
[14:53:11.327] Build 8878417440082321200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:53:11.327] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:70:0) after 1m0s
[14:54:11.445] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:70:0)
[14:54:11.445] Timer tick, asking Buildbucket for the build status
[14:54:11.575] Build 8878417440082321200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:54:11.575] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:71:0) after 1m0s
[14:55:11.614] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:71:0)
[14:55:11.614] Timer tick, asking Buildbucket for the build status
[14:55:11.818] Build 8878417440082321200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:55:11.818] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:72:0) after 1m0s
[14:56:11.894] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:72:0)
[14:56:11.894] Timer tick, asking Buildbucket for the build status
[14:56:12.063] Build 8878417440082321200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:56:12.063] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:73:0) after 1m0s
[14:57:12.169] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:73:0)
[14:57:12.169] Timer tick, asking Buildbucket for the build status
[14:57:13.554] Build 8878417440082321200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:57:13.554] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:74:0) after 1m0s
[14:58:13.655] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:74:0)
[14:58:13.655] Timer tick, asking Buildbucket for the build status
[14:58:14.156] Build 8878417440082321200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:58:14.156] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:75:0) after 1m0s
[14:59:14.284] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:75:0)
[14:59:14.284] Timer tick, asking Buildbucket for the build status
[14:59:15.277] Build 8878417440082321200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:59:15.277] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:76:0) after 1m0s
[15:00:15.358] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:76:0)
[15:00:15.358] Timer tick, asking Buildbucket for the build status
[15:00:15.946] Build 8878417440082321200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:00:15.946] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:77:0) after 1m0s
[15:01:16.019] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:77:0)
[15:01:16.019] Timer tick, asking Buildbucket for the build status
[15:01:16.202] Build 8878417440082321200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:01:16.202] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:78:0) after 1m0s
[15:02:16.326] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:78:0)
[15:02:16.326] Timer tick, asking Buildbucket for the build status
[15:02:16.482] Build 8878417440082321200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:02:16.482] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:79:0) after 1m0s
[15:03:16.553] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:79:0)
[15:03:16.553] Timer tick, asking Buildbucket for the build status
[15:03:16.865] Build 8878417440082321200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:03:16.865] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:80:0) after 1m0s
[15:04:16.900] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:80:0)
[15:04:16.900] Timer tick, asking Buildbucket for the build status
[15:04:17.034] Build 8878417440082321200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:04:17.034] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:81:0) after 1m0s
[15:05:17.070] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:81:0)
[15:05:17.070] Timer tick, asking Buildbucket for the build status
[15:05:17.216] Build 8878417440082321200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:05:17.216] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:82:0) after 1m0s
[15:06:17.281] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:82:0)
[15:06:17.281] Timer tick, asking Buildbucket for the build status
[15:06:17.759] Build 8878417440082321200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:06:17.759] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:83:0) after 1m0s
[15:07:17.827] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:83:0)
[15:07:17.827] Timer tick, asking Buildbucket for the build status
[15:07:17.993] Build 8878417440082321200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:07:17.993] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:84:0) after 1m0s
[15:08:18.098] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:84:0)
[15:08:18.098] Timer tick, asking Buildbucket for the build status
[15:08:18.379] Build 8878417440082321200: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:08:18.379] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9043847502331756000:85:0) after 1m0s
[15:08:46.608] Received PubSub notification, asking Buildbucket for the build status
[15:08:46.777] Build 8878417440082321200: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[15:08:46.777] Buildbucket build:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary_preference": "PROD",
"completed_ts": "1591283323739167",
"created_by": "project:emscripten-releases",
"created_ts": "1591278339268490",
"id": "8878417440082321200",
"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\": \"81fa10921ced83d4fd0376b4a56acbaeb25d31a6\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@81fa10921ced83d4fd0376b4a56acbaeb25d31a6\", \"title\": \"81fa10921ced83d4fd0376b4a56acbaeb25d31a6\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/81fa10921ced83d4fd0376b4a56acbaeb25d31a6\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"81fa10921ced83d4fd0376b4a56acbaeb25d31a6\"}}",
"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\": \"81fa10921ced83d4fd0376b4a56acbaeb25d31a6\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@81fa10921ced83d4fd0376b4a56acbaeb25d31a6\", \"title\": \"81fa10921ced83d4fd0376b4a56acbaeb25d31a6\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/81fa10921ced83d4fd0376b4a56acbaeb25d31a6\"}]}, \"bot_id\": \"luci-emscripten-releases-ci-win10-0-755c\", \"branch\": \"refs/heads/master\", \"buildername\": \"win\", \"got_revision\": \"81fa10921ced83d4fd0376b4a56acbaeb25d31a6\", \"path_config\": \"generic\", \"recipe\": \"emscripten_releases\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"81fa10921ced83d4fd0376b4a56acbaeb25d31a6\"}, \"swarming\": {\"bot_dimensions\": {\"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"host_class\": [\"default\"], \"id\": [\"luci-emscripten-releases-ci-win10-0-755c\"], \"image\": [\"chrome-win10-1703-20052900-58250aa4257\"], \"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\": [\"5158-af0aabf\"], \"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": "1591278389221270",
"status": "COMPLETED",
"status_changed_ts": "1591283325208155",
"tags": [
"builder:win",
"buildset:commit/git/81fa10921ced83d4fd0376b4a56acbaeb25d31a6",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/81fa10921ced83d4fd0376b4a56acbaeb25d31a6",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9043847502331756000",
"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/8878417440082321200/+/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:4c986610a7ade510",
"user_agent:luci-scheduler"
],
"updated_ts": "1591283325685256",
"url": "https://ci.chromium.org/b/8878417440082321200",
"utcnow_ts": "1591283326755293"
}
}
[15:08:46.777] Invocation finished in 1h23m9.0379021s with status SUCCEEDED