[18:03:16.354] New invocation is queued and will start shortly
[18:03:17.432] Starting the invocation (attempt 1)
[18:03:17.469] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[18:03:17.469] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[18:03:17.469] Buildbucket request:
{
"bucket": "luci.emscripten-releases.ci",
"client_operation_id": "9050173080465642432",
"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\":\"d9b23c6e8378b526329766dc7517ca638538f360\"},\"id\":\"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@d9b23c6e8378b526329766dc7517ca638538f360\",\"title\":\"d9b23c6e8378b526329766dc7517ca638538f360\",\"url\":\"https://chromium.googlesource.com/emscripten-releases/+/d9b23c6e8378b526329766dc7517ca638538f360\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/emscripten-releases\",\"revision\":\"d9b23c6e8378b526329766dc7517ca638538f360\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win",
"scheduler_invocation_id:9050173080465642432",
"scheduler_job_id:emscripten-releases/win",
"user_agent:luci-scheduler",
"buildset:commit/git/d9b23c6e8378b526329766dc7517ca638538f360",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/d9b23c6e8378b526329766dc7517ca638538f360",
"gitiles_ref:refs/heads/master"
]
}
[18:03:20.958] Buildbucket response:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary_preference": "PROD",
"created_by": "project:emscripten-releases",
"created_ts": "1585245797909779",
"id": "8884743018169590032",
"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\": \"d9b23c6e8378b526329766dc7517ca638538f360\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@d9b23c6e8378b526329766dc7517ca638538f360\", \"title\": \"d9b23c6e8378b526329766dc7517ca638538f360\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/d9b23c6e8378b526329766dc7517ca638538f360\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"d9b23c6e8378b526329766dc7517ca638538f360\"}}",
"project": "emscripten-releases",
"result_details_json": "{\"properties\": {}}",
"service_account": "emscripten-releases-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1585245800095057",
"tags": [
"builder:win",
"buildset:commit/git/d9b23c6e8378b526329766dc7517ca638538f360",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/d9b23c6e8378b526329766dc7517ca638538f360",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9050173080465642432",
"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/8884743018169590032/+/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": "1585245800095209",
"url": "https://ci.chromium.org/b/8884743018169590032",
"utcnow_ts": "1585245800911587"
}
}
[18:03:20.958] Task URL: https://ci.chromium.org/b/8884743018169590032
[18:03:20.958] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:2:0) after 1m0s
[18:03:59.472] Received PubSub notification, asking Buildbucket for the build status
[18:03:59.851] Build 8884743018169590032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:04:21.073] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:2:0)
[18:04:21.073] Timer tick, asking Buildbucket for the build status
[18:04:21.444] Build 8884743018169590032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:04:21.445] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:4:0) after 1m0s
[18:05:21.448] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:4:0)
[18:05:21.448] Timer tick, asking Buildbucket for the build status
[18:05:21.852] Build 8884743018169590032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:05:21.852] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:5:0) after 1m0s
[18:06:21.867] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:5:0)
[18:06:21.867] Timer tick, asking Buildbucket for the build status
[18:06:22.020] Build 8884743018169590032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:06:22.020] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:6:0) after 1m0s
[18:07:22.049] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:6:0)
[18:07:22.049] Timer tick, asking Buildbucket for the build status
[18:07:22.513] Build 8884743018169590032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:07:22.513] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:7:0) after 1m0s
[18:08:22.533] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:7:0)
[18:08:22.533] Timer tick, asking Buildbucket for the build status
[18:08:22.710] Build 8884743018169590032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:08:22.710] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:8:0) after 1m0s
[18:09:22.733] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:8:0)
[18:09:22.733] Timer tick, asking Buildbucket for the build status
[18:09:22.900] Build 8884743018169590032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:09:22.900] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:9:0) after 1m0s
[18:10:22.916] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:9:0)
[18:10:22.917] Timer tick, asking Buildbucket for the build status
[18:10:23.258] Build 8884743018169590032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:10:23.258] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:10:0) after 1m0s
[18:11:23.318] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:10:0)
[18:11:23.318] Timer tick, asking Buildbucket for the build status
[18:11:23.505] Build 8884743018169590032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:11:23.505] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:11:0) after 1m0s
[18:12:23.571] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:11:0)
[18:12:23.571] Timer tick, asking Buildbucket for the build status
[18:12:24.304] Build 8884743018169590032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:12:24.304] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:12:0) after 1m0s
[18:13:24.321] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:12:0)
[18:13:24.321] Timer tick, asking Buildbucket for the build status
[18:13:24.592] Build 8884743018169590032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:13:24.592] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:13:0) after 1m0s
[18:14:24.663] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:13:0)
[18:14:24.663] Timer tick, asking Buildbucket for the build status
[18:14:24.850] Build 8884743018169590032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:14:24.850] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:14:0) after 1m0s
[18:15:24.885] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:14:0)
[18:15:24.885] Timer tick, asking Buildbucket for the build status
[18:15:25.407] Build 8884743018169590032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:15:25.407] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:15:0) after 1m0s
[18:16:25.541] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:15:0)
[18:16:25.541] Timer tick, asking Buildbucket for the build status
[18:16:25.844] Build 8884743018169590032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:16:25.845] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:16:0) after 1m0s
[18:17:25.890] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:16:0)
[18:17:25.890] Timer tick, asking Buildbucket for the build status
[18:17:27.010] Build 8884743018169590032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:17:27.010] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:17:0) after 1m0s
[18:18:27.115] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:17:0)
[18:18:27.115] Timer tick, asking Buildbucket for the build status
[18:18:27.455] Build 8884743018169590032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:18:27.455] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:18:0) after 1m0s
[18:19:27.495] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:18:0)
[18:19:27.495] Timer tick, asking Buildbucket for the build status
[18:19:27.933] Build 8884743018169590032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:19:27.933] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:19:0) after 1m0s
[18:20:27.981] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:19:0)
[18:20:27.981] Timer tick, asking Buildbucket for the build status
[18:20:28.260] Build 8884743018169590032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:20:28.260] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:20:0) after 1m0s
[18:21:28.416] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:20:0)
[18:21:28.416] Timer tick, asking Buildbucket for the build status
[18:21:28.648] Build 8884743018169590032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:21:28.648] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:21:0) after 1m0s
[18:22:28.867] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:21:0)
[18:22:28.867] Timer tick, asking Buildbucket for the build status
[18:22:29.068] Build 8884743018169590032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:22:29.068] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:22:0) after 1m0s
[18:23:29.234] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:22:0)
[18:23:29.234] Timer tick, asking Buildbucket for the build status
[18:23:29.360] Build 8884743018169590032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:23:29.360] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:23:0) after 1m0s
[18:24:29.642] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:23:0)
[18:24:29.642] Timer tick, asking Buildbucket for the build status
[18:24:29.930] Build 8884743018169590032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:24:29.930] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:24:0) after 1m0s
[18:25:30.087] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:24:0)
[18:25:30.087] Timer tick, asking Buildbucket for the build status
[18:25:30.206] Build 8884743018169590032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:25:30.206] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:25:0) after 1m0s
[18:26:30.481] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:25:0)
[18:26:30.481] Timer tick, asking Buildbucket for the build status
[18:26:30.627] Build 8884743018169590032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:26:30.627] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:26:0) after 1m0s
[18:27:31.044] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:26:0)
[18:27:31.044] Timer tick, asking Buildbucket for the build status
[18:27:34.651] Build 8884743018169590032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:27:34.651] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:27:0) after 1m0s
[18:28:34.685] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:27:0)
[18:28:34.685] Timer tick, asking Buildbucket for the build status
[18:28:35.051] Build 8884743018169590032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:28:35.051] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:28:0) after 1m0s
[18:29:35.082] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:28:0)
[18:29:35.082] Timer tick, asking Buildbucket for the build status
[18:29:35.535] Build 8884743018169590032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:29:35.535] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:29:0) after 1m0s
[18:30:35.576] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:29:0)
[18:30:35.576] Timer tick, asking Buildbucket for the build status
[18:30:35.789] Build 8884743018169590032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:30:35.789] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:30:0) after 1m0s
[18:31:35.828] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:30:0)
[18:31:35.828] Timer tick, asking Buildbucket for the build status
[18:31:36.937] Build 8884743018169590032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:31:36.937] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:31:0) after 1m0s
[18:32:37.001] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:31:0)
[18:32:37.001] Timer tick, asking Buildbucket for the build status
[18:32:38.295] Build 8884743018169590032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:32:38.295] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:32:0) after 1m0s
[18:33:38.368] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:32:0)
[18:33:38.368] Timer tick, asking Buildbucket for the build status
[18:33:38.577] Build 8884743018169590032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:33:38.577] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:33:0) after 1m0s
[18:34:38.603] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:33:0)
[18:34:38.603] Timer tick, asking Buildbucket for the build status
[18:34:39.009] Build 8884743018169590032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:34:39.009] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:34:0) after 1m0s
[18:35:39.052] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:34:0)
[18:35:39.052] Timer tick, asking Buildbucket for the build status
[18:35:39.284] Build 8884743018169590032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:35:39.284] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:35:0) after 1m0s
[18:36:39.306] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:35:0)
[18:36:39.306] Timer tick, asking Buildbucket for the build status
[18:36:40.342] Build 8884743018169590032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:36:40.342] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:36:0) after 1m0s
[18:37:40.454] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:36:0)
[18:37:40.454] Timer tick, asking Buildbucket for the build status
[18:37:41.591] Build 8884743018169590032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:37:41.591] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:37:0) after 1m0s
[18:38:41.676] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:37:0)
[18:38:41.676] Timer tick, asking Buildbucket for the build status
[18:38:41.818] Build 8884743018169590032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:38:41.818] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:38:0) after 1m0s
[18:39:41.863] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:38:0)
[18:39:41.863] Timer tick, asking Buildbucket for the build status
[18:39:42.057] Build 8884743018169590032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:39:42.057] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:39:0) after 1m0s
[18:40:42.082] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:39:0)
[18:40:42.082] Timer tick, asking Buildbucket for the build status
[18:40:42.398] Build 8884743018169590032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:40:42.398] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:40:0) after 1m0s
[18:41:42.416] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:40:0)
[18:41:42.416] Timer tick, asking Buildbucket for the build status
[18:41:42.852] Build 8884743018169590032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:41:42.852] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:41:0) after 1m0s
[18:42:42.874] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:41:0)
[18:42:42.874] Timer tick, asking Buildbucket for the build status
[18:42:43.382] Build 8884743018169590032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:42:43.382] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:42:0) after 1m0s
[18:43:43.407] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:42:0)
[18:43:43.407] Timer tick, asking Buildbucket for the build status
[18:43:43.654] Build 8884743018169590032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:43:43.654] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:43:0) after 1m0s
[18:44:43.731] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:43:0)
[18:44:43.731] Timer tick, asking Buildbucket for the build status
[18:44:43.941] Build 8884743018169590032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:44:43.941] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:44:0) after 1m0s
[18:45:44.000] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:44:0)
[18:45:44.000] Timer tick, asking Buildbucket for the build status
[18:45:44.283] Build 8884743018169590032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:45:44.283] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:45:0) after 1m0s
[18:46:44.277] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:45:0)
[18:46:44.277] Timer tick, asking Buildbucket for the build status
[18:46:44.569] Build 8884743018169590032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:46:44.569] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:46:0) after 1m0s
[18:47:44.625] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:46:0)
[18:47:44.625] Timer tick, asking Buildbucket for the build status
[18:47:44.942] Build 8884743018169590032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:47:44.942] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:47:0) after 1m0s
[18:48:44.990] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:47:0)
[18:48:44.990] Timer tick, asking Buildbucket for the build status
[18:48:45.180] Build 8884743018169590032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:48:45.180] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:48:0) after 1m0s
[18:49:45.321] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:48:0)
[18:49:45.321] Timer tick, asking Buildbucket for the build status
[18:49:45.899] Build 8884743018169590032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:49:45.899] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:49:0) after 1m0s
[18:50:45.964] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:49:0)
[18:50:45.964] Timer tick, asking Buildbucket for the build status
[18:50:46.567] Build 8884743018169590032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:50:46.567] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:50:0) after 1m0s
[18:51:46.622] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:50:0)
[18:51:46.622] Timer tick, asking Buildbucket for the build status
[18:51:46.766] Build 8884743018169590032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:51:46.766] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:51:0) after 1m0s
[18:52:46.787] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:51:0)
[18:52:46.787] Timer tick, asking Buildbucket for the build status
[18:52:46.963] Build 8884743018169590032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:52:46.963] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:52:0) after 1m0s
[18:53:47.008] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:52:0)
[18:53:47.008] Timer tick, asking Buildbucket for the build status
[18:53:47.153] Build 8884743018169590032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:53:47.153] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:53:0) after 1m0s
[18:54:47.199] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:53:0)
[18:54:47.199] Timer tick, asking Buildbucket for the build status
[18:54:47.511] Build 8884743018169590032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:54:47.511] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:54:0) after 1m0s
[18:55:47.578] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:54:0)
[18:55:47.578] Timer tick, asking Buildbucket for the build status
[18:55:47.748] Build 8884743018169590032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:55:47.748] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:55:0) after 1m0s
[18:56:47.751] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:55:0)
[18:56:47.751] Timer tick, asking Buildbucket for the build status
[18:56:47.887] Build 8884743018169590032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:56:47.887] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:56:0) after 1m0s
[18:57:47.973] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:56:0)
[18:57:47.973] Timer tick, asking Buildbucket for the build status
[18:57:48.580] Build 8884743018169590032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:57:48.580] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:57:0) after 1m0s
[18:58:48.639] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:57:0)
[18:58:48.639] Timer tick, asking Buildbucket for the build status
[18:58:48.850] Build 8884743018169590032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:58:48.850] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:58:0) after 1m0s
[18:59:48.918] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:58:0)
[18:59:48.918] Timer tick, asking Buildbucket for the build status
[18:59:49.080] Build 8884743018169590032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:59:49.080] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:59:0) after 1m0s
[19:00:49.162] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:59:0)
[19:00:49.163] Timer tick, asking Buildbucket for the build status
[19:00:49.316] Build 8884743018169590032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:00:49.316] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:60:0) after 1m0s
[19:01:49.362] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:60:0)
[19:01:49.362] Timer tick, asking Buildbucket for the build status
[19:01:49.954] Build 8884743018169590032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:01:49.954] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:61:0) after 1m0s
[19:02:49.969] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:61:0)
[19:02:49.969] Timer tick, asking Buildbucket for the build status
[19:02:50.200] Build 8884743018169590032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:02:50.200] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:62:0) after 1m0s
[19:03:50.302] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:62:0)
[19:03:50.302] Timer tick, asking Buildbucket for the build status
[19:03:51.512] Build 8884743018169590032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:03:51.512] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:63:0) after 1m0s
[19:04:51.615] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:63:0)
[19:04:51.615] Timer tick, asking Buildbucket for the build status
[19:04:51.980] Build 8884743018169590032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:04:51.980] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:64:0) after 1m0s
[19:05:52.177] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:64:0)
[19:05:52.177] Timer tick, asking Buildbucket for the build status
[19:05:52.341] Build 8884743018169590032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:05:52.341] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:65:0) after 1m0s
[19:06:52.364] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:65:0)
[19:06:52.364] Timer tick, asking Buildbucket for the build status
[19:06:52.515] Build 8884743018169590032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:06:52.515] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:66:0) after 1m0s
[19:07:52.553] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:66:0)
[19:07:52.553] Timer tick, asking Buildbucket for the build status
[19:07:53.316] Build 8884743018169590032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:07:53.316] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:67:0) after 1m0s
[19:08:53.358] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:67:0)
[19:08:53.358] Timer tick, asking Buildbucket for the build status
[19:08:53.513] Build 8884743018169590032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:08:53.513] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:68:0) after 1m0s
[19:09:53.583] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:68:0)
[19:09:53.583] Timer tick, asking Buildbucket for the build status
[19:09:53.754] Build 8884743018169590032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:09:53.754] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:69:0) after 1m0s
[19:10:54.221] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:69:0)
[19:10:54.221] Timer tick, asking Buildbucket for the build status
[19:10:54.397] Build 8884743018169590032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:10:54.397] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:70:0) after 1m0s
[19:11:54.407] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:70:0)
[19:11:54.408] Timer tick, asking Buildbucket for the build status
[19:11:55.093] Build 8884743018169590032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:11:55.093] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:71:0) after 1m0s
[19:12:55.131] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:71:0)
[19:12:55.131] Timer tick, asking Buildbucket for the build status
[19:12:55.316] Build 8884743018169590032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:12:55.316] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:72:0) after 1m0s
[19:13:55.401] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:72:0)
[19:13:55.402] Timer tick, asking Buildbucket for the build status
[19:13:57.707] Build 8884743018169590032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:13:57.707] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:73:0) after 1m0s
[19:14:58.378] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:73:0)
[19:14:58.378] Timer tick, asking Buildbucket for the build status
[19:14:58.524] Build 8884743018169590032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:14:58.524] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:74:0) after 1m0s
[19:15:58.677] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:74:0)
[19:15:58.677] Timer tick, asking Buildbucket for the build status
[19:15:59.057] Build 8884743018169590032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:15:59.057] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9050173080465642432:75:0) after 1m0s
[19:16:55.997] Received PubSub notification, asking Buildbucket for the build status
[19:16:56.272] Build 8884743018169590032: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[19:16:56.272] Buildbucket build:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary_preference": "PROD",
"completed_ts": "1585250214553751",
"created_by": "project:emscripten-releases",
"created_ts": "1585245797909779",
"id": "8884743018169590032",
"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\": \"d9b23c6e8378b526329766dc7517ca638538f360\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@d9b23c6e8378b526329766dc7517ca638538f360\", \"title\": \"d9b23c6e8378b526329766dc7517ca638538f360\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/d9b23c6e8378b526329766dc7517ca638538f360\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"d9b23c6e8378b526329766dc7517ca638538f360\"}}",
"project": "emscripten-releases",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$recipe_engine/path\": {\"cache_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\cache\", \"temp_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\tmp\\\\rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"d9b23c6e8378b526329766dc7517ca638538f360\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@d9b23c6e8378b526329766dc7517ca638538f360\", \"title\": \"d9b23c6e8378b526329766dc7517ca638538f360\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/d9b23c6e8378b526329766dc7517ca638538f360\"}]}, \"bot_id\": \"luci-emscripten-releases-ci-win10-0-wwcn\", \"branch\": \"refs/heads/master\", \"buildername\": \"win\", \"got_revision\": \"d9b23c6e8378b526329766dc7517ca638538f360\", \"path_config\": \"generic\", \"recipe\": \"emscripten_releases\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"d9b23c6e8378b526329766dc7517ca638538f360\"}, \"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-wwcn\"], \"image\": [\"chrome-win10-1703-19112100-3085293b610\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"n1-standard-8\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.emscripten-releases.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"4895-ce889b2\"], \"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": "1585245838711746",
"status": "COMPLETED",
"status_changed_ts": "1585250215272020",
"tags": [
"builder:win",
"buildset:commit/git/d9b23c6e8378b526329766dc7517ca638538f360",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/d9b23c6e8378b526329766dc7517ca638538f360",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9050173080465642432",
"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/8884743018169590032/+/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:4b30d4d125fdef10",
"user_agent:luci-scheduler"
],
"updated_ts": "1585250215318781",
"url": "https://ci.chromium.org/b/8884743018169590032",
"utcnow_ts": "1585250216244800"
}
}
[19:16:56.272] Invocation finished in 1h13m39.935986369s with status SUCCEEDED