[17:27:01.849] New invocation is queued and will start shortly
[17:27:05.175] Starting the invocation (attempt 1)
[17:27:05.216] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[17:27:05.216] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[17:27:05.216] Buildbucket request:
{
"bucket": "luci.emscripten-releases.ci",
"client_operation_id": "9035045667212041648",
"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\":\"a24187853cbd4a6f0282b4c2f97126f1249399c3\"},\"id\":\"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@a24187853cbd4a6f0282b4c2f97126f1249399c3\",\"title\":\"a24187853cbd4a6f0282b4c2f97126f1249399c3\",\"url\":\"https://chromium.googlesource.com/emscripten-releases/+/a24187853cbd4a6f0282b4c2f97126f1249399c3\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/emscripten-releases\",\"revision\":\"a24187853cbd4a6f0282b4c2f97126f1249399c3\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win",
"scheduler_invocation_id:9035045667212041648",
"scheduler_job_id:emscripten-releases/win",
"user_agent:luci-scheduler",
"buildset:commit/git/a24187853cbd4a6f0282b4c2f97126f1249399c3",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/a24187853cbd4a6f0282b4c2f97126f1249399c3",
"gitiles_ref:refs/heads/master"
]
}
[17:27:06.407] Buildbucket response:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary_preference": "PROD",
"created_by": "project:emscripten-releases",
"created_ts": "1599672425355877",
"id": "8869615602869478832",
"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\": \"a24187853cbd4a6f0282b4c2f97126f1249399c3\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@a24187853cbd4a6f0282b4c2f97126f1249399c3\", \"title\": \"a24187853cbd4a6f0282b4c2f97126f1249399c3\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/a24187853cbd4a6f0282b4c2f97126f1249399c3\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"a24187853cbd4a6f0282b4c2f97126f1249399c3\"}}",
"project": "emscripten-releases",
"result_details_json": "{\"properties\": {}}",
"service_account": "emscripten-releases-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1599672425909425",
"tags": [
"builder:win",
"buildset:commit/git/a24187853cbd4a6f0282b4c2f97126f1249399c3",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/a24187853cbd4a6f0282b4c2f97126f1249399c3",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9035045667212041648",
"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/8869615602869478832/+/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": "1599672425909614",
"url": "https://ci.chromium.org/b/8869615602869478832",
"utcnow_ts": "1599672426385322"
}
}
[17:27:06.407] Task URL: https://ci.chromium.org/b/8869615602869478832
[17:27:06.407] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9035045667212041648:2:0) after 1m0s
[17:27:33.100] Received PubSub notification, asking Buildbucket for the build status
[17:27:33.224] Build 8869615602869478832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:28:06.502] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9035045667212041648:2:0)
[17:28:06.502] Timer tick, asking Buildbucket for the build status
[17:28:06.731] Build 8869615602869478832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:28:06.731] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9035045667212041648:4:0) after 1m0s
[17:29:06.751] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9035045667212041648:4:0)
[17:29:06.751] Timer tick, asking Buildbucket for the build status
[17:29:07.515] Build 8869615602869478832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:29:07.515] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9035045667212041648:5:0) after 1m0s
[17:30:07.596] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9035045667212041648:5:0)
[17:30:07.596] Timer tick, asking Buildbucket for the build status
[17:30:07.792] Build 8869615602869478832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:30:07.792] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9035045667212041648:6:0) after 1m0s
[17:31:07.973] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9035045667212041648:6:0)
[17:31:07.973] Timer tick, asking Buildbucket for the build status
[17:31:08.076] Build 8869615602869478832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:31:08.076] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9035045667212041648:7:0) after 1m0s
[17:32:08.118] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9035045667212041648:7:0)
[17:32:08.118] Timer tick, asking Buildbucket for the build status
[17:32:08.433] Build 8869615602869478832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:32:08.433] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9035045667212041648:8:0) after 1m0s
[17:33:08.606] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9035045667212041648:8:0)
[17:33:08.606] Timer tick, asking Buildbucket for the build status
[17:33:08.778] Build 8869615602869478832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:33:08.778] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9035045667212041648:9:0) after 1m0s
[17:34:08.829] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9035045667212041648:9:0)
[17:34:08.829] Timer tick, asking Buildbucket for the build status
[17:34:09.395] Build 8869615602869478832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:34:09.395] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9035045667212041648:10:0) after 1m0s
[17:35:09.606] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9035045667212041648:10:0)
[17:35:09.606] Timer tick, asking Buildbucket for the build status
[17:35:09.726] Build 8869615602869478832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:35:09.726] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9035045667212041648:11:0) after 1m0s
[17:36:09.804] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9035045667212041648:11:0)
[17:36:09.804] Timer tick, asking Buildbucket for the build status
[17:36:09.898] Build 8869615602869478832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:36:09.898] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9035045667212041648:12:0) after 1m0s
[17:37:09.914] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9035045667212041648:12:0)
[17:37:09.914] Timer tick, asking Buildbucket for the build status
[17:37:10.087] Build 8869615602869478832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:37:10.087] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9035045667212041648:13:0) after 1m0s
[17:38:10.140] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9035045667212041648:13:0)
[17:38:10.140] Timer tick, asking Buildbucket for the build status
[17:38:15.574] Build 8869615602869478832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:38:15.574] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9035045667212041648:14:0) after 1m0s
[17:39:15.852] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9035045667212041648:14:0)
[17:39:15.852] Timer tick, asking Buildbucket for the build status
[17:39:16.595] Build 8869615602869478832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:39:16.595] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9035045667212041648:15:0) after 1m0s
[17:40:16.727] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9035045667212041648:15:0)
[17:40:16.727] Timer tick, asking Buildbucket for the build status
[17:40:17.011] Build 8869615602869478832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:40:17.011] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9035045667212041648:16:0) after 1m0s
[17:41:17.109] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9035045667212041648:16:0)
[17:41:17.109] Timer tick, asking Buildbucket for the build status
[17:41:17.247] Build 8869615602869478832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:41:17.247] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9035045667212041648:17:0) after 1m0s
[17:42:17.324] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9035045667212041648:17:0)
[17:42:17.324] Timer tick, asking Buildbucket for the build status
[17:42:17.463] Build 8869615602869478832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:42:17.463] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9035045667212041648:18:0) after 1m0s
[17:43:18.803] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9035045667212041648:18:0)
[17:43:18.803] Timer tick, asking Buildbucket for the build status
[17:43:18.914] Build 8869615602869478832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:43:18.914] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9035045667212041648:19:0) after 1m0s
[17:44:19.039] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9035045667212041648:19:0)
[17:44:19.039] Timer tick, asking Buildbucket for the build status
[17:44:19.219] Build 8869615602869478832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:44:19.219] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9035045667212041648:20:0) after 1m0s
[17:45:19.260] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9035045667212041648:20:0)
[17:45:19.260] Timer tick, asking Buildbucket for the build status
[17:45:19.695] Build 8869615602869478832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:45:19.695] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9035045667212041648:21:0) after 1m0s
[17:46:19.764] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9035045667212041648:21:0)
[17:46:19.764] Timer tick, asking Buildbucket for the build status
[17:46:19.823] Build 8869615602869478832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:46:19.823] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9035045667212041648:22:0) after 1m0s
[17:47:19.865] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9035045667212041648:22:0)
[17:47:19.865] Timer tick, asking Buildbucket for the build status
[17:47:20.182] Build 8869615602869478832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:47:20.182] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9035045667212041648:23:0) after 1m0s
[17:48:20.175] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9035045667212041648:23:0)
[17:48:20.175] Timer tick, asking Buildbucket for the build status
[17:48:20.403] Build 8869615602869478832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:48:20.403] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9035045667212041648:24:0) after 1m0s
[17:49:20.531] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9035045667212041648:24:0)
[17:49:20.532] Timer tick, asking Buildbucket for the build status
[17:49:20.999] Build 8869615602869478832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:49:21.001] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9035045667212041648:25:0) after 1m0s
[17:50:21.070] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9035045667212041648:25:0)
[17:50:21.070] Timer tick, asking Buildbucket for the build status
[17:50:21.394] Build 8869615602869478832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:50:21.394] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9035045667212041648:26:0) after 1m0s
[17:51:21.470] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9035045667212041648:26:0)
[17:51:21.470] Timer tick, asking Buildbucket for the build status
[17:51:21.537] Build 8869615602869478832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:51:21.537] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9035045667212041648:27:0) after 1m0s
[17:52:21.559] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9035045667212041648:27:0)
[17:52:21.559] Timer tick, asking Buildbucket for the build status
[17:52:22.053] Build 8869615602869478832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:52:22.053] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9035045667212041648:28:0) after 1m0s
[17:53:22.096] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9035045667212041648:28:0)
[17:53:22.096] Timer tick, asking Buildbucket for the build status
[17:53:22.285] Build 8869615602869478832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:53:22.285] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9035045667212041648:29:0) after 1m0s
[17:54:22.354] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9035045667212041648:29:0)
[17:54:22.354] Timer tick, asking Buildbucket for the build status
[17:54:22.472] Build 8869615602869478832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:54:22.472] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9035045667212041648:30:0) after 1m0s
[17:55:22.538] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9035045667212041648:30:0)
[17:55:22.538] Timer tick, asking Buildbucket for the build status
[17:55:22.606] Build 8869615602869478832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:55:22.606] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9035045667212041648:31:0) after 1m0s
[17:56:22.707] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9035045667212041648:31:0)
[17:56:22.707] Timer tick, asking Buildbucket for the build status
[17:56:22.923] Build 8869615602869478832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:56:22.923] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9035045667212041648:32:0) after 1m0s
[17:57:22.922] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9035045667212041648:32:0)
[17:57:22.922] Timer tick, asking Buildbucket for the build status
[17:57:23.534] Build 8869615602869478832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:57:23.534] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9035045667212041648:33:0) after 1m0s
[17:58:23.555] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9035045667212041648:33:0)
[17:58:23.555] Timer tick, asking Buildbucket for the build status
[17:58:23.614] Build 8869615602869478832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:58:23.614] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9035045667212041648:34:0) after 1m0s
[17:59:23.675] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9035045667212041648:34:0)
[17:59:23.675] Timer tick, asking Buildbucket for the build status
[17:59:23.811] Build 8869615602869478832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:59:23.811] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9035045667212041648:35:0) after 1m0s
[18:00:23.906] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9035045667212041648:35:0)
[18:00:23.906] Timer tick, asking Buildbucket for the build status
[18:00:24.158] Build 8869615602869478832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:00:24.158] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9035045667212041648:36:0) after 1m0s
[18:01:24.201] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9035045667212041648:36:0)
[18:01:24.201] Timer tick, asking Buildbucket for the build status
[18:01:24.478] Build 8869615602869478832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:01:24.478] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9035045667212041648:37:0) after 1m0s
[18:02:24.544] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9035045667212041648:37:0)
[18:02:24.544] Timer tick, asking Buildbucket for the build status
[18:02:24.677] Build 8869615602869478832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:02:24.677] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9035045667212041648:38:0) after 1m0s
[18:03:24.775] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9035045667212041648:38:0)
[18:03:24.775] Timer tick, asking Buildbucket for the build status
[18:03:24.850] Build 8869615602869478832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:03:24.850] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9035045667212041648:39:0) after 1m0s
[18:04:24.908] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9035045667212041648:39:0)
[18:04:24.908] Timer tick, asking Buildbucket for the build status
[18:04:25.007] Build 8869615602869478832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:04:25.007] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9035045667212041648:40:0) after 1m0s
[18:05:25.110] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9035045667212041648:40:0)
[18:05:25.110] Timer tick, asking Buildbucket for the build status
[18:05:25.392] Build 8869615602869478832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:05:25.393] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9035045667212041648:41:0) after 1m0s
[18:06:25.507] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9035045667212041648:41:0)
[18:06:25.507] Timer tick, asking Buildbucket for the build status
[18:06:25.904] Build 8869615602869478832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:06:25.904] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9035045667212041648:42:0) after 1m0s
[18:07:25.982] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9035045667212041648:42:0)
[18:07:25.982] Timer tick, asking Buildbucket for the build status
[18:07:26.138] Build 8869615602869478832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:07:26.138] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9035045667212041648:43:0) after 1m0s
[18:08:26.293] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9035045667212041648:43:0)
[18:08:26.293] Timer tick, asking Buildbucket for the build status
[18:08:26.806] Build 8869615602869478832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:08:26.806] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9035045667212041648:44:0) after 1m0s
[18:09:26.873] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9035045667212041648:44:0)
[18:09:26.873] Timer tick, asking Buildbucket for the build status
[18:09:27.378] Build 8869615602869478832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:09:27.378] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9035045667212041648:45:0) after 1m0s
[18:10:27.447] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9035045667212041648:45:0)
[18:10:27.447] Timer tick, asking Buildbucket for the build status
[18:10:27.548] Build 8869615602869478832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:10:27.548] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9035045667212041648:46:0) after 1m0s
[18:11:27.553] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9035045667212041648:46:0)
[18:11:27.553] Timer tick, asking Buildbucket for the build status
[18:11:28.024] Build 8869615602869478832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:11:28.025] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9035045667212041648:47:0) after 1m0s
[18:12:28.137] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9035045667212041648:47:0)
[18:12:28.137] Timer tick, asking Buildbucket for the build status
[18:12:28.275] Build 8869615602869478832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:12:28.275] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9035045667212041648:48:0) after 1m0s
[18:13:28.359] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9035045667212041648:48:0)
[18:13:28.359] Timer tick, asking Buildbucket for the build status
[18:13:28.457] Build 8869615602869478832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:13:28.457] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9035045667212041648:49:0) after 1m0s
[18:14:28.573] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9035045667212041648:49:0)
[18:14:28.573] Timer tick, asking Buildbucket for the build status
[18:14:28.683] Build 8869615602869478832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:14:28.683] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9035045667212041648:50:0) after 1m0s
[18:15:28.710] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9035045667212041648:50:0)
[18:15:28.710] Timer tick, asking Buildbucket for the build status
[18:15:28.805] Build 8869615602869478832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:15:28.805] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9035045667212041648:51:0) after 1m0s
[18:16:28.832] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9035045667212041648:51:0)
[18:16:28.832] Timer tick, asking Buildbucket for the build status
[18:16:29.093] Build 8869615602869478832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:16:29.093] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9035045667212041648:52:0) after 1m0s
[18:17:29.099] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9035045667212041648:52:0)
[18:17:29.099] Timer tick, asking Buildbucket for the build status
[18:17:29.461] Build 8869615602869478832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:17:29.461] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9035045667212041648:53:0) after 1m0s
[18:18:29.590] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9035045667212041648:53:0)
[18:18:29.590] Timer tick, asking Buildbucket for the build status
[18:18:29.666] Build 8869615602869478832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:18:29.666] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9035045667212041648:54:0) after 1m0s
[18:19:29.716] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9035045667212041648:54:0)
[18:19:29.716] Timer tick, asking Buildbucket for the build status
[18:19:30.049] Build 8869615602869478832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:19:30.049] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9035045667212041648:55:0) after 1m0s
[18:20:30.069] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9035045667212041648:55:0)
[18:20:30.070] Timer tick, asking Buildbucket for the build status
[18:20:30.482] Build 8869615602869478832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:20:30.482] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9035045667212041648:56:0) after 1m0s
[18:21:30.527] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9035045667212041648:56:0)
[18:21:30.527] Timer tick, asking Buildbucket for the build status
[18:21:30.630] Build 8869615602869478832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:21:30.630] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9035045667212041648:57:0) after 1m0s
[18:22:30.691] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9035045667212041648:57:0)
[18:22:30.691] Timer tick, asking Buildbucket for the build status
[18:22:31.317] Build 8869615602869478832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:22:31.317] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9035045667212041648:58:0) after 1m0s
[18:23:31.444] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9035045667212041648:58:0)
[18:23:31.444] Timer tick, asking Buildbucket for the build status
[18:23:31.545] Build 8869615602869478832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:23:31.545] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9035045667212041648:59:0) after 1m0s
[18:24:31.612] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9035045667212041648:59:0)
[18:24:31.612] Timer tick, asking Buildbucket for the build status
[18:24:31.905] Build 8869615602869478832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:24:31.905] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9035045667212041648:60:0) after 1m0s
[18:25:32.098] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9035045667212041648:60:0)
[18:25:32.098] Timer tick, asking Buildbucket for the build status
[18:25:32.377] Build 8869615602869478832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:25:32.377] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9035045667212041648:61:0) after 1m0s
[18:26:32.397] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9035045667212041648:61:0)
[18:26:32.397] Timer tick, asking Buildbucket for the build status
[18:26:32.475] Build 8869615602869478832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:26:32.475] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9035045667212041648:62:0) after 1m0s
[18:27:32.546] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9035045667212041648:62:0)
[18:27:32.546] Timer tick, asking Buildbucket for the build status
[18:27:32.651] Build 8869615602869478832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:27:32.651] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9035045667212041648:63:0) after 1m0s
[18:28:32.730] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9035045667212041648:63:0)
[18:28:32.730] Timer tick, asking Buildbucket for the build status
[18:28:32.935] Build 8869615602869478832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:28:32.935] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9035045667212041648:64:0) after 1m0s
[18:29:33.093] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9035045667212041648:64:0)
[18:29:33.093] Timer tick, asking Buildbucket for the build status
[18:29:33.525] Build 8869615602869478832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:29:33.525] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9035045667212041648:65:0) after 1m0s
[18:30:33.670] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9035045667212041648:65:0)
[18:30:33.670] Timer tick, asking Buildbucket for the build status
[18:30:33.892] Build 8869615602869478832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:30:33.892] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9035045667212041648:66:0) after 1m0s
[18:31:34.006] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9035045667212041648:66:0)
[18:31:34.006] Timer tick, asking Buildbucket for the build status
[18:31:34.091] Build 8869615602869478832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:31:34.091] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9035045667212041648:67:0) after 1m0s
[18:32:34.225] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9035045667212041648:67:0)
[18:32:34.225] Timer tick, asking Buildbucket for the build status
[18:32:34.736] Build 8869615602869478832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:32:34.736] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9035045667212041648:68:0) after 1m0s
[18:33:34.766] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9035045667212041648:68:0)
[18:33:34.766] Timer tick, asking Buildbucket for the build status
[18:33:34.853] Build 8869615602869478832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:33:34.853] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9035045667212041648:69:0) after 1m0s
[18:34:34.912] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9035045667212041648:69:0)
[18:34:34.913] Timer tick, asking Buildbucket for the build status
[18:34:35.486] Build 8869615602869478832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:34:35.486] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9035045667212041648:70:0) after 1m0s
[18:34:48.833] Received PubSub notification, asking Buildbucket for the build status
[18:34:48.902] Build 8869615602869478832: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[18:34:48.902] Buildbucket build:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary_preference": "PROD",
"completed_ts": "1599676488139630",
"created_by": "project:emscripten-releases",
"created_ts": "1599672425355877",
"id": "8869615602869478832",
"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\": \"a24187853cbd4a6f0282b4c2f97126f1249399c3\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@a24187853cbd4a6f0282b4c2f97126f1249399c3\", \"title\": \"a24187853cbd4a6f0282b4c2f97126f1249399c3\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/a24187853cbd4a6f0282b4c2f97126f1249399c3\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"a24187853cbd4a6f0282b4c2f97126f1249399c3\"}}",
"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\": \"a24187853cbd4a6f0282b4c2f97126f1249399c3\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@a24187853cbd4a6f0282b4c2f97126f1249399c3\", \"title\": \"a24187853cbd4a6f0282b4c2f97126f1249399c3\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/a24187853cbd4a6f0282b4c2f97126f1249399c3\"}]}, \"bot_id\": \"luci-emscripten-releases-ci-win10-0-izaf\", \"branch\": \"refs/heads/master\", \"buildername\": \"win\", \"got_revision\": \"a24187853cbd4a6f0282b4c2f97126f1249399c3\", \"path_config\": \"generic\", \"recipe\": \"emscripten_releases\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"a24187853cbd4a6f0282b4c2f97126f1249399c3\"}, \"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-izaf\"], \"image\": [\"chrome-win10-1703-20083100-22dcea0839e\"], \"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\": [\"5513-ee65082\"], \"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": "1599672449220445",
"status": "COMPLETED",
"status_changed_ts": "1599676488469335",
"tags": [
"builder:win",
"buildset:commit/git/a24187853cbd4a6f0282b4c2f97126f1249399c3",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/a24187853cbd4a6f0282b4c2f97126f1249399c3",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9035045667212041648",
"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/8869615602869478832/+/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:4e8cb9a860aa6a10",
"user_agent:luci-scheduler"
],
"updated_ts": "1599676488536274",
"url": "https://ci.chromium.org/b/8869615602869478832",
"utcnow_ts": "1599676488893730"
}
}
[18:34:48.902] Invocation finished in 1h7m47.071138598s with status SUCCEEDED