[05:56:55.351] New invocation is queued and will start shortly
[05:56:56.413] Starting the invocation (attempt 1)
[05:56:56.458] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[05:56:56.458] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[05:56:56.459] Buildbucket request:
{
"bucket": "luci.emscripten-releases.ci",
"client_operation_id": "9054386238914423040",
"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\":\"7232eff5d3a6935dccd4c5a2be72c654f216dadf\"},\"id\":\"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@7232eff5d3a6935dccd4c5a2be72c654f216dadf\",\"title\":\"7232eff5d3a6935dccd4c5a2be72c654f216dadf\",\"url\":\"https://chromium.googlesource.com/emscripten-releases/+/7232eff5d3a6935dccd4c5a2be72c654f216dadf\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/emscripten-releases\",\"revision\":\"7232eff5d3a6935dccd4c5a2be72c654f216dadf\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win",
"scheduler_invocation_id:9054386238914423040",
"scheduler_job_id:emscripten-releases/win",
"user_agent:luci-scheduler",
"buildset:commit/git/7232eff5d3a6935dccd4c5a2be72c654f216dadf",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/7232eff5d3a6935dccd4c5a2be72c654f216dadf",
"gitiles_ref:refs/heads/master"
]
}
[05:56:57.177] Buildbucket response:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary": true,
"canary_preference": "CANARY",
"created_by": "project:emscripten-releases",
"created_ts": "1581227816558928",
"id": "8888956176983620432",
"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\": \"7232eff5d3a6935dccd4c5a2be72c654f216dadf\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@7232eff5d3a6935dccd4c5a2be72c654f216dadf\", \"title\": \"7232eff5d3a6935dccd4c5a2be72c654f216dadf\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/7232eff5d3a6935dccd4c5a2be72c654f216dadf\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"7232eff5d3a6935dccd4c5a2be72c654f216dadf\"}}",
"project": "emscripten-releases",
"result_details_json": "{\"properties\": {}}",
"service_account": "emscripten-releases-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1581227816898623",
"tags": [
"builder:win",
"buildset:commit/git/7232eff5d3a6935dccd4c5a2be72c654f216dadf",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/7232eff5d3a6935dccd4c5a2be72c654f216dadf",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9054386238914423040",
"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/8888956176983620432/+/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": "1581227816898729",
"url": "https://ci.chromium.org/b/8888956176983620432",
"utcnow_ts": "1581227817156432"
}
}
[05:56:57.177] Task URL: https://ci.chromium.org/b/8888956176983620432
[05:56:57.177] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:2:0) after 1m0s
[05:57:53.274] Received PubSub notification, asking Buildbucket for the build status
[05:57:53.332] Build 8888956176983620432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:57:57.222] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:2:0)
[05:57:57.222] Timer tick, asking Buildbucket for the build status
[05:57:57.331] Build 8888956176983620432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:57:57.331] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:4:0) after 1m0s
[05:58:57.351] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:4:0)
[05:58:57.351] Timer tick, asking Buildbucket for the build status
[05:58:57.840] Build 8888956176983620432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:58:57.840] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:5:0) after 1m0s
[05:59:57.942] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:5:0)
[05:59:57.942] Timer tick, asking Buildbucket for the build status
[05:59:58.283] Build 8888956176983620432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:59:58.283] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:6:0) after 1m0s
[06:00:58.317] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:6:0)
[06:00:58.317] Timer tick, asking Buildbucket for the build status
[06:01:00.306] Build 8888956176983620432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:01:00.306] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:7:0) after 1m0s
[06:02:00.323] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:7:0)
[06:02:00.323] Timer tick, asking Buildbucket for the build status
[06:02:00.435] Build 8888956176983620432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:02:00.435] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:8:0) after 1m0s
[06:03:00.460] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:8:0)
[06:03:00.460] Timer tick, asking Buildbucket for the build status
[06:03:02.453] Build 8888956176983620432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:03:02.453] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:9:0) after 1m0s
[06:04:02.549] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:9:0)
[06:04:02.549] Timer tick, asking Buildbucket for the build status
[06:04:02.661] Build 8888956176983620432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:04:02.661] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:10:0) after 1m0s
[06:05:02.765] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:10:0)
[06:05:02.765] Timer tick, asking Buildbucket for the build status
[06:05:03.132] Build 8888956176983620432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:05:03.132] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:11:0) after 1m0s
[06:06:03.201] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:11:0)
[06:06:03.201] Timer tick, asking Buildbucket for the build status
[06:06:03.519] Build 8888956176983620432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:06:03.519] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:12:0) after 1m0s
[06:07:03.541] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:12:0)
[06:07:03.541] Timer tick, asking Buildbucket for the build status
[06:07:03.762] Build 8888956176983620432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:07:03.762] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:13:0) after 1m0s
[06:08:03.806] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:13:0)
[06:08:03.806] Timer tick, asking Buildbucket for the build status
[06:08:03.947] Build 8888956176983620432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:08:03.947] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:14:0) after 1m0s
[06:09:03.949] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:14:0)
[06:09:03.949] Timer tick, asking Buildbucket for the build status
[06:09:04.231] Build 8888956176983620432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:09:04.231] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:15:0) after 1m0s
[06:10:04.933] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:15:0)
[06:10:04.933] Timer tick, asking Buildbucket for the build status
[06:10:05.083] Build 8888956176983620432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:10:05.083] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:16:0) after 1m0s
[06:11:05.891] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:16:0)
[06:11:05.891] Timer tick, asking Buildbucket for the build status
[06:11:06.212] Build 8888956176983620432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:11:06.212] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:17:0) after 1m0s
[06:12:06.501] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:17:0)
[06:12:06.501] Timer tick, asking Buildbucket for the build status
[06:12:06.694] Build 8888956176983620432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:12:06.694] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:18:0) after 1m0s
[06:13:06.782] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:18:0)
[06:13:06.782] Timer tick, asking Buildbucket for the build status
[06:13:06.993] Build 8888956176983620432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:13:06.993] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:19:0) after 1m0s
[06:14:07.057] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:19:0)
[06:14:07.057] Timer tick, asking Buildbucket for the build status
[06:14:07.257] Build 8888956176983620432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:14:07.257] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:20:0) after 1m0s
[06:15:07.302] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:20:0)
[06:15:07.302] Timer tick, asking Buildbucket for the build status
[06:15:07.390] Build 8888956176983620432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:15:07.390] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:21:0) after 1m0s
[06:16:07.640] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:21:0)
[06:16:07.640] Timer tick, asking Buildbucket for the build status
[06:16:07.943] Build 8888956176983620432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:16:07.943] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:22:0) after 1m0s
[06:17:08.038] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:22:0)
[06:17:08.038] Timer tick, asking Buildbucket for the build status
[06:17:08.151] Build 8888956176983620432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:17:08.151] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:23:0) after 1m0s
[06:18:08.171] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:23:0)
[06:18:08.171] Timer tick, asking Buildbucket for the build status
[06:18:08.746] Build 8888956176983620432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:18:08.746] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:24:0) after 1m0s
[06:19:08.867] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:24:0)
[06:19:08.867] Timer tick, asking Buildbucket for the build status
[06:19:09.096] Build 8888956176983620432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:19:09.096] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:25:0) after 1m0s
[06:20:09.121] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:25:0)
[06:20:09.121] Timer tick, asking Buildbucket for the build status
[06:20:09.429] Build 8888956176983620432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:20:09.429] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:26:0) after 1m0s
[06:21:09.453] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:26:0)
[06:21:09.453] Timer tick, asking Buildbucket for the build status
[06:21:09.583] Build 8888956176983620432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:21:09.583] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:27:0) after 1m0s
[06:22:09.601] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:27:0)
[06:22:09.601] Timer tick, asking Buildbucket for the build status
[06:22:09.721] Build 8888956176983620432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:22:09.721] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:28:0) after 1m0s
[06:23:09.936] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:28:0)
[06:23:09.936] Timer tick, asking Buildbucket for the build status
[06:23:10.064] Build 8888956176983620432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:23:10.064] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:29:0) after 1m0s
[06:24:10.045] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:29:0)
[06:24:10.045] Timer tick, asking Buildbucket for the build status
[06:24:10.142] Build 8888956176983620432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:24:10.142] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:30:0) after 1m0s
[06:25:10.426] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:30:0)
[06:25:10.426] Timer tick, asking Buildbucket for the build status
[06:25:10.775] Build 8888956176983620432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:25:10.775] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:31:0) after 1m0s
[06:26:10.808] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:31:0)
[06:26:10.808] Timer tick, asking Buildbucket for the build status
[06:26:11.282] Build 8888956176983620432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:26:11.282] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:32:0) after 1m0s
[06:27:11.416] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:32:0)
[06:27:11.416] Timer tick, asking Buildbucket for the build status
[06:27:11.720] Build 8888956176983620432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:27:11.720] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:33:0) after 1m0s
[06:28:11.743] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:33:0)
[06:28:11.743] Timer tick, asking Buildbucket for the build status
[06:28:12.093] Build 8888956176983620432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:28:12.093] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:34:0) after 1m0s
[06:29:12.123] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:34:0)
[06:29:12.123] Timer tick, asking Buildbucket for the build status
[06:29:12.379] Build 8888956176983620432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:29:12.379] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:35:0) after 1m0s
[06:30:12.499] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:35:0)
[06:30:12.499] Timer tick, asking Buildbucket for the build status
[06:30:12.593] Build 8888956176983620432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:30:12.593] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:36:0) after 1m0s
[06:31:12.734] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:36:0)
[06:31:12.734] Timer tick, asking Buildbucket for the build status
[06:31:12.830] Build 8888956176983620432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:31:12.830] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:37:0) after 1m0s
[06:32:12.954] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:37:0)
[06:32:12.954] Timer tick, asking Buildbucket for the build status
[06:32:13.278] Build 8888956176983620432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:32:13.278] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:38:0) after 1m0s
[06:33:16.926] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:38:0)
[06:33:16.926] Timer tick, asking Buildbucket for the build status
[06:33:17.043] Build 8888956176983620432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:33:17.043] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:39:0) after 1m0s
[06:34:17.065] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:39:0)
[06:34:17.065] Timer tick, asking Buildbucket for the build status
[06:34:17.212] Build 8888956176983620432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:34:17.212] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:40:0) after 1m0s
[06:35:17.231] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:40:0)
[06:35:17.231] Timer tick, asking Buildbucket for the build status
[06:35:17.399] Build 8888956176983620432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:35:17.399] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:41:0) after 1m0s
[06:36:17.484] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:41:0)
[06:36:17.484] Timer tick, asking Buildbucket for the build status
[06:36:17.724] Build 8888956176983620432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:36:17.724] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:42:0) after 1m0s
[06:37:17.733] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:42:0)
[06:37:17.733] Timer tick, asking Buildbucket for the build status
[06:37:17.986] Build 8888956176983620432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:37:17.986] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:43:0) after 1m0s
[06:38:17.968] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:43:0)
[06:38:17.968] Timer tick, asking Buildbucket for the build status
[06:38:18.097] Build 8888956176983620432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:38:18.097] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:44:0) after 1m0s
[06:39:18.125] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:44:0)
[06:39:18.125] Timer tick, asking Buildbucket for the build status
[06:39:18.267] Build 8888956176983620432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:39:18.267] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:45:0) after 1m0s
[06:40:18.304] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:45:0)
[06:40:18.304] Timer tick, asking Buildbucket for the build status
[06:40:18.395] Build 8888956176983620432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:40:18.395] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:46:0) after 1m0s
[06:41:18.530] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:46:0)
[06:41:18.530] Timer tick, asking Buildbucket for the build status
[06:41:18.790] Build 8888956176983620432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:41:18.790] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:47:0) after 1m0s
[06:42:18.834] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:47:0)
[06:42:18.834] Timer tick, asking Buildbucket for the build status
[06:42:18.951] Build 8888956176983620432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:42:18.951] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:48:0) after 1m0s
[06:43:19.019] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:48:0)
[06:43:19.019] Timer tick, asking Buildbucket for the build status
[06:43:19.146] Build 8888956176983620432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:43:19.146] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:49:0) after 1m0s
[06:44:19.218] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:49:0)
[06:44:19.218] Timer tick, asking Buildbucket for the build status
[06:44:19.364] Build 8888956176983620432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:44:19.364] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:50:0) after 1m0s
[06:45:19.429] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:50:0)
[06:45:19.429] Timer tick, asking Buildbucket for the build status
[06:45:19.531] Build 8888956176983620432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:45:19.531] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:51:0) after 1m0s
[06:46:19.644] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:51:0)
[06:46:19.644] Timer tick, asking Buildbucket for the build status
[06:46:19.901] Build 8888956176983620432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:46:19.901] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:52:0) after 1m0s
[06:47:19.976] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:52:0)
[06:47:19.976] Timer tick, asking Buildbucket for the build status
[06:47:20.118] Build 8888956176983620432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:47:20.118] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:53:0) after 1m0s
[06:48:20.142] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:53:0)
[06:48:20.142] Timer tick, asking Buildbucket for the build status
[06:48:20.295] Build 8888956176983620432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:48:20.295] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:54:0) after 1m0s
[06:49:20.355] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:54:0)
[06:49:20.355] Timer tick, asking Buildbucket for the build status
[06:49:20.603] Build 8888956176983620432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:49:20.603] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:55:0) after 1m0s
[06:50:20.649] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:55:0)
[06:50:20.649] Timer tick, asking Buildbucket for the build status
[06:50:20.795] Build 8888956176983620432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:50:20.795] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:56:0) after 1m0s
[06:51:20.818] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:56:0)
[06:51:20.818] Timer tick, asking Buildbucket for the build status
[06:51:21.214] Build 8888956176983620432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:51:21.214] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:57:0) after 1m0s
[06:52:21.239] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:57:0)
[06:52:21.239] Timer tick, asking Buildbucket for the build status
[06:52:21.802] Build 8888956176983620432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:52:21.802] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:58:0) after 1m0s
[06:53:21.807] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:58:0)
[06:53:21.807] Timer tick, asking Buildbucket for the build status
[06:53:22.321] Build 8888956176983620432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:53:22.321] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:59:0) after 1m0s
[06:54:22.419] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:59:0)
[06:54:22.419] Timer tick, asking Buildbucket for the build status
[06:54:22.692] Build 8888956176983620432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:54:22.692] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:60:0) after 1m0s
[06:55:22.785] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:60:0)
[06:55:22.785] Timer tick, asking Buildbucket for the build status
[06:55:22.965] Build 8888956176983620432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:55:22.965] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:61:0) after 1m0s
[06:56:23.048] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:61:0)
[06:56:23.048] Timer tick, asking Buildbucket for the build status
[06:56:23.166] Build 8888956176983620432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:56:23.166] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:62:0) after 1m0s
[06:57:23.242] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:62:0)
[06:57:23.242] Timer tick, asking Buildbucket for the build status
[06:57:23.464] Build 8888956176983620432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:57:23.464] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:63:0) after 1m0s
[06:58:23.461] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:63:0)
[06:58:23.461] Timer tick, asking Buildbucket for the build status
[06:58:23.552] Build 8888956176983620432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:58:23.552] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:64:0) after 1m0s
[06:59:23.547] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:64:0)
[06:59:23.547] Timer tick, asking Buildbucket for the build status
[06:59:23.689] Build 8888956176983620432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:59:23.689] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:65:0) after 1m0s
[07:00:23.762] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:65:0)
[07:00:23.762] Timer tick, asking Buildbucket for the build status
[07:00:24.049] Build 8888956176983620432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:00:24.049] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:66:0) after 1m0s
[07:01:24.069] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:66:0)
[07:01:24.069] Timer tick, asking Buildbucket for the build status
[07:01:24.162] Build 8888956176983620432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:01:24.162] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:67:0) after 1m0s
[07:02:24.192] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:67:0)
[07:02:24.192] Timer tick, asking Buildbucket for the build status
[07:02:24.362] Build 8888956176983620432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:02:24.362] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:68:0) after 1m0s
[07:03:24.418] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:68:0)
[07:03:24.418] Timer tick, asking Buildbucket for the build status
[07:03:24.562] Build 8888956176983620432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:03:24.562] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:69:0) after 1m0s
[07:04:24.633] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:69:0)
[07:04:24.633] Timer tick, asking Buildbucket for the build status
[07:04:24.723] Build 8888956176983620432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:04:24.723] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:70:0) after 1m0s
[07:05:24.770] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:70:0)
[07:05:24.770] Timer tick, asking Buildbucket for the build status
[07:05:24.918] Build 8888956176983620432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:05:24.918] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:71:0) after 1m0s
[07:06:24.917] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:71:0)
[07:06:24.917] Timer tick, asking Buildbucket for the build status
[07:06:25.065] Build 8888956176983620432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:06:25.065] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:72:0) after 1m0s
[07:07:25.141] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:72:0)
[07:07:25.141] Timer tick, asking Buildbucket for the build status
[07:07:25.377] Build 8888956176983620432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:07:25.377] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:73:0) after 1m0s
[07:08:25.462] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:73:0)
[07:08:25.462] Timer tick, asking Buildbucket for the build status
[07:08:25.559] Build 8888956176983620432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:08:25.559] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:74:0) after 1m0s
[07:09:25.609] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:74:0)
[07:09:25.609] Timer tick, asking Buildbucket for the build status
[07:09:25.772] Build 8888956176983620432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:09:25.772] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:75:0) after 1m0s
[07:10:25.804] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:75:0)
[07:10:25.804] Timer tick, asking Buildbucket for the build status
[07:10:25.889] Build 8888956176983620432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:10:25.889] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:76:0) after 1m0s
[07:11:25.918] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:76:0)
[07:11:25.918] Timer tick, asking Buildbucket for the build status
[07:11:26.037] Build 8888956176983620432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:11:26.037] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:77:0) after 1m0s
[07:12:26.114] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:77:0)
[07:12:26.114] Timer tick, asking Buildbucket for the build status
[07:12:26.269] Build 8888956176983620432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:12:26.269] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:78:0) after 1m0s
[07:13:26.328] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:78:0)
[07:13:26.328] Timer tick, asking Buildbucket for the build status
[07:13:26.680] Build 8888956176983620432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:13:26.680] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:79:0) after 1m0s
[07:14:26.715] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:79:0)
[07:14:26.715] Timer tick, asking Buildbucket for the build status
[07:14:26.906] Build 8888956176983620432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:14:26.906] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:80:0) after 1m0s
[07:15:26.934] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:80:0)
[07:15:26.934] Timer tick, asking Buildbucket for the build status
[07:15:27.034] Build 8888956176983620432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:15:27.034] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:81:0) after 1m0s
[07:16:27.080] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:81:0)
[07:16:27.080] Timer tick, asking Buildbucket for the build status
[07:16:27.490] Build 8888956176983620432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:16:27.490] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:82:0) after 1m0s
[07:17:27.561] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:82:0)
[07:17:27.561] Timer tick, asking Buildbucket for the build status
[07:17:27.959] Build 8888956176983620432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:17:27.959] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:83:0) after 1m0s
[07:18:28.037] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:83:0)
[07:18:28.037] Timer tick, asking Buildbucket for the build status
[07:18:28.156] Build 8888956176983620432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:18:28.156] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:84:0) after 1m0s
[07:19:28.190] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:84:0)
[07:19:28.190] Timer tick, asking Buildbucket for the build status
[07:19:28.370] Build 8888956176983620432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:19:28.370] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:85:0) after 1m0s
[07:20:28.392] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:85:0)
[07:20:28.393] Timer tick, asking Buildbucket for the build status
[07:20:28.492] Build 8888956176983620432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:20:28.492] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054386238914423040:86:0) after 1m0s
[07:21:10.448] Received PubSub notification, asking Buildbucket for the build status
[07:21:10.562] Build 8888956176983620432: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[07:21:10.562] Buildbucket build:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary": true,
"canary_preference": "CANARY",
"completed_ts": "1581232869661015",
"created_by": "project:emscripten-releases",
"created_ts": "1581227816558928",
"id": "8888956176983620432",
"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\": \"7232eff5d3a6935dccd4c5a2be72c654f216dadf\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@7232eff5d3a6935dccd4c5a2be72c654f216dadf\", \"title\": \"7232eff5d3a6935dccd4c5a2be72c654f216dadf\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/7232eff5d3a6935dccd4c5a2be72c654f216dadf\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"7232eff5d3a6935dccd4c5a2be72c654f216dadf\"}}",
"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\": \"7232eff5d3a6935dccd4c5a2be72c654f216dadf\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@7232eff5d3a6935dccd4c5a2be72c654f216dadf\", \"title\": \"7232eff5d3a6935dccd4c5a2be72c654f216dadf\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/7232eff5d3a6935dccd4c5a2be72c654f216dadf\"}]}, \"bot_id\": \"luci-emscripten-releases-ci-win10-0-3k4k\", \"branch\": \"refs/heads/master\", \"buildername\": \"win\", \"got_revision\": \"7232eff5d3a6935dccd4c5a2be72c654f216dadf\", \"path_config\": \"generic\", \"recipe\": \"emscripten_releases\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"7232eff5d3a6935dccd4c5a2be72c654f216dadf\"}, \"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-3k4k\"], \"image\": [\"chrome-win10-1703-19112100-3085293b610\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"n1-standard-8\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.emscripten-releases.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"4809-bb2013c\"], \"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": "1581227872054152",
"status": "COMPLETED",
"status_changed_ts": "1581232870105566",
"tags": [
"builder:win",
"buildset:commit/git/7232eff5d3a6935dccd4c5a2be72c654f216dadf",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/7232eff5d3a6935dccd4c5a2be72c654f216dadf",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9054386238914423040",
"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/8888956176983620432/+/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:4a415741545c1410",
"user_agent:luci-scheduler"
],
"updated_ts": "1581232870117630",
"url": "https://ci.chromium.org/b/8888956176983620432",
"utcnow_ts": "1581232870549699"
}
}
[07:21:10.562] Invocation finished in 1h24m15.229371237s with status SUCCEEDED