[09:17:08.223] New invocation is queued and will start shortly
[09:17:09.425] Starting the invocation (attempt 1)
[09:17:09.481] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[09:17:09.482] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[09:17:09.482] Buildbucket request:
{
"bucket": "luci.emscripten-releases.ci",
"client_operation_id": "9042596036876282240",
"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\":\"698868d18828c9cf35e3d90b36f74e013456aa2f\"},\"id\":\"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@698868d18828c9cf35e3d90b36f74e013456aa2f\",\"title\":\"698868d18828c9cf35e3d90b36f74e013456aa2f\",\"url\":\"https://chromium.googlesource.com/emscripten-releases/+/698868d18828c9cf35e3d90b36f74e013456aa2f\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/emscripten-releases\",\"revision\":\"698868d18828c9cf35e3d90b36f74e013456aa2f\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win",
"scheduler_invocation_id:9042596036876282240",
"scheduler_job_id:emscripten-releases/win",
"user_agent:luci-scheduler",
"buildset:commit/git/698868d18828c9cf35e3d90b36f74e013456aa2f",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/698868d18828c9cf35e3d90b36f74e013456aa2f",
"gitiles_ref:refs/heads/master"
]
}
[09:17:11.263] Buildbucket response:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary_preference": "PROD",
"created_by": "project:emscripten-releases",
"created_ts": "1592471829643809",
"id": "8877165974718640416",
"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\": \"698868d18828c9cf35e3d90b36f74e013456aa2f\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@698868d18828c9cf35e3d90b36f74e013456aa2f\", \"title\": \"698868d18828c9cf35e3d90b36f74e013456aa2f\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/698868d18828c9cf35e3d90b36f74e013456aa2f\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"698868d18828c9cf35e3d90b36f74e013456aa2f\"}}",
"project": "emscripten-releases",
"result_details_json": "{\"properties\": {}}",
"service_account": "emscripten-releases-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1592471830514368",
"tags": [
"builder:win",
"buildset:commit/git/698868d18828c9cf35e3d90b36f74e013456aa2f",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/698868d18828c9cf35e3d90b36f74e013456aa2f",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9042596036876282240",
"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/8877165974718640416/+/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": "1592471830517114",
"url": "https://ci.chromium.org/b/8877165974718640416",
"utcnow_ts": "1592471831228565"
}
}
[09:17:11.263] Task URL: https://ci.chromium.org/b/8877165974718640416
[09:17:11.263] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:2:0) after 1m0s
[09:17:39.762] Received PubSub notification, asking Buildbucket for the build status
[09:17:43.086] Build 8877165974718640416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:18:11.349] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:2:0)
[09:18:11.349] Timer tick, asking Buildbucket for the build status
[09:18:11.489] Build 8877165974718640416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:18:11.489] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:4:0) after 1m0s
[09:19:11.573] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:4:0)
[09:19:11.573] Timer tick, asking Buildbucket for the build status
[09:19:11.715] Build 8877165974718640416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:19:11.715] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:5:0) after 1m0s
[09:20:11.728] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:5:0)
[09:20:11.728] Timer tick, asking Buildbucket for the build status
[09:20:12.400] Build 8877165974718640416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:20:12.400] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:6:0) after 1m0s
[09:21:12.496] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:6:0)
[09:21:12.496] Timer tick, asking Buildbucket for the build status
[09:21:12.788] Build 8877165974718640416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:21:12.788] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:7:0) after 1m0s
[09:22:12.938] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:7:0)
[09:22:12.938] Timer tick, asking Buildbucket for the build status
[09:22:13.084] Build 8877165974718640416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:22:13.084] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:8:0) after 1m0s
[09:23:13.154] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:8:0)
[09:23:13.154] Timer tick, asking Buildbucket for the build status
[09:23:13.350] Build 8877165974718640416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:23:13.350] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:9:0) after 1m0s
[09:24:13.594] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:9:0)
[09:24:13.594] Timer tick, asking Buildbucket for the build status
[09:24:14.515] Build 8877165974718640416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:24:14.515] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:10:0) after 1m0s
[09:25:14.495] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:10:0)
[09:25:14.495] Timer tick, asking Buildbucket for the build status
[09:25:14.828] Build 8877165974718640416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:25:14.828] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:11:0) after 1m0s
[09:26:14.942] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:11:0)
[09:26:14.942] Timer tick, asking Buildbucket for the build status
[09:26:15.541] Build 8877165974718640416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:26:15.541] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:12:0) after 1m0s
[09:27:15.661] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:12:0)
[09:27:15.661] Timer tick, asking Buildbucket for the build status
[09:27:16.816] Build 8877165974718640416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:27:16.817] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:13:0) after 1m0s
[09:28:16.895] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:13:0)
[09:28:16.895] Timer tick, asking Buildbucket for the build status
[09:28:17.182] Build 8877165974718640416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:28:17.182] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:14:0) after 1m0s
[09:29:17.265] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:14:0)
[09:29:17.265] Timer tick, asking Buildbucket for the build status
[09:29:17.557] Build 8877165974718640416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:29:17.557] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:15:0) after 1m0s
[09:30:17.745] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:15:0)
[09:30:17.745] Timer tick, asking Buildbucket for the build status
[09:30:17.907] Build 8877165974718640416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:30:17.907] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:16:0) after 1m0s
[09:31:17.965] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:16:0)
[09:31:17.965] Timer tick, asking Buildbucket for the build status
[09:31:18.124] Build 8877165974718640416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:31:18.124] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:17:0) after 1m0s
[09:32:18.125] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:17:0)
[09:32:18.125] Timer tick, asking Buildbucket for the build status
[09:32:18.300] Build 8877165974718640416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:32:18.300] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:18:0) after 1m0s
[09:33:18.346] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:18:0)
[09:33:18.346] Timer tick, asking Buildbucket for the build status
[09:33:18.689] Build 8877165974718640416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:33:18.689] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:19:0) after 1m0s
[09:34:18.758] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:19:0)
[09:34:18.758] Timer tick, asking Buildbucket for the build status
[09:34:19.861] Build 8877165974718640416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:34:19.861] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:20:0) after 1m0s
[09:35:20.027] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:20:0)
[09:35:20.027] Timer tick, asking Buildbucket for the build status
[09:35:20.196] Build 8877165974718640416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:35:20.196] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:21:0) after 1m0s
[09:36:20.263] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:21:0)
[09:36:20.263] Timer tick, asking Buildbucket for the build status
[09:36:20.470] Build 8877165974718640416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:36:20.470] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:22:0) after 1m0s
[09:37:20.511] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:22:0)
[09:37:20.511] Timer tick, asking Buildbucket for the build status
[09:37:20.981] Build 8877165974718640416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:37:20.981] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:23:0) after 1m0s
[09:38:21.004] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:23:0)
[09:38:21.004] Timer tick, asking Buildbucket for the build status
[09:38:21.609] Build 8877165974718640416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:38:21.609] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:24:0) after 1m0s
[09:39:21.831] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:24:0)
[09:39:21.831] Timer tick, asking Buildbucket for the build status
[09:39:22.057] Build 8877165974718640416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:39:22.057] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:25:0) after 1m0s
[09:40:22.332] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:25:0)
[09:40:22.332] Timer tick, asking Buildbucket for the build status
[09:40:22.701] Build 8877165974718640416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:40:22.701] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:26:0) after 1m0s
[09:41:23.946] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:26:0)
[09:41:23.946] Timer tick, asking Buildbucket for the build status
[09:41:24.075] Build 8877165974718640416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:41:24.075] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:27:0) after 1m0s
[09:42:24.216] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:27:0)
[09:42:24.216] Timer tick, asking Buildbucket for the build status
[09:42:24.337] Build 8877165974718640416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:42:24.337] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:28:0) after 1m0s
[09:43:24.502] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:28:0)
[09:43:24.502] Timer tick, asking Buildbucket for the build status
[09:43:24.644] Build 8877165974718640416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:43:24.644] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:29:0) after 1m0s
[09:44:24.711] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:29:0)
[09:44:24.711] Timer tick, asking Buildbucket for the build status
[09:44:24.868] Build 8877165974718640416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:44:24.868] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:30:0) after 1m0s
[09:45:24.924] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:30:0)
[09:45:24.924] Timer tick, asking Buildbucket for the build status
[09:45:25.832] Build 8877165974718640416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:45:25.832] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:31:0) after 1m0s
[09:46:26.026] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:31:0)
[09:46:26.026] Timer tick, asking Buildbucket for the build status
[09:46:26.532] Build 8877165974718640416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:46:26.532] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:32:0) after 1m0s
[09:47:26.720] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:32:0)
[09:47:26.720] Timer tick, asking Buildbucket for the build status
[09:47:26.890] Build 8877165974718640416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:47:26.890] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:33:0) after 1m0s
[09:48:27.184] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:33:0)
[09:48:27.184] Timer tick, asking Buildbucket for the build status
[09:48:27.405] Build 8877165974718640416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:48:27.405] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:34:0) after 1m0s
[09:49:27.594] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:34:0)
[09:49:27.594] Timer tick, asking Buildbucket for the build status
[09:49:27.816] Build 8877165974718640416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:49:27.816] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:35:0) after 1m0s
[09:50:27.900] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:35:0)
[09:50:27.900] Timer tick, asking Buildbucket for the build status
[09:50:28.042] Build 8877165974718640416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:50:28.042] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:36:0) after 1m0s
[09:51:28.131] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:36:0)
[09:51:28.131] Timer tick, asking Buildbucket for the build status
[09:51:28.295] Build 8877165974718640416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:51:28.295] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:37:0) after 1m0s
[09:52:28.403] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:37:0)
[09:52:28.403] Timer tick, asking Buildbucket for the build status
[09:52:28.522] Build 8877165974718640416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:52:28.522] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:38:0) after 1m0s
[09:53:28.624] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:38:0)
[09:53:28.624] Timer tick, asking Buildbucket for the build status
[09:53:28.757] Build 8877165974718640416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:53:28.757] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:39:0) after 1m0s
[09:54:28.867] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:39:0)
[09:54:28.867] Timer tick, asking Buildbucket for the build status
[09:54:29.006] Build 8877165974718640416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:54:29.006] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:40:0) after 1m0s
[09:55:29.059] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:40:0)
[09:55:29.059] Timer tick, asking Buildbucket for the build status
[09:55:29.239] Build 8877165974718640416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:55:29.313] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:41:0) after 1m0s
[09:56:29.422] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:41:0)
[09:56:29.422] Timer tick, asking Buildbucket for the build status
[09:56:29.533] Build 8877165974718640416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:56:29.533] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:42:0) after 1m0s
[09:57:29.554] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:42:0)
[09:57:29.554] Timer tick, asking Buildbucket for the build status
[09:57:31.081] Build 8877165974718640416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:57:31.081] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:43:0) after 1m0s
[09:58:31.230] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:43:0)
[09:58:31.230] Timer tick, asking Buildbucket for the build status
[09:58:32.106] Build 8877165974718640416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:58:32.106] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:44:0) after 1m0s
[09:59:32.147] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:44:0)
[09:59:32.147] Timer tick, asking Buildbucket for the build status
[09:59:32.466] Build 8877165974718640416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:59:32.466] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:45:0) after 1m0s
[10:00:32.534] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:45:0)
[10:00:32.534] Timer tick, asking Buildbucket for the build status
[10:00:33.045] Build 8877165974718640416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:00:33.045] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:46:0) after 1m0s
[10:01:33.073] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:46:0)
[10:01:33.073] Timer tick, asking Buildbucket for the build status
[10:01:33.753] Build 8877165974718640416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:01:33.754] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:47:0) after 1m0s
[10:02:33.797] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:47:0)
[10:02:33.797] Timer tick, asking Buildbucket for the build status
[10:02:34.744] Build 8877165974718640416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:02:34.744] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:48:0) after 1m0s
[10:03:34.796] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:48:0)
[10:03:34.796] Timer tick, asking Buildbucket for the build status
[10:03:37.502] Build 8877165974718640416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:03:37.502] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:49:0) after 1m0s
[10:04:37.526] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:49:0)
[10:04:37.526] Timer tick, asking Buildbucket for the build status
[10:04:37.682] Build 8877165974718640416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:04:37.682] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:50:0) after 1m0s
[10:05:37.729] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:50:0)
[10:05:37.729] Timer tick, asking Buildbucket for the build status
[10:05:37.896] Build 8877165974718640416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:05:37.896] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:51:0) after 1m0s
[10:06:37.919] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:51:0)
[10:06:37.919] Timer tick, asking Buildbucket for the build status
[10:06:38.606] Build 8877165974718640416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:06:38.606] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:52:0) after 1m0s
[10:07:38.664] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:52:0)
[10:07:38.664] Timer tick, asking Buildbucket for the build status
[10:07:38.915] Build 8877165974718640416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:07:38.915] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:53:0) after 1m0s
[10:08:38.946] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:53:0)
[10:08:38.946] Timer tick, asking Buildbucket for the build status
[10:08:39.197] Build 8877165974718640416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:08:39.197] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:54:0) after 1m0s
[10:09:39.241] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:54:0)
[10:09:39.241] Timer tick, asking Buildbucket for the build status
[10:09:39.385] Build 8877165974718640416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:09:39.385] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:55:0) after 1m0s
[10:10:39.428] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:55:0)
[10:10:39.428] Timer tick, asking Buildbucket for the build status
[10:10:39.554] Build 8877165974718640416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:10:39.554] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:56:0) after 1m0s
[10:11:39.544] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:56:0)
[10:11:39.544] Timer tick, asking Buildbucket for the build status
[10:11:39.683] Build 8877165974718640416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:11:39.683] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:57:0) after 1m0s
[10:12:39.750] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:57:0)
[10:12:39.750] Timer tick, asking Buildbucket for the build status
[10:12:41.115] Build 8877165974718640416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:12:41.115] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:58:0) after 1m0s
[10:13:41.206] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:58:0)
[10:13:41.206] Timer tick, asking Buildbucket for the build status
[10:13:42.916] Build 8877165974718640416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:13:42.916] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:59:0) after 1m0s
[10:14:42.979] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:59:0)
[10:14:42.979] Timer tick, asking Buildbucket for the build status
[10:14:43.174] Build 8877165974718640416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:14:43.174] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:60:0) after 1m0s
[10:15:43.280] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:60:0)
[10:15:43.280] Timer tick, asking Buildbucket for the build status
[10:15:45.968] Build 8877165974718640416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:15:45.968] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:61:0) after 1m0s
[10:16:46.071] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:61:0)
[10:16:46.071] Timer tick, asking Buildbucket for the build status
[10:16:46.319] Build 8877165974718640416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:16:46.319] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:62:0) after 1m0s
[10:17:46.346] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:62:0)
[10:17:46.346] Timer tick, asking Buildbucket for the build status
[10:17:46.492] Build 8877165974718640416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:17:46.492] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:63:0) after 1m0s
[10:18:46.623] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:63:0)
[10:18:46.623] Timer tick, asking Buildbucket for the build status
[10:18:52.886] Build 8877165974718640416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:18:52.886] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:64:0) after 1m0s
[10:19:52.973] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:64:0)
[10:19:52.973] Timer tick, asking Buildbucket for the build status
[10:19:53.177] Build 8877165974718640416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:19:53.177] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:65:0) after 1m0s
[10:20:53.198] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:65:0)
[10:20:53.199] Timer tick, asking Buildbucket for the build status
[10:20:53.414] Build 8877165974718640416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:20:53.414] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:66:0) after 1m0s
[10:21:53.577] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:66:0)
[10:21:53.577] Timer tick, asking Buildbucket for the build status
[10:21:53.754] Build 8877165974718640416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:21:53.754] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:67:0) after 1m0s
[10:22:53.945] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:67:0)
[10:22:53.945] Timer tick, asking Buildbucket for the build status
[10:22:54.351] Build 8877165974718640416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:22:54.351] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:68:0) after 1m0s
[10:23:54.412] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:68:0)
[10:23:54.412] Timer tick, asking Buildbucket for the build status
[10:23:55.032] Build 8877165974718640416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:23:55.032] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:69:0) after 1m0s
[10:24:55.026] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:69:0)
[10:24:55.026] Timer tick, asking Buildbucket for the build status
[10:24:55.452] Build 8877165974718640416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:24:55.452] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:70:0) after 1m0s
[10:25:55.486] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:70:0)
[10:25:55.486] Timer tick, asking Buildbucket for the build status
[10:25:55.910] Build 8877165974718640416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:25:55.910] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:71:0) after 1m0s
[10:26:55.995] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:71:0)
[10:26:55.995] Timer tick, asking Buildbucket for the build status
[10:26:56.174] Build 8877165974718640416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:26:56.174] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:72:0) after 1m0s
[10:27:56.296] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:72:0)
[10:27:56.296] Timer tick, asking Buildbucket for the build status
[10:27:56.437] Build 8877165974718640416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:27:56.437] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:73:0) after 1m0s
[10:28:56.621] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:73:0)
[10:28:56.621] Timer tick, asking Buildbucket for the build status
[10:28:56.916] Build 8877165974718640416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:28:56.916] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:74:0) after 1m0s
[10:29:57.111] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:74:0)
[10:29:57.111] Timer tick, asking Buildbucket for the build status
[10:29:57.258] Build 8877165974718640416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:29:57.258] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:75:0) after 1m0s
[10:30:57.326] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:75:0)
[10:30:57.326] Timer tick, asking Buildbucket for the build status
[10:30:57.480] Build 8877165974718640416: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:30:57.480] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9042596036876282240:76:0) after 1m0s
[10:31:54.647] Received PubSub notification, asking Buildbucket for the build status
[10:31:54.805] Build 8877165974718640416: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[10:31:54.805] Buildbucket build:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary_preference": "PROD",
"completed_ts": "1592476313451934",
"created_by": "project:emscripten-releases",
"created_ts": "1592471829643809",
"id": "8877165974718640416",
"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\": \"698868d18828c9cf35e3d90b36f74e013456aa2f\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@698868d18828c9cf35e3d90b36f74e013456aa2f\", \"title\": \"698868d18828c9cf35e3d90b36f74e013456aa2f\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/698868d18828c9cf35e3d90b36f74e013456aa2f\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"698868d18828c9cf35e3d90b36f74e013456aa2f\"}}",
"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\": \"698868d18828c9cf35e3d90b36f74e013456aa2f\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@698868d18828c9cf35e3d90b36f74e013456aa2f\", \"title\": \"698868d18828c9cf35e3d90b36f74e013456aa2f\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/698868d18828c9cf35e3d90b36f74e013456aa2f\"}]}, \"bot_id\": \"luci-emscripten-releases-ci-win10-0-mw1l\", \"branch\": \"refs/heads/master\", \"buildername\": \"win\", \"got_revision\": \"698868d18828c9cf35e3d90b36f74e013456aa2f\", \"path_config\": \"generic\", \"recipe\": \"emscripten_releases\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"698868d18828c9cf35e3d90b36f74e013456aa2f\"}, \"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-mw1l\"], \"image\": [\"chrome-win10-1703-20052900-58250aa4257\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"n1-standard-8\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.emscripten-releases.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"5218-123b763\"], \"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": "1592471857905621",
"status": "COMPLETED",
"status_changed_ts": "1592476313954030",
"tags": [
"builder:win",
"buildset:commit/git/698868d18828c9cf35e3d90b36f74e013456aa2f",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/698868d18828c9cf35e3d90b36f74e013456aa2f",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9042596036876282240",
"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/8877165974718640416/+/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:4cdf894c2d1b8310",
"user_agent:luci-scheduler"
],
"updated_ts": "1592476313986443",
"url": "https://ci.chromium.org/b/8877165974718640416",
"utcnow_ts": "1592476314790693"
}
}
[10:31:54.806] Invocation finished in 1h14m46.603835811s with status SUCCEEDED