[22:49:28.755] New invocation is queued and will start shortly
[22:49:29.822] Starting the invocation (attempt 1)
[22:49:29.861] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[22:49:29.861] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[22:49:29.862] Buildbucket request:
{
"bucket": "luci.emscripten-releases.ci",
"client_operation_id": "9053778952567432240",
"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\":\"352a45f0aa2a7679af3ddbfaa5391282d01b8a2e\"},\"id\":\"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@352a45f0aa2a7679af3ddbfaa5391282d01b8a2e\",\"title\":\"352a45f0aa2a7679af3ddbfaa5391282d01b8a2e\",\"url\":\"https://chromium.googlesource.com/emscripten-releases/+/352a45f0aa2a7679af3ddbfaa5391282d01b8a2e\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/emscripten-releases\",\"revision\":\"352a45f0aa2a7679af3ddbfaa5391282d01b8a2e\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win",
"scheduler_invocation_id:9053778952567432240",
"scheduler_job_id:emscripten-releases/win",
"user_agent:luci-scheduler",
"buildset:commit/git/352a45f0aa2a7679af3ddbfaa5391282d01b8a2e",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/352a45f0aa2a7679af3ddbfaa5391282d01b8a2e",
"gitiles_ref:refs/heads/master"
]
}
[22:49:30.473] Buildbucket response:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary_preference": "PROD",
"created_by": "project:emscripten-releases",
"created_ts": "1581806969941003",
"id": "8888348890645860880",
"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\": \"352a45f0aa2a7679af3ddbfaa5391282d01b8a2e\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@352a45f0aa2a7679af3ddbfaa5391282d01b8a2e\", \"title\": \"352a45f0aa2a7679af3ddbfaa5391282d01b8a2e\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/352a45f0aa2a7679af3ddbfaa5391282d01b8a2e\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"352a45f0aa2a7679af3ddbfaa5391282d01b8a2e\"}}",
"project": "emscripten-releases",
"result_details_json": "{\"properties\": {}}",
"service_account": "emscripten-releases-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1581806970280641",
"tags": [
"builder:win",
"buildset:commit/git/352a45f0aa2a7679af3ddbfaa5391282d01b8a2e",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/352a45f0aa2a7679af3ddbfaa5391282d01b8a2e",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9053778952567432240",
"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/8888348890645860880/+/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": "1581806970280947",
"url": "https://ci.chromium.org/b/8888348890645860880",
"utcnow_ts": "1581806970462391"
}
}
[22:49:30.473] Task URL: https://ci.chromium.org/b/8888348890645860880
[22:49:30.473] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:2:0) after 1m0s
[22:50:05.463] Received PubSub notification, asking Buildbucket for the build status
[22:50:05.547] Build 8888348890645860880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:50:30.562] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:2:0)
[22:50:30.562] Timer tick, asking Buildbucket for the build status
[22:50:30.670] Build 8888348890645860880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:50:30.670] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:4:0) after 1m0s
[22:51:30.828] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:4:0)
[22:51:30.828] Timer tick, asking Buildbucket for the build status
[22:51:31.083] Build 8888348890645860880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:51:31.083] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:5:0) after 1m0s
[22:52:31.200] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:5:0)
[22:52:31.200] Timer tick, asking Buildbucket for the build status
[22:52:31.383] Build 8888348890645860880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:52:31.383] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:6:0) after 1m0s
[22:53:31.601] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:6:0)
[22:53:31.601] Timer tick, asking Buildbucket for the build status
[22:53:31.703] Build 8888348890645860880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:53:31.703] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:7:0) after 1m0s
[22:54:31.896] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:7:0)
[22:54:31.896] Timer tick, asking Buildbucket for the build status
[22:54:31.998] Build 8888348890645860880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:54:31.998] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:8:0) after 1m0s
[22:55:32.092] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:8:0)
[22:55:32.092] Timer tick, asking Buildbucket for the build status
[22:55:32.195] Build 8888348890645860880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:55:32.195] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:9:0) after 1m0s
[22:56:32.302] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:9:0)
[22:56:32.302] Timer tick, asking Buildbucket for the build status
[22:56:32.564] Build 8888348890645860880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:56:32.564] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:10:0) after 1m0s
[22:57:32.726] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:10:0)
[22:57:32.726] Timer tick, asking Buildbucket for the build status
[22:57:32.827] Build 8888348890645860880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:57:32.827] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:11:0) after 1m0s
[22:58:33.039] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:11:0)
[22:58:33.040] Timer tick, asking Buildbucket for the build status
[22:58:33.128] Build 8888348890645860880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:58:33.128] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:12:0) after 1m0s
[22:59:33.464] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:12:0)
[22:59:33.464] Timer tick, asking Buildbucket for the build status
[22:59:33.608] Build 8888348890645860880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:59:33.608] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:13:0) after 1m0s
[23:00:33.779] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:13:0)
[23:00:33.779] Timer tick, asking Buildbucket for the build status
[23:00:33.902] Build 8888348890645860880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:00:33.902] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:14:0) after 1m0s
[23:01:34.092] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:14:0)
[23:01:34.093] Timer tick, asking Buildbucket for the build status
[23:01:34.488] Build 8888348890645860880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:01:34.488] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:15:0) after 1m0s
[23:02:34.657] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:15:0)
[23:02:34.657] Timer tick, asking Buildbucket for the build status
[23:02:34.961] Build 8888348890645860880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:02:34.961] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:16:0) after 1m0s
[23:03:35.083] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:16:0)
[23:03:35.083] Timer tick, asking Buildbucket for the build status
[23:03:35.188] Build 8888348890645860880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:03:35.188] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:17:0) after 1m0s
[23:04:35.194] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:17:0)
[23:04:35.194] Timer tick, asking Buildbucket for the build status
[23:04:35.296] Build 8888348890645860880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:04:35.296] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:18:0) after 1m0s
[23:05:35.440] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:18:0)
[23:05:35.440] Timer tick, asking Buildbucket for the build status
[23:05:35.587] Build 8888348890645860880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:05:35.587] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:19:0) after 1m0s
[23:06:35.693] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:19:0)
[23:06:35.693] Timer tick, asking Buildbucket for the build status
[23:06:35.840] Build 8888348890645860880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:06:35.840] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:20:0) after 1m0s
[23:07:35.971] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:20:0)
[23:07:35.971] Timer tick, asking Buildbucket for the build status
[23:07:36.093] Build 8888348890645860880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:07:36.093] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:21:0) after 1m0s
[23:08:36.136] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:21:0)
[23:08:36.136] Timer tick, asking Buildbucket for the build status
[23:08:36.258] Build 8888348890645860880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:08:36.258] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:22:0) after 1m0s
[23:09:36.320] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:22:0)
[23:09:36.320] Timer tick, asking Buildbucket for the build status
[23:09:36.490] Build 8888348890645860880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:09:36.490] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:23:0) after 1m0s
[23:10:36.567] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:23:0)
[23:10:36.567] Timer tick, asking Buildbucket for the build status
[23:10:36.767] Build 8888348890645860880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:10:36.767] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:24:0) after 1m0s
[23:11:36.844] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:24:0)
[23:11:36.844] Timer tick, asking Buildbucket for the build status
[23:11:36.939] Build 8888348890645860880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:11:36.939] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:25:0) after 1m0s
[23:12:36.926] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:25:0)
[23:12:36.926] Timer tick, asking Buildbucket for the build status
[23:12:36.994] Build 8888348890645860880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:12:36.994] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:26:0) after 1m0s
[23:13:37.015] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:26:0)
[23:13:37.015] Timer tick, asking Buildbucket for the build status
[23:13:37.243] Build 8888348890645860880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:13:37.243] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:27:0) after 1m0s
[23:14:37.427] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:27:0)
[23:14:37.427] Timer tick, asking Buildbucket for the build status
[23:14:37.489] Build 8888348890645860880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:14:37.489] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:28:0) after 1m0s
[23:15:37.509] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:28:0)
[23:15:37.509] Timer tick, asking Buildbucket for the build status
[23:15:37.606] Build 8888348890645860880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:15:37.606] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:29:0) after 1m0s
[23:16:37.626] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:29:0)
[23:16:37.626] Timer tick, asking Buildbucket for the build status
[23:16:37.802] Build 8888348890645860880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:16:37.802] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:30:0) after 1m0s
[23:17:37.833] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:30:0)
[23:17:37.833] Timer tick, asking Buildbucket for the build status
[23:17:37.945] Build 8888348890645860880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:17:37.945] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:31:0) after 1m0s
[23:18:38.105] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:31:0)
[23:18:38.105] Timer tick, asking Buildbucket for the build status
[23:18:38.254] Build 8888348890645860880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:18:38.254] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:32:0) after 1m0s
[23:19:38.598] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:32:0)
[23:19:38.598] Timer tick, asking Buildbucket for the build status
[23:19:38.719] Build 8888348890645860880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:19:38.719] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:33:0) after 1m0s
[23:20:38.797] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:33:0)
[23:20:38.797] Timer tick, asking Buildbucket for the build status
[23:20:38.878] Build 8888348890645860880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:20:38.878] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:34:0) after 1m0s
[23:21:38.898] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:34:0)
[23:21:38.898] Timer tick, asking Buildbucket for the build status
[23:21:39.010] Build 8888348890645860880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:21:39.010] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:35:0) after 1m0s
[23:22:38.993] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:35:0)
[23:22:38.993] Timer tick, asking Buildbucket for the build status
[23:22:39.127] Build 8888348890645860880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:22:39.127] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:36:0) after 1m0s
[23:23:39.146] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:36:0)
[23:23:39.146] Timer tick, asking Buildbucket for the build status
[23:23:39.496] Build 8888348890645860880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:23:39.496] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:37:0) after 1m0s
[23:24:39.523] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:37:0)
[23:24:39.523] Timer tick, asking Buildbucket for the build status
[23:24:39.645] Build 8888348890645860880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:24:39.645] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:38:0) after 1m0s
[23:25:39.815] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:38:0)
[23:25:39.815] Timer tick, asking Buildbucket for the build status
[23:25:39.958] Build 8888348890645860880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:25:39.958] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:39:0) after 1m0s
[23:26:40.036] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:39:0)
[23:26:40.036] Timer tick, asking Buildbucket for the build status
[23:26:40.160] Build 8888348890645860880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:26:40.160] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:40:0) after 1m0s
[23:27:40.180] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:40:0)
[23:27:40.180] Timer tick, asking Buildbucket for the build status
[23:27:40.373] Build 8888348890645860880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:27:40.373] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:41:0) after 1m0s
[23:28:40.396] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:41:0)
[23:28:40.396] Timer tick, asking Buildbucket for the build status
[23:28:40.491] Build 8888348890645860880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:28:40.491] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:42:0) after 1m0s
[23:29:40.500] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:42:0)
[23:29:40.500] Timer tick, asking Buildbucket for the build status
[23:29:41.008] Build 8888348890645860880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:29:41.008] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:43:0) after 1m0s
[23:30:41.041] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:43:0)
[23:30:41.041] Timer tick, asking Buildbucket for the build status
[23:30:41.291] Build 8888348890645860880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:30:41.291] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:44:0) after 1m0s
[23:31:41.279] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:44:0)
[23:31:41.279] Timer tick, asking Buildbucket for the build status
[23:31:41.686] Build 8888348890645860880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:31:41.686] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:45:0) after 1m0s
[23:32:41.736] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:45:0)
[23:32:41.736] Timer tick, asking Buildbucket for the build status
[23:32:42.031] Build 8888348890645860880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:32:42.031] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:46:0) after 1m0s
[23:33:42.094] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:46:0)
[23:33:42.094] Timer tick, asking Buildbucket for the build status
[23:33:42.207] Build 8888348890645860880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:33:42.207] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:47:0) after 1m0s
[23:34:42.286] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:47:0)
[23:34:42.286] Timer tick, asking Buildbucket for the build status
[23:34:42.387] Build 8888348890645860880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:34:42.387] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:48:0) after 1m0s
[23:35:42.479] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:48:0)
[23:35:42.479] Timer tick, asking Buildbucket for the build status
[23:35:42.710] Build 8888348890645860880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:35:42.710] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:49:0) after 1m0s
[23:36:42.728] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:49:0)
[23:36:42.728] Timer tick, asking Buildbucket for the build status
[23:36:42.885] Build 8888348890645860880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:36:42.885] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:50:0) after 1m0s
[23:37:42.955] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:50:0)
[23:37:42.955] Timer tick, asking Buildbucket for the build status
[23:37:43.058] Build 8888348890645860880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:37:43.058] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:51:0) after 1m0s
[23:38:43.088] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:51:0)
[23:38:43.088] Timer tick, asking Buildbucket for the build status
[23:38:43.200] Build 8888348890645860880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:38:43.200] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:52:0) after 1m0s
[23:39:43.259] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:52:0)
[23:39:43.259] Timer tick, asking Buildbucket for the build status
[23:39:43.446] Build 8888348890645860880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:39:43.446] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:53:0) after 1m0s
[23:40:43.482] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:53:0)
[23:40:43.482] Timer tick, asking Buildbucket for the build status
[23:40:43.589] Build 8888348890645860880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:40:43.589] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:54:0) after 1m0s
[23:41:43.567] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:54:0)
[23:41:43.567] Timer tick, asking Buildbucket for the build status
[23:41:43.714] Build 8888348890645860880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:41:43.714] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:55:0) after 1m0s
[23:42:43.797] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:55:0)
[23:42:43.797] Timer tick, asking Buildbucket for the build status
[23:42:43.896] Build 8888348890645860880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:42:43.896] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:56:0) after 1m0s
[23:43:43.927] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:56:0)
[23:43:43.927] Timer tick, asking Buildbucket for the build status
[23:43:44.086] Build 8888348890645860880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:43:44.086] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:57:0) after 1m0s
[23:44:44.124] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:57:0)
[23:44:44.124] Timer tick, asking Buildbucket for the build status
[23:44:44.466] Build 8888348890645860880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:44:44.466] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:58:0) after 1m0s
[23:45:44.506] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:58:0)
[23:45:44.506] Timer tick, asking Buildbucket for the build status
[23:45:44.893] Build 8888348890645860880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:45:44.893] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:59:0) after 1m0s
[23:46:44.926] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:59:0)
[23:46:44.926] Timer tick, asking Buildbucket for the build status
[23:46:45.079] Build 8888348890645860880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:46:45.079] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:60:0) after 1m0s
[23:47:45.098] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:60:0)
[23:47:45.098] Timer tick, asking Buildbucket for the build status
[23:47:45.177] Build 8888348890645860880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:47:45.177] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:61:0) after 1m0s
[23:48:45.156] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:61:0)
[23:48:45.156] Timer tick, asking Buildbucket for the build status
[23:48:45.336] Build 8888348890645860880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:48:45.336] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:62:0) after 1m0s
[23:49:45.437] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:62:0)
[23:49:45.437] Timer tick, asking Buildbucket for the build status
[23:49:45.655] Build 8888348890645860880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:49:45.655] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:63:0) after 1m0s
[23:50:45.738] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:63:0)
[23:50:45.738] Timer tick, asking Buildbucket for the build status
[23:50:45.881] Build 8888348890645860880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:50:45.881] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:64:0) after 1m0s
[23:51:45.924] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:64:0)
[23:51:45.924] Timer tick, asking Buildbucket for the build status
[23:51:45.973] Build 8888348890645860880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:51:45.973] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:65:0) after 1m0s
[23:52:46.179] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:65:0)
[23:52:46.179] Timer tick, asking Buildbucket for the build status
[23:52:46.250] Build 8888348890645860880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:52:46.250] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:66:0) after 1m0s
[23:53:46.548] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:66:0)
[23:53:46.548] Timer tick, asking Buildbucket for the build status
[23:53:46.628] Build 8888348890645860880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:53:46.628] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:67:0) after 1m0s
[23:54:46.641] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:67:0)
[23:54:46.641] Timer tick, asking Buildbucket for the build status
[23:54:46.738] Build 8888348890645860880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:54:46.738] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:68:0) after 1m0s
[23:55:46.905] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:68:0)
[23:55:46.906] Timer tick, asking Buildbucket for the build status
[23:55:47.135] Build 8888348890645860880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:55:47.135] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:69:0) after 1m0s
[23:56:47.199] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:69:0)
[23:56:47.199] Timer tick, asking Buildbucket for the build status
[23:56:47.295] Build 8888348890645860880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:56:47.295] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:70:0) after 1m0s
[23:57:47.406] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:70:0)
[23:57:47.406] Timer tick, asking Buildbucket for the build status
[23:57:47.627] Build 8888348890645860880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:57:47.627] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:71:0) after 1m0s
[23:58:47.674] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:71:0)
[23:58:47.674] Timer tick, asking Buildbucket for the build status
[23:58:47.783] Build 8888348890645860880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:58:47.783] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:72:0) after 1m0s
[23:59:47.803] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:72:0)
[23:59:47.803] Timer tick, asking Buildbucket for the build status
[23:59:47.981] Build 8888348890645860880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:59:47.981] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:73:0) after 1m0s
[00:00:48.087] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:73:0)
[00:00:48.087] Timer tick, asking Buildbucket for the build status
[00:00:48.351] Build 8888348890645860880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:00:48.351] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:74:0) after 1m0s
[00:01:48.384] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:74:0)
[00:01:48.384] Timer tick, asking Buildbucket for the build status
[00:01:49.656] Build 8888348890645860880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:01:49.656] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:75:0) after 1m0s
[00:02:49.771] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:75:0)
[00:02:49.771] Timer tick, asking Buildbucket for the build status
[00:02:50.095] Build 8888348890645860880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:02:50.095] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:76:0) after 1m0s
[00:03:50.171] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:76:0)
[00:03:50.171] Timer tick, asking Buildbucket for the build status
[00:03:50.461] Build 8888348890645860880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:03:50.461] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:77:0) after 1m0s
[00:04:50.588] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:77:0)
[00:04:50.588] Timer tick, asking Buildbucket for the build status
[00:04:50.794] Build 8888348890645860880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:04:50.794] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053778952567432240:78:0) after 1m0s
[00:05:32.591] Received PubSub notification, asking Buildbucket for the build status
[00:05:32.655] Build 8888348890645860880: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[00:05:32.655] Buildbucket build:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary_preference": "PROD",
"completed_ts": "1581811531688933",
"created_by": "project:emscripten-releases",
"created_ts": "1581806969941003",
"id": "8888348890645860880",
"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\": \"352a45f0aa2a7679af3ddbfaa5391282d01b8a2e\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@352a45f0aa2a7679af3ddbfaa5391282d01b8a2e\", \"title\": \"352a45f0aa2a7679af3ddbfaa5391282d01b8a2e\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/352a45f0aa2a7679af3ddbfaa5391282d01b8a2e\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"352a45f0aa2a7679af3ddbfaa5391282d01b8a2e\"}}",
"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\": \"352a45f0aa2a7679af3ddbfaa5391282d01b8a2e\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@352a45f0aa2a7679af3ddbfaa5391282d01b8a2e\", \"title\": \"352a45f0aa2a7679af3ddbfaa5391282d01b8a2e\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/352a45f0aa2a7679af3ddbfaa5391282d01b8a2e\"}]}, \"bot_id\": \"luci-emscripten-releases-ci-win10-0-cque\", \"branch\": \"refs/heads/master\", \"buildername\": \"win\", \"got_revision\": \"352a45f0aa2a7679af3ddbfaa5391282d01b8a2e\", \"path_config\": \"generic\", \"recipe\": \"emscripten_releases\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"352a45f0aa2a7679af3ddbfaa5391282d01b8a2e\"}, \"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-cque\"], \"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\": [\"4815-21a95a1\"], \"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": "1581807004688880",
"status": "COMPLETED",
"status_changed_ts": "1581811532078507",
"tags": [
"builder:win",
"buildset:commit/git/352a45f0aa2a7679af3ddbfaa5391282d01b8a2e",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/352a45f0aa2a7679af3ddbfaa5391282d01b8a2e",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9053778952567432240",
"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/8888348890645860880/+/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:4a63dc6f9af38310",
"user_agent:luci-scheduler"
],
"updated_ts": "1581811532092091",
"url": "https://ci.chromium.org/b/8888348890645860880",
"utcnow_ts": "1581811532635063"
}
}
[00:05:32.655] Invocation finished in 1h16m3.931475973s with status SUCCEEDED