[17:06:29.385] New invocation is queued and will start shortly
[17:06:30.653] Starting the invocation (attempt 1)
[17:06:30.704] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[17:06:30.704] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[17:06:30.704] Buildbucket request:
{
"bucket": "luci.emscripten-releases.ci",
"client_operation_id": "9020913832781653104",
"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\":\"6103218ed94b74334554551fcc61ad2bdf411873\"},\"id\":\"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@6103218ed94b74334554551fcc61ad2bdf411873\",\"title\":\"6103218ed94b74334554551fcc61ad2bdf411873\",\"url\":\"https://chromium.googlesource.com/emscripten-releases/+/6103218ed94b74334554551fcc61ad2bdf411873\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/emscripten-releases\",\"revision\":\"6103218ed94b74334554551fcc61ad2bdf411873\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win",
"scheduler_invocation_id:9020913832781653104",
"scheduler_job_id:emscripten-releases/win",
"user_agent:luci-scheduler",
"buildset:commit/git/6103218ed94b74334554551fcc61ad2bdf411873",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/6103218ed94b74334554551fcc61ad2bdf411873",
"gitiles_ref:refs/heads/master"
]
}
[17:06:31.576] Buildbucket response:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary_preference": "PROD",
"created_by": "project:emscripten-releases",
"created_ts": "1613149590978119",
"id": "8855483770448894128",
"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\": \"6103218ed94b74334554551fcc61ad2bdf411873\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@6103218ed94b74334554551fcc61ad2bdf411873\", \"title\": \"6103218ed94b74334554551fcc61ad2bdf411873\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/6103218ed94b74334554551fcc61ad2bdf411873\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"6103218ed94b74334554551fcc61ad2bdf411873\"}}",
"project": "emscripten-releases",
"result_details_json": "{\"properties\": {}}",
"service_account": "emscripten-releases-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1613149591439017",
"tags": [
"builder:win",
"buildset:commit/git/6103218ed94b74334554551fcc61ad2bdf411873",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/6103218ed94b74334554551fcc61ad2bdf411873",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9020913832781653104",
"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/8855483770448894128/+/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": "1613149591439157",
"url": "https://ci.chromium.org/b/8855483770448894128",
"utcnow_ts": "1613149591565898"
}
}
[17:06:31.576] Task URL: https://ci.chromium.org/b/8855483770448894128
[17:06:31.576] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:2:0) after 1m0s
[17:07:18.805] Received PubSub notification, asking Buildbucket for the build status
[17:07:18.888] Build 8855483770448894128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:07:31.619] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:2:0)
[17:07:31.619] Timer tick, asking Buildbucket for the build status
[17:07:31.705] Build 8855483770448894128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:07:31.705] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:4:0) after 1m0s
[17:08:31.716] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:4:0)
[17:08:31.716] Timer tick, asking Buildbucket for the build status
[17:08:31.807] Build 8855483770448894128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:08:31.807] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:5:0) after 1m0s
[17:09:31.822] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:5:0)
[17:09:31.822] Timer tick, asking Buildbucket for the build status
[17:09:32.006] Build 8855483770448894128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:09:32.006] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:6:0) after 1m0s
[17:10:32.035] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:6:0)
[17:10:32.035] Timer tick, asking Buildbucket for the build status
[17:10:32.124] Build 8855483770448894128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:10:32.124] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:7:0) after 1m0s
[17:11:32.142] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:7:0)
[17:11:32.142] Timer tick, asking Buildbucket for the build status
[17:11:32.490] Build 8855483770448894128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:11:32.490] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:8:0) after 1m0s
[17:12:32.534] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:8:0)
[17:12:32.534] Timer tick, asking Buildbucket for the build status
[17:12:32.625] Build 8855483770448894128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:12:32.625] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:9:0) after 1m0s
[17:13:32.666] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:9:0)
[17:13:32.666] Timer tick, asking Buildbucket for the build status
[17:13:32.822] Build 8855483770448894128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:13:32.822] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:10:0) after 1m0s
[17:14:32.925] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:10:0)
[17:14:32.925] Timer tick, asking Buildbucket for the build status
[17:14:33.014] Build 8855483770448894128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:14:33.014] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:11:0) after 1m0s
[17:15:33.125] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:11:0)
[17:15:33.125] Timer tick, asking Buildbucket for the build status
[17:15:33.392] Build 8855483770448894128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:15:33.392] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:12:0) after 1m0s
[17:16:33.377] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:12:0)
[17:16:33.377] Timer tick, asking Buildbucket for the build status
[17:16:33.518] Build 8855483770448894128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:16:33.518] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:13:0) after 1m0s
[17:17:33.586] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:13:0)
[17:17:33.586] Timer tick, asking Buildbucket for the build status
[17:17:33.716] Build 8855483770448894128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:17:33.716] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:14:0) after 1m0s
[17:18:33.771] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:14:0)
[17:18:33.771] Timer tick, asking Buildbucket for the build status
[17:18:33.846] Build 8855483770448894128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:18:33.846] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:15:0) after 1m0s
[17:19:33.864] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:15:0)
[17:19:33.864] Timer tick, asking Buildbucket for the build status
[17:19:33.943] Build 8855483770448894128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:19:33.943] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:16:0) after 1m0s
[17:20:34.034] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:16:0)
[17:20:34.034] Timer tick, asking Buildbucket for the build status
[17:20:34.165] Build 8855483770448894128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:20:34.165] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:17:0) after 1m0s
[17:21:34.310] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:17:0)
[17:21:34.310] Timer tick, asking Buildbucket for the build status
[17:21:34.693] Build 8855483770448894128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:21:34.693] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:18:0) after 1m0s
[17:22:34.867] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:18:0)
[17:22:34.867] Timer tick, asking Buildbucket for the build status
[17:22:35.111] Build 8855483770448894128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:22:35.111] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:19:0) after 1m0s
[17:23:35.206] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:19:0)
[17:23:35.206] Timer tick, asking Buildbucket for the build status
[17:23:35.274] Build 8855483770448894128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:23:35.274] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:20:0) after 1m0s
[17:24:35.328] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:20:0)
[17:24:35.328] Timer tick, asking Buildbucket for the build status
[17:24:35.422] Build 8855483770448894128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:24:35.422] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:21:0) after 1m0s
[17:25:35.462] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:21:0)
[17:25:35.462] Timer tick, asking Buildbucket for the build status
[17:25:35.625] Build 8855483770448894128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:25:35.625] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:22:0) after 1m0s
[17:26:35.957] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:22:0)
[17:26:35.957] Timer tick, asking Buildbucket for the build status
[17:26:36.078] Build 8855483770448894128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:26:36.079] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:23:0) after 1m0s
[17:27:36.104] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:23:0)
[17:27:36.104] Timer tick, asking Buildbucket for the build status
[17:27:36.417] Build 8855483770448894128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:27:36.417] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:24:0) after 1m0s
[17:28:36.542] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:24:0)
[17:28:36.542] Timer tick, asking Buildbucket for the build status
[17:28:36.630] Build 8855483770448894128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:28:36.630] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:25:0) after 1m0s
[17:29:36.672] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:25:0)
[17:29:36.672] Timer tick, asking Buildbucket for the build status
[17:29:36.750] Build 8855483770448894128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:29:36.751] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:26:0) after 1m0s
[17:30:36.802] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:26:0)
[17:30:36.802] Timer tick, asking Buildbucket for the build status
[17:30:37.018] Build 8855483770448894128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:30:37.018] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:27:0) after 1m0s
[17:31:37.125] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:27:0)
[17:31:37.125] Timer tick, asking Buildbucket for the build status
[17:31:37.261] Build 8855483770448894128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:31:37.261] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:28:0) after 1m0s
[17:32:37.431] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:28:0)
[17:32:37.431] Timer tick, asking Buildbucket for the build status
[17:32:37.517] Build 8855483770448894128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:32:37.517] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:29:0) after 1m0s
[17:33:37.588] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:29:0)
[17:33:37.588] Timer tick, asking Buildbucket for the build status
[17:33:37.693] Build 8855483770448894128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:33:37.694] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:30:0) after 1m0s
[17:34:37.922] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:30:0)
[17:34:37.922] Timer tick, asking Buildbucket for the build status
[17:34:38.019] Build 8855483770448894128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:34:38.019] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:31:0) after 1m0s
[17:35:38.064] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:31:0)
[17:35:38.064] Timer tick, asking Buildbucket for the build status
[17:35:38.469] Build 8855483770448894128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:35:38.469] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:32:0) after 1m0s
[17:36:38.565] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:32:0)
[17:36:38.565] Timer tick, asking Buildbucket for the build status
[17:36:38.660] Build 8855483770448894128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:36:38.660] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:33:0) after 1m0s
[17:37:38.738] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:33:0)
[17:37:38.738] Timer tick, asking Buildbucket for the build status
[17:37:38.839] Build 8855483770448894128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:37:38.839] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:34:0) after 1m0s
[17:38:38.898] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:34:0)
[17:38:38.898] Timer tick, asking Buildbucket for the build status
[17:38:38.987] Build 8855483770448894128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:38:38.987] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:35:0) after 1m0s
[17:39:38.994] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:35:0)
[17:39:38.994] Timer tick, asking Buildbucket for the build status
[17:39:39.104] Build 8855483770448894128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:39:39.104] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:36:0) after 1m0s
[17:40:39.139] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:36:0)
[17:40:39.139] Timer tick, asking Buildbucket for the build status
[17:40:39.231] Build 8855483770448894128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:40:39.231] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:37:0) after 1m0s
[17:41:39.295] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:37:0)
[17:41:39.295] Timer tick, asking Buildbucket for the build status
[17:41:39.414] Build 8855483770448894128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:41:39.414] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:38:0) after 1m0s
[17:42:39.500] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:38:0)
[17:42:39.500] Timer tick, asking Buildbucket for the build status
[17:42:39.653] Build 8855483770448894128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:42:39.653] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:39:0) after 1m0s
[17:43:39.714] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:39:0)
[17:43:39.714] Timer tick, asking Buildbucket for the build status
[17:43:39.890] Build 8855483770448894128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:43:39.890] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:40:0) after 1m0s
[17:44:39.919] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:40:0)
[17:44:39.919] Timer tick, asking Buildbucket for the build status
[17:44:40.004] Build 8855483770448894128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:44:40.004] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:41:0) after 1m0s
[17:45:40.032] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:41:0)
[17:45:40.032] Timer tick, asking Buildbucket for the build status
[17:45:40.117] Build 8855483770448894128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:45:40.117] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:42:0) after 1m0s
[17:46:40.230] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:42:0)
[17:46:40.230] Timer tick, asking Buildbucket for the build status
[17:46:40.385] Build 8855483770448894128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:46:40.385] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:43:0) after 1m0s
[17:47:40.412] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:43:0)
[17:47:40.412] Timer tick, asking Buildbucket for the build status
[17:47:40.682] Build 8855483770448894128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:47:40.682] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:44:0) after 1m0s
[17:48:40.721] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:44:0)
[17:48:40.721] Timer tick, asking Buildbucket for the build status
[17:48:40.813] Build 8855483770448894128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:48:40.813] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:45:0) after 1m0s
[17:49:40.887] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:45:0)
[17:49:40.887] Timer tick, asking Buildbucket for the build status
[17:49:41.154] Build 8855483770448894128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:49:41.155] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:46:0) after 1m0s
[17:50:41.174] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:46:0)
[17:50:41.174] Timer tick, asking Buildbucket for the build status
[17:50:41.256] Build 8855483770448894128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:50:41.256] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:47:0) after 1m0s
[17:51:41.261] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:47:0)
[17:51:41.261] Timer tick, asking Buildbucket for the build status
[17:51:41.368] Build 8855483770448894128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:51:41.368] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:48:0) after 1m0s
[17:52:41.464] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:48:0)
[17:52:41.464] Timer tick, asking Buildbucket for the build status
[17:52:41.548] Build 8855483770448894128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:52:41.548] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:49:0) after 1m0s
[17:53:41.604] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:49:0)
[17:53:41.604] Timer tick, asking Buildbucket for the build status
[17:53:41.685] Build 8855483770448894128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:53:41.685] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:50:0) after 1m0s
[17:54:41.748] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:50:0)
[17:54:41.748] Timer tick, asking Buildbucket for the build status
[17:54:41.858] Build 8855483770448894128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:54:41.859] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:51:0) after 1m0s
[17:55:41.892] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:51:0)
[17:55:41.892] Timer tick, asking Buildbucket for the build status
[17:55:41.965] Build 8855483770448894128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:55:41.965] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:52:0) after 1m0s
[17:56:42.058] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:52:0)
[17:56:42.058] Timer tick, asking Buildbucket for the build status
[17:56:42.277] Build 8855483770448894128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:56:42.277] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:53:0) after 1m0s
[17:57:42.300] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:53:0)
[17:57:42.300] Timer tick, asking Buildbucket for the build status
[17:57:42.647] Build 8855483770448894128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:57:42.647] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:54:0) after 1m0s
[17:58:42.681] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:54:0)
[17:58:42.681] Timer tick, asking Buildbucket for the build status
[17:58:42.828] Build 8855483770448894128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:58:42.828] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:55:0) after 1m0s
[17:59:42.899] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:55:0)
[17:59:42.899] Timer tick, asking Buildbucket for the build status
[17:59:42.996] Build 8855483770448894128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:59:42.996] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:56:0) after 1m0s
[18:00:43.019] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:56:0)
[18:00:43.019] Timer tick, asking Buildbucket for the build status
[18:00:43.347] Build 8855483770448894128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:00:43.347] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:57:0) after 1m0s
[18:01:43.348] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:57:0)
[18:01:43.348] Timer tick, asking Buildbucket for the build status
[18:01:43.456] Build 8855483770448894128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:01:43.456] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:58:0) after 1m0s
[18:02:43.579] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:58:0)
[18:02:43.579] Timer tick, asking Buildbucket for the build status
[18:02:43.722] Build 8855483770448894128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:02:43.722] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:59:0) after 1m0s
[18:03:43.698] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:59:0)
[18:03:43.698] Timer tick, asking Buildbucket for the build status
[18:03:43.771] Build 8855483770448894128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:03:43.771] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:60:0) after 1m0s
[18:04:43.812] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:60:0)
[18:04:43.812] Timer tick, asking Buildbucket for the build status
[18:04:43.908] Build 8855483770448894128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:04:43.908] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:61:0) after 1m0s
[18:05:43.929] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:61:0)
[18:05:43.930] Timer tick, asking Buildbucket for the build status
[18:05:44.023] Build 8855483770448894128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:05:44.023] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:62:0) after 1m0s
[18:06:44.111] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:62:0)
[18:06:44.111] Timer tick, asking Buildbucket for the build status
[18:06:44.203] Build 8855483770448894128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:06:44.203] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:63:0) after 1m0s
[18:07:44.254] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:63:0)
[18:07:44.254] Timer tick, asking Buildbucket for the build status
[18:07:44.408] Build 8855483770448894128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:07:44.408] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:64:0) after 1m0s
[18:08:44.499] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:64:0)
[18:08:44.499] Timer tick, asking Buildbucket for the build status
[18:08:44.604] Build 8855483770448894128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:08:44.604] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:65:0) after 1m0s
[18:09:44.660] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:65:0)
[18:09:44.660] Timer tick, asking Buildbucket for the build status
[18:09:44.747] Build 8855483770448894128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:09:44.747] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:66:0) after 1m0s
[18:10:44.767] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:66:0)
[18:10:44.767] Timer tick, asking Buildbucket for the build status
[18:10:44.883] Build 8855483770448894128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:10:44.883] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:67:0) after 1m0s
[18:11:44.909] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:67:0)
[18:11:44.909] Timer tick, asking Buildbucket for the build status
[18:11:45.189] Build 8855483770448894128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:11:45.189] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:68:0) after 1m0s
[18:12:45.165] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:68:0)
[18:12:45.165] Timer tick, asking Buildbucket for the build status
[18:12:45.242] Build 8855483770448894128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:12:45.242] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:69:0) after 1m0s
[18:13:45.274] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:69:0)
[18:13:45.274] Timer tick, asking Buildbucket for the build status
[18:13:45.424] Build 8855483770448894128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:13:45.424] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:70:0) after 1m0s
[18:14:45.534] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:70:0)
[18:14:45.534] Timer tick, asking Buildbucket for the build status
[18:14:45.615] Build 8855483770448894128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:14:45.615] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:71:0) after 1m0s
[18:15:45.701] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:71:0)
[18:15:45.701] Timer tick, asking Buildbucket for the build status
[18:15:45.814] Build 8855483770448894128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:15:45.814] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:72:0) after 1m0s
[18:16:45.874] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:72:0)
[18:16:45.874] Timer tick, asking Buildbucket for the build status
[18:16:46.056] Build 8855483770448894128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:16:46.056] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:73:0) after 1m0s
[18:17:46.096] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:73:0)
[18:17:46.096] Timer tick, asking Buildbucket for the build status
[18:17:46.409] Build 8855483770448894128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:17:46.409] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:74:0) after 1m0s
[18:18:46.524] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:74:0)
[18:18:46.524] Timer tick, asking Buildbucket for the build status
[18:18:46.645] Build 8855483770448894128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:18:46.645] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:75:0) after 1m0s
[18:19:46.630] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:75:0)
[18:19:46.630] Timer tick, asking Buildbucket for the build status
[18:19:46.826] Build 8855483770448894128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:19:46.826] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:76:0) after 1m0s
[18:20:46.860] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:76:0)
[18:20:46.860] Timer tick, asking Buildbucket for the build status
[18:20:46.966] Build 8855483770448894128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:20:46.966] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:77:0) after 1m0s
[18:21:47.029] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:77:0)
[18:21:47.029] Timer tick, asking Buildbucket for the build status
[18:21:47.166] Build 8855483770448894128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:21:47.166] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:78:0) after 1m0s
[18:22:47.238] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:78:0)
[18:22:47.238] Timer tick, asking Buildbucket for the build status
[18:22:47.461] Build 8855483770448894128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:22:47.461] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:79:0) after 1m0s
[18:23:47.462] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:79:0)
[18:23:47.462] Timer tick, asking Buildbucket for the build status
[18:23:47.596] Build 8855483770448894128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:23:47.596] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:80:0) after 1m0s
[18:24:47.618] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:80:0)
[18:24:47.618] Timer tick, asking Buildbucket for the build status
[18:24:47.706] Build 8855483770448894128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:24:47.706] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9020913832781653104:81:0) after 1m0s
[18:25:21.315] Received PubSub notification, asking Buildbucket for the build status
[18:25:21.441] Build 8855483770448894128: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[18:25:21.441] Buildbucket build:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary_preference": "PROD",
"completed_ts": "1613154320337367",
"created_by": "project:emscripten-releases",
"created_ts": "1613149590978119",
"id": "8855483770448894128",
"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\": \"6103218ed94b74334554551fcc61ad2bdf411873\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@6103218ed94b74334554551fcc61ad2bdf411873\", \"title\": \"6103218ed94b74334554551fcc61ad2bdf411873\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/6103218ed94b74334554551fcc61ad2bdf411873\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"6103218ed94b74334554551fcc61ad2bdf411873\"}}",
"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\": \"6103218ed94b74334554551fcc61ad2bdf411873\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@6103218ed94b74334554551fcc61ad2bdf411873\", \"title\": \"6103218ed94b74334554551fcc61ad2bdf411873\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/6103218ed94b74334554551fcc61ad2bdf411873\"}]}, \"bot_id\": \"luci-emscripten-releases-ci-win10-0-o2sa\", \"branch\": \"refs/heads/master\", \"buildername\": \"win\", \"got_revision\": \"6103218ed94b74334554551fcc61ad2bdf411873\", \"path_config\": \"generic\", \"recipe\": \"emscripten_releases\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"6103218ed94b74334554551fcc61ad2bdf411873\"}, \"swarming\": {\"bot_dimensions\": {\"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"host_class\": [\"default\"], \"id\": [\"luci-emscripten-releases-ci-win10-0-o2sa\"], \"image\": [\"chrome-win10-1703-20111600-a937a23cefb\"], \"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\": [\"5690-40331dd\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-b\"]}}}",
"service_account": "emscripten-releases-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1613149638014019",
"status": "COMPLETED",
"status_changed_ts": "1613154320950478",
"tags": [
"builder:win",
"buildset:commit/git/6103218ed94b74334554551fcc61ad2bdf411873",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/6103218ed94b74334554551fcc61ad2bdf411873",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9020913832781653104",
"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/8855483770448894128/+/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:51b006e0f2186510",
"user_agent:luci-scheduler"
],
"updated_ts": "1613154321005692",
"url": "https://ci.chromium.org/b/8855483770448894128",
"utcnow_ts": "1613154321434618"
}
}
[18:25:21.441] Invocation finished in 1h18m52.071350686s with status SUCCEEDED