[23:43:42.752] New invocation is queued and will start shortly
[23:43:43.897] Starting the invocation (attempt 1)
[23:43:43.948] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[23:43:43.948] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[23:43:43.948] Buildbucket request:
{
"bucket": "luci.emscripten-releases.ci",
"client_operation_id": "9033028835191826528",
"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\":\"2ab40b9581726e85c823630283b099d046687bfd\"},\"id\":\"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@2ab40b9581726e85c823630283b099d046687bfd\",\"title\":\"2ab40b9581726e85c823630283b099d046687bfd\",\"url\":\"https://chromium.googlesource.com/emscripten-releases/+/2ab40b9581726e85c823630283b099d046687bfd\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/emscripten-releases\",\"revision\":\"2ab40b9581726e85c823630283b099d046687bfd\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win",
"scheduler_invocation_id:9033028835191826528",
"scheduler_job_id:emscripten-releases/win",
"user_agent:luci-scheduler",
"buildset:commit/git/2ab40b9581726e85c823630283b099d046687bfd",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/2ab40b9581726e85c823630283b099d046687bfd",
"gitiles_ref:refs/heads/master"
]
}
[23:43:44.924] Buildbucket response:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary_preference": "PROD",
"created_by": "project:emscripten-releases",
"created_ts": "1601595824164386",
"id": "8867598773039237808",
"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\": \"2ab40b9581726e85c823630283b099d046687bfd\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@2ab40b9581726e85c823630283b099d046687bfd\", \"title\": \"2ab40b9581726e85c823630283b099d046687bfd\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/2ab40b9581726e85c823630283b099d046687bfd\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"2ab40b9581726e85c823630283b099d046687bfd\"}}",
"project": "emscripten-releases",
"result_details_json": "{\"properties\": {}}",
"service_account": "emscripten-releases-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1601595824619080",
"tags": [
"builder:win",
"buildset:commit/git/2ab40b9581726e85c823630283b099d046687bfd",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/2ab40b9581726e85c823630283b099d046687bfd",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9033028835191826528",
"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/8867598773039237808/+/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": "1601595824619233",
"url": "https://ci.chromium.org/b/8867598773039237808",
"utcnow_ts": "1601595824862728"
}
}
[23:43:44.924] Task URL: https://ci.chromium.org/b/8867598773039237808
[23:43:44.924] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9033028835191826528:2:0) after 1m0s
[23:44:13.676] Received PubSub notification, asking Buildbucket for the build status
[23:44:13.786] Build 8867598773039237808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:44:44.997] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9033028835191826528:2:0)
[23:44:44.997] Timer tick, asking Buildbucket for the build status
[23:44:45.406] Build 8867598773039237808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:44:45.406] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9033028835191826528:4:0) after 1m0s
[23:45:45.451] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9033028835191826528:4:0)
[23:45:45.451] Timer tick, asking Buildbucket for the build status
[23:45:45.709] Build 8867598773039237808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:45:45.709] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9033028835191826528:5:0) after 1m0s
[23:46:45.678] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9033028835191826528:5:0)
[23:46:45.678] Timer tick, asking Buildbucket for the build status
[23:46:45.941] Build 8867598773039237808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:46:45.941] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9033028835191826528:6:0) after 1m0s
[23:47:45.960] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9033028835191826528:6:0)
[23:47:45.960] Timer tick, asking Buildbucket for the build status
[23:47:46.095] Build 8867598773039237808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:47:46.095] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9033028835191826528:7:0) after 1m0s
[23:48:46.110] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9033028835191826528:7:0)
[23:48:46.110] Timer tick, asking Buildbucket for the build status
[23:48:46.269] Build 8867598773039237808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:48:46.269] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9033028835191826528:8:0) after 1m0s
[23:49:46.362] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9033028835191826528:8:0)
[23:49:46.362] Timer tick, asking Buildbucket for the build status
[23:49:46.476] Build 8867598773039237808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:49:46.476] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9033028835191826528:9:0) after 1m0s
[23:50:46.511] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9033028835191826528:9:0)
[23:50:46.511] Timer tick, asking Buildbucket for the build status
[23:50:46.603] Build 8867598773039237808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:50:46.603] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9033028835191826528:10:0) after 1m0s
[23:51:46.731] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9033028835191826528:10:0)
[23:51:46.731] Timer tick, asking Buildbucket for the build status
[23:51:46.795] Build 8867598773039237808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:51:46.795] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9033028835191826528:11:0) after 1m0s
[23:52:46.883] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9033028835191826528:11:0)
[23:52:46.883] Timer tick, asking Buildbucket for the build status
[23:52:46.949] Build 8867598773039237808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:52:46.949] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9033028835191826528:12:0) after 1m0s
[23:53:47.071] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9033028835191826528:12:0)
[23:53:47.071] Timer tick, asking Buildbucket for the build status
[23:53:47.454] Build 8867598773039237808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:53:47.454] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9033028835191826528:13:0) after 1m0s
[23:54:47.620] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9033028835191826528:13:0)
[23:54:47.620] Timer tick, asking Buildbucket for the build status
[23:54:47.691] Build 8867598773039237808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:54:47.691] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9033028835191826528:14:0) after 1m0s
[23:55:47.782] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9033028835191826528:14:0)
[23:55:47.782] Timer tick, asking Buildbucket for the build status
[23:55:47.928] Build 8867598773039237808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:55:47.928] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9033028835191826528:15:0) after 1m0s
[23:56:47.978] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9033028835191826528:15:0)
[23:56:47.978] Timer tick, asking Buildbucket for the build status
[23:56:48.157] Build 8867598773039237808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:56:48.157] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9033028835191826528:16:0) after 1m0s
[23:57:48.201] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9033028835191826528:16:0)
[23:57:48.201] Timer tick, asking Buildbucket for the build status
[23:57:50.731] Build 8867598773039237808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:57:50.731] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9033028835191826528:17:0) after 1m0s
[23:58:50.831] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9033028835191826528:17:0)
[23:58:50.831] Timer tick, asking Buildbucket for the build status
[23:58:51.021] Build 8867598773039237808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:58:51.021] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9033028835191826528:18:0) after 1m0s
[23:59:51.132] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9033028835191826528:18:0)
[23:59:51.132] Timer tick, asking Buildbucket for the build status
[23:59:51.189] Build 8867598773039237808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:59:51.189] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9033028835191826528:19:0) after 1m0s
[00:00:51.275] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9033028835191826528:19:0)
[00:00:51.275] Timer tick, asking Buildbucket for the build status
[00:00:51.781] Build 8867598773039237808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:00:51.781] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9033028835191826528:20:0) after 1m0s
[00:01:51.841] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9033028835191826528:20:0)
[00:01:51.841] Timer tick, asking Buildbucket for the build status
[00:01:51.929] Build 8867598773039237808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:01:51.929] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9033028835191826528:21:0) after 1m0s
[00:02:52.078] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9033028835191826528:21:0)
[00:02:52.079] Timer tick, asking Buildbucket for the build status
[00:02:52.172] Build 8867598773039237808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:02:52.172] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9033028835191826528:22:0) after 1m0s
[00:03:52.322] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9033028835191826528:22:0)
[00:03:52.322] Timer tick, asking Buildbucket for the build status
[00:03:52.803] Build 8867598773039237808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:03:52.803] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9033028835191826528:23:0) after 1m0s
[00:04:53.016] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9033028835191826528:23:0)
[00:04:53.016] Timer tick, asking Buildbucket for the build status
[00:04:53.077] Build 8867598773039237808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:04:53.077] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9033028835191826528:24:0) after 1m0s
[00:05:53.209] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9033028835191826528:24:0)
[00:05:53.209] Timer tick, asking Buildbucket for the build status
[00:05:53.324] Build 8867598773039237808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:05:53.324] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9033028835191826528:25:0) after 1m0s
[00:06:53.512] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9033028835191826528:25:0)
[00:06:53.512] Timer tick, asking Buildbucket for the build status
[00:06:53.593] Build 8867598773039237808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:06:53.594] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9033028835191826528:26:0) after 1m0s
[00:07:53.787] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9033028835191826528:26:0)
[00:07:53.787] Timer tick, asking Buildbucket for the build status
[00:07:53.904] Build 8867598773039237808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:07:53.904] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9033028835191826528:27:0) after 1m0s
[00:08:54.071] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9033028835191826528:27:0)
[00:08:54.071] Timer tick, asking Buildbucket for the build status
[00:08:54.776] Build 8867598773039237808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:08:54.776] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9033028835191826528:28:0) after 1m0s
[00:09:55.003] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9033028835191826528:28:0)
[00:09:55.003] Timer tick, asking Buildbucket for the build status
[00:09:55.301] Build 8867598773039237808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:09:55.301] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9033028835191826528:29:0) after 1m0s
[00:10:55.520] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9033028835191826528:29:0)
[00:10:55.520] Timer tick, asking Buildbucket for the build status
[00:10:55.594] Build 8867598773039237808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:10:55.594] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9033028835191826528:30:0) after 1m0s
[00:11:55.760] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9033028835191826528:30:0)
[00:11:55.760] Timer tick, asking Buildbucket for the build status
[00:11:55.841] Build 8867598773039237808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:11:55.841] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9033028835191826528:31:0) after 1m0s
[00:12:56.033] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9033028835191826528:31:0)
[00:12:56.033] Timer tick, asking Buildbucket for the build status
[00:12:56.126] Build 8867598773039237808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:12:56.126] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9033028835191826528:32:0) after 1m0s
[00:13:56.154] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9033028835191826528:32:0)
[00:13:56.154] Timer tick, asking Buildbucket for the build status
[00:13:57.385] Build 8867598773039237808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:13:57.385] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9033028835191826528:33:0) after 1m0s
[00:14:57.558] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9033028835191826528:33:0)
[00:14:57.558] Timer tick, asking Buildbucket for the build status
[00:14:58.639] Build 8867598773039237808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:14:58.639] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9033028835191826528:34:0) after 1m0s
[00:16:00.815] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9033028835191826528:34:0)
[00:16:00.815] Timer tick, asking Buildbucket for the build status
[00:16:00.889] Build 8867598773039237808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:16:00.889] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9033028835191826528:35:0) after 1m0s
[00:17:03.193] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9033028835191826528:35:0)
[00:17:03.193] Timer tick, asking Buildbucket for the build status
[00:17:03.485] Build 8867598773039237808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:17:03.485] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9033028835191826528:36:0) after 1m0s
[00:18:03.511] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9033028835191826528:36:0)
[00:18:03.511] Timer tick, asking Buildbucket for the build status
[00:18:03.727] Build 8867598773039237808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:18:03.727] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9033028835191826528:37:0) after 1m0s
[00:19:03.738] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9033028835191826528:37:0)
[00:19:03.738] Timer tick, asking Buildbucket for the build status
[00:19:03.956] Build 8867598773039237808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:19:03.956] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9033028835191826528:38:0) after 1m0s
[00:20:03.997] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9033028835191826528:38:0)
[00:20:03.997] Timer tick, asking Buildbucket for the build status
[00:20:04.344] Build 8867598773039237808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:20:04.344] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9033028835191826528:39:0) after 1m0s
[00:21:04.419] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9033028835191826528:39:0)
[00:21:04.419] Timer tick, asking Buildbucket for the build status
[00:21:04.541] Build 8867598773039237808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:21:04.541] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9033028835191826528:40:0) after 1m0s
[00:22:04.560] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9033028835191826528:40:0)
[00:22:04.560] Timer tick, asking Buildbucket for the build status
[00:22:04.683] Build 8867598773039237808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:22:04.683] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9033028835191826528:41:0) after 1m0s
[00:23:04.681] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9033028835191826528:41:0)
[00:23:04.681] Timer tick, asking Buildbucket for the build status
[00:23:04.780] Build 8867598773039237808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:23:04.780] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9033028835191826528:42:0) after 1m0s
[00:24:04.836] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9033028835191826528:42:0)
[00:24:04.836] Timer tick, asking Buildbucket for the build status
[00:24:05.438] Build 8867598773039237808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:24:05.438] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9033028835191826528:43:0) after 1m0s
[00:25:05.461] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9033028835191826528:43:0)
[00:25:05.461] Timer tick, asking Buildbucket for the build status
[00:25:05.588] Build 8867598773039237808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:25:05.588] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9033028835191826528:44:0) after 1m0s
[00:26:05.606] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9033028835191826528:44:0)
[00:26:05.606] Timer tick, asking Buildbucket for the build status
[00:26:05.671] Build 8867598773039237808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:26:05.671] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9033028835191826528:45:0) after 1m0s
[00:27:05.695] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9033028835191826528:45:0)
[00:27:05.695] Timer tick, asking Buildbucket for the build status
[00:27:06.112] Build 8867598773039237808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:27:06.112] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9033028835191826528:46:0) after 1m0s
[00:28:06.132] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9033028835191826528:46:0)
[00:28:06.132] Timer tick, asking Buildbucket for the build status
[00:28:06.202] Build 8867598773039237808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:28:06.202] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9033028835191826528:47:0) after 1m0s
[00:29:06.249] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9033028835191826528:47:0)
[00:29:06.249] Timer tick, asking Buildbucket for the build status
[00:29:06.389] Build 8867598773039237808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:29:06.389] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9033028835191826528:48:0) after 1m0s
[00:30:06.437] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9033028835191826528:48:0)
[00:30:06.437] Timer tick, asking Buildbucket for the build status
[00:30:06.523] Build 8867598773039237808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:30:06.523] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9033028835191826528:49:0) after 1m0s
[00:31:06.531] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9033028835191826528:49:0)
[00:31:06.531] Timer tick, asking Buildbucket for the build status
[00:31:07.237] Build 8867598773039237808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:31:07.237] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9033028835191826528:50:0) after 1m0s
[00:32:07.344] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9033028835191826528:50:0)
[00:32:07.344] Timer tick, asking Buildbucket for the build status
[00:32:07.538] Build 8867598773039237808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:32:07.538] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9033028835191826528:51:0) after 1m0s
[00:33:07.622] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9033028835191826528:51:0)
[00:33:07.622] Timer tick, asking Buildbucket for the build status
[00:33:07.765] Build 8867598773039237808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:33:07.765] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9033028835191826528:52:0) after 1m0s
[00:34:07.792] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9033028835191826528:52:0)
[00:34:07.793] Timer tick, asking Buildbucket for the build status
[00:34:08.020] Build 8867598773039237808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:34:08.020] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9033028835191826528:53:0) after 1m0s
[00:35:08.125] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9033028835191826528:53:0)
[00:35:08.125] Timer tick, asking Buildbucket for the build status
[00:35:08.292] Build 8867598773039237808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:35:08.292] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9033028835191826528:54:0) after 1m0s
[00:36:08.306] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9033028835191826528:54:0)
[00:36:08.306] Timer tick, asking Buildbucket for the build status
[00:36:08.677] Build 8867598773039237808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:36:08.677] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9033028835191826528:55:0) after 1m0s
[00:37:08.727] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9033028835191826528:55:0)
[00:37:08.727] Timer tick, asking Buildbucket for the build status
[00:37:08.889] Build 8867598773039237808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:37:08.889] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9033028835191826528:56:0) after 1m0s
[00:38:08.975] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9033028835191826528:56:0)
[00:38:08.975] Timer tick, asking Buildbucket for the build status
[00:38:09.052] Build 8867598773039237808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:38:09.052] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9033028835191826528:57:0) after 1m0s
[00:39:09.080] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9033028835191826528:57:0)
[00:39:09.081] Timer tick, asking Buildbucket for the build status
[00:39:09.386] Build 8867598773039237808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:39:09.386] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9033028835191826528:58:0) after 1m0s
[00:40:09.423] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9033028835191826528:58:0)
[00:40:09.423] Timer tick, asking Buildbucket for the build status
[00:40:09.743] Build 8867598773039237808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:40:09.743] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9033028835191826528:59:0) after 1m0s
[00:41:09.820] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9033028835191826528:59:0)
[00:41:09.820] Timer tick, asking Buildbucket for the build status
[00:41:09.995] Build 8867598773039237808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:41:09.995] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9033028835191826528:60:0) after 1m0s
[00:42:10.078] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9033028835191826528:60:0)
[00:42:10.078] Timer tick, asking Buildbucket for the build status
[00:42:10.154] Build 8867598773039237808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:42:10.154] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9033028835191826528:61:0) after 1m0s
[00:43:10.196] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9033028835191826528:61:0)
[00:43:10.196] Timer tick, asking Buildbucket for the build status
[00:43:10.403] Build 8867598773039237808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:43:10.403] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9033028835191826528:62:0) after 1m0s
[00:44:10.391] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9033028835191826528:62:0)
[00:44:10.391] Timer tick, asking Buildbucket for the build status
[00:44:10.681] Build 8867598773039237808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:44:10.681] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9033028835191826528:63:0) after 1m0s
[00:45:10.726] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9033028835191826528:63:0)
[00:45:10.726] Timer tick, asking Buildbucket for the build status
[00:45:11.131] Build 8867598773039237808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:45:11.131] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9033028835191826528:64:0) after 1m0s
[00:46:11.122] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9033028835191826528:64:0)
[00:46:11.122] Timer tick, asking Buildbucket for the build status
[00:46:11.303] Build 8867598773039237808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:46:11.303] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9033028835191826528:65:0) after 1m0s
[00:47:11.373] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9033028835191826528:65:0)
[00:47:11.373] Timer tick, asking Buildbucket for the build status
[00:47:11.436] Build 8867598773039237808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:47:11.436] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9033028835191826528:66:0) after 1m0s
[00:48:11.515] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9033028835191826528:66:0)
[00:48:11.515] Timer tick, asking Buildbucket for the build status
[00:48:11.746] Build 8867598773039237808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:48:11.746] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9033028835191826528:67:0) after 1m0s
[00:49:11.781] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9033028835191826528:67:0)
[00:49:11.781] Timer tick, asking Buildbucket for the build status
[00:49:12.208] Build 8867598773039237808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:49:12.208] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9033028835191826528:68:0) after 1m0s
[00:50:12.286] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9033028835191826528:68:0)
[00:50:12.286] Timer tick, asking Buildbucket for the build status
[00:50:12.460] Build 8867598773039237808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:50:12.460] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9033028835191826528:69:0) after 1m0s
[00:50:30.311] Received PubSub notification, asking Buildbucket for the build status
[00:50:30.428] Build 8867598773039237808: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[00:50:30.428] Buildbucket build:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary_preference": "PROD",
"completed_ts": "1601599829221217",
"created_by": "project:emscripten-releases",
"created_ts": "1601595824164386",
"id": "8867598773039237808",
"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\": \"2ab40b9581726e85c823630283b099d046687bfd\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@2ab40b9581726e85c823630283b099d046687bfd\", \"title\": \"2ab40b9581726e85c823630283b099d046687bfd\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/2ab40b9581726e85c823630283b099d046687bfd\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"2ab40b9581726e85c823630283b099d046687bfd\"}}",
"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\": \"2ab40b9581726e85c823630283b099d046687bfd\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@2ab40b9581726e85c823630283b099d046687bfd\", \"title\": \"2ab40b9581726e85c823630283b099d046687bfd\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/2ab40b9581726e85c823630283b099d046687bfd\"}]}, \"bot_id\": \"luci-emscripten-releases-ci-win10-0-vtwf\", \"branch\": \"refs/heads/master\", \"buildername\": \"win\", \"got_revision\": \"2ab40b9581726e85c823630283b099d046687bfd\", \"path_config\": \"generic\", \"recipe\": \"emscripten_releases\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"2ab40b9581726e85c823630283b099d046687bfd\"}, \"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-vtwf\"], \"image\": [\"chrome-win10-1703-20092300-22ba5320847\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"n1-standard-8\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.emscripten-releases.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"5564-e41b668\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-b\"]}}}",
"service_account": "emscripten-releases-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1601595852670185",
"status": "COMPLETED",
"status_changed_ts": "1601599829895944",
"tags": [
"builder:win",
"buildset:commit/git/2ab40b9581726e85c823630283b099d046687bfd",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/2ab40b9581726e85c823630283b099d046687bfd",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9033028835191826528",
"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/8867598773039237808/+/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:4eff5e6304527010",
"user_agent:luci-scheduler"
],
"updated_ts": "1601599829962524",
"url": "https://ci.chromium.org/b/8867598773039237808",
"utcnow_ts": "1601599830419587"
}
}
[00:50:30.428] Invocation finished in 1h6m47.700057218s with status SUCCEEDED