[22:52:34.778] New invocation is queued and will start shortly
[22:52:35.990] Starting the invocation (attempt 1)
[22:52:36.025] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[22:52:36.025] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[22:52:36.025] Buildbucket request:
{
"bucket": "luci.emscripten-releases.ci",
"client_operation_id": "9054775324134464496",
"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\":\"c362227a2ec43e381283129d2cdba8ae2b415240\"},\"id\":\"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@c362227a2ec43e381283129d2cdba8ae2b415240\",\"title\":\"c362227a2ec43e381283129d2cdba8ae2b415240\",\"url\":\"https://chromium.googlesource.com/emscripten-releases/+/c362227a2ec43e381283129d2cdba8ae2b415240\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/emscripten-releases\",\"revision\":\"c362227a2ec43e381283129d2cdba8ae2b415240\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win",
"scheduler_invocation_id:9054775324134464496",
"scheduler_job_id:emscripten-releases/win",
"user_agent:luci-scheduler",
"buildset:commit/git/c362227a2ec43e381283129d2cdba8ae2b415240",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/c362227a2ec43e381283129d2cdba8ae2b415240",
"gitiles_ref:refs/heads/master"
]
}
[22:52:37.928] Buildbucket response:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary_preference": "PROD",
"created_by": "project:emscripten-releases",
"created_ts": "1580856756180907",
"id": "8889345261990182320",
"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\": \"c362227a2ec43e381283129d2cdba8ae2b415240\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@c362227a2ec43e381283129d2cdba8ae2b415240\", \"title\": \"c362227a2ec43e381283129d2cdba8ae2b415240\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/c362227a2ec43e381283129d2cdba8ae2b415240\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"c362227a2ec43e381283129d2cdba8ae2b415240\"}}",
"project": "emscripten-releases",
"result_details_json": "{\"properties\": {}}",
"service_account": "emscripten-releases-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1580856756620243",
"tags": [
"builder:win",
"buildset:commit/git/c362227a2ec43e381283129d2cdba8ae2b415240",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/c362227a2ec43e381283129d2cdba8ae2b415240",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9054775324134464496",
"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/8889345261990182320/+/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": "1580856756620382",
"url": "https://ci.chromium.org/b/8889345261990182320",
"utcnow_ts": "1580856757903031"
}
}
[22:52:37.928] Task URL: https://ci.chromium.org/b/8889345261990182320
[22:52:37.928] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:2:0) after 1m0s
[22:53:38.044] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:2:0)
[22:53:38.044] Timer tick, asking Buildbucket for the build status
[22:53:38.536] Build 8889345261990182320: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:53:38.536] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:3:0) after 1m0s
[22:54:38.596] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:3:0)
[22:54:38.596] Timer tick, asking Buildbucket for the build status
[22:54:38.767] Build 8889345261990182320: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:54:38.767] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:4:0) after 1m0s
[22:55:38.970] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:4:0)
[22:55:38.970] Timer tick, asking Buildbucket for the build status
[22:55:39.126] Build 8889345261990182320: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:55:39.126] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:5:0) after 1m0s
[22:56:39.286] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:5:0)
[22:56:39.286] Timer tick, asking Buildbucket for the build status
[22:56:40.902] Build 8889345261990182320: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:56:40.902] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:6:0) after 1m0s
[22:57:41.086] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:6:0)
[22:57:41.086] Timer tick, asking Buildbucket for the build status
[22:57:41.476] Build 8889345261990182320: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:57:41.476] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:7:0) after 1m0s
[22:58:41.532] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:7:0)
[22:58:41.532] Timer tick, asking Buildbucket for the build status
[22:58:41.742] Build 8889345261990182320: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:58:41.742] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:8:0) after 1m0s
[22:59:41.766] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:8:0)
[22:59:41.766] Timer tick, asking Buildbucket for the build status
[22:59:45.031] Build 8889345261990182320: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:59:45.031] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:9:0) after 1m0s
[23:00:45.453] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:9:0)
[23:00:45.453] Timer tick, asking Buildbucket for the build status
[23:00:46.723] Build 8889345261990182320: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:00:46.723] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:10:0) after 1m0s
[23:01:47.045] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:10:0)
[23:01:47.045] Timer tick, asking Buildbucket for the build status
[23:01:47.237] Build 8889345261990182320: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:01:47.237] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:11:0) after 1m0s
[23:02:47.260] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:11:0)
[23:02:47.260] Timer tick, asking Buildbucket for the build status
[23:02:47.522] Build 8889345261990182320: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:02:47.522] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:12:0) after 1m0s
[23:03:47.558] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:12:0)
[23:03:47.558] Timer tick, asking Buildbucket for the build status
[23:03:47.893] Build 8889345261990182320: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:03:47.893] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:13:0) after 1m0s
[23:04:47.866] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:13:0)
[23:04:47.866] Timer tick, asking Buildbucket for the build status
[23:04:47.996] Build 8889345261990182320: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:04:47.996] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:14:0) after 1m0s
[23:05:48.126] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:14:0)
[23:05:48.126] Timer tick, asking Buildbucket for the build status
[23:05:48.319] Build 8889345261990182320: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:05:48.319] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:15:0) after 1m0s
[23:06:48.354] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:15:0)
[23:06:48.354] Timer tick, asking Buildbucket for the build status
[23:06:48.675] Build 8889345261990182320: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:06:48.675] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:16:0) after 1m0s
[23:07:48.705] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:16:0)
[23:07:48.705] Timer tick, asking Buildbucket for the build status
[23:07:48.994] Build 8889345261990182320: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:07:48.994] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:17:0) after 1m0s
[23:08:49.058] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:17:0)
[23:08:49.058] Timer tick, asking Buildbucket for the build status
[23:08:49.577] Build 8889345261990182320: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:08:49.577] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:18:0) after 1m0s
[23:09:49.596] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:18:0)
[23:09:49.596] Timer tick, asking Buildbucket for the build status
[23:09:49.867] Build 8889345261990182320: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:09:49.867] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:19:0) after 1m0s
[23:10:49.905] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:19:0)
[23:10:49.905] Timer tick, asking Buildbucket for the build status
[23:10:50.230] Build 8889345261990182320: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:10:50.230] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:20:0) after 1m0s
[23:11:50.353] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:20:0)
[23:11:50.353] Timer tick, asking Buildbucket for the build status
[23:11:50.486] Build 8889345261990182320: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:11:50.486] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:21:0) after 1m0s
[23:12:50.719] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:21:0)
[23:12:50.719] Timer tick, asking Buildbucket for the build status
[23:12:51.176] Build 8889345261990182320: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:12:51.176] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:22:0) after 1m0s
[23:13:51.247] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:22:0)
[23:13:51.247] Timer tick, asking Buildbucket for the build status
[23:13:51.347] Build 8889345261990182320: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:13:51.347] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:23:0) after 1m0s
[23:14:51.409] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:23:0)
[23:14:51.409] Timer tick, asking Buildbucket for the build status
[23:14:52.900] Build 8889345261990182320: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:14:52.900] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:24:0) after 1m0s
[23:15:52.928] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:24:0)
[23:15:52.928] Timer tick, asking Buildbucket for the build status
[23:15:58.224] Build 8889345261990182320: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:15:58.224] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:25:0) after 1m0s
[23:16:58.247] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:25:0)
[23:16:58.247] Timer tick, asking Buildbucket for the build status
[23:16:58.469] Build 8889345261990182320: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:16:58.469] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:26:0) after 1m0s
[23:17:58.499] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:26:0)
[23:17:58.499] Timer tick, asking Buildbucket for the build status
[23:17:58.672] Build 8889345261990182320: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:17:58.672] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:27:0) after 1m0s
[23:18:58.795] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:27:0)
[23:18:58.795] Timer tick, asking Buildbucket for the build status
[23:18:58.880] Build 8889345261990182320: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:18:58.880] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:28:0) after 1m0s
[23:19:59.065] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:28:0)
[23:19:59.065] Timer tick, asking Buildbucket for the build status
[23:19:59.220] Build 8889345261990182320: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:19:59.220] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:29:0) after 1m0s
[23:20:59.243] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:29:0)
[23:20:59.243] Timer tick, asking Buildbucket for the build status
[23:20:59.438] Build 8889345261990182320: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:20:59.438] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:30:0) after 1m0s
[23:21:59.627] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:30:0)
[23:21:59.627] Timer tick, asking Buildbucket for the build status
[23:22:00.000] Build 8889345261990182320: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:22:00.000] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:31:0) after 1m0s
[23:23:00.108] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:31:0)
[23:23:00.108] Timer tick, asking Buildbucket for the build status
[23:23:00.302] Build 8889345261990182320: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:23:00.302] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:32:0) after 1m0s
[23:24:00.673] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:32:0)
[23:24:00.673] Timer tick, asking Buildbucket for the build status
[23:24:01.216] Build 8889345261990182320: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:24:01.216] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:33:0) after 1m0s
[23:25:01.489] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:33:0)
[23:25:01.489] Timer tick, asking Buildbucket for the build status
[23:25:01.609] Build 8889345261990182320: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:25:01.609] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:34:0) after 1m0s
[23:26:01.974] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:34:0)
[23:26:01.974] Timer tick, asking Buildbucket for the build status
[23:26:02.132] Build 8889345261990182320: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:26:02.133] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:35:0) after 1m0s
[23:27:02.258] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:35:0)
[23:27:02.258] Timer tick, asking Buildbucket for the build status
[23:27:02.524] Build 8889345261990182320: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:27:02.524] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:36:0) after 1m0s
[23:28:02.538] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:36:0)
[23:28:02.538] Timer tick, asking Buildbucket for the build status
[23:28:03.255] Build 8889345261990182320: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:28:03.255] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:37:0) after 1m0s
[23:29:03.388] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:37:0)
[23:29:03.388] Timer tick, asking Buildbucket for the build status
[23:29:03.727] Build 8889345261990182320: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:29:03.727] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:38:0) after 1m0s
[23:30:03.799] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:38:0)
[23:30:03.799] Timer tick, asking Buildbucket for the build status
[23:30:03.949] Build 8889345261990182320: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:30:03.949] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:39:0) after 1m0s
[23:31:03.966] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:39:0)
[23:31:03.966] Timer tick, asking Buildbucket for the build status
[23:31:04.765] Build 8889345261990182320: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:31:04.765] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:40:0) after 1m0s
[23:32:04.853] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:40:0)
[23:32:04.853] Timer tick, asking Buildbucket for the build status
[23:32:05.907] Build 8889345261990182320: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:32:05.907] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:41:0) after 1m0s
[23:33:06.009] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:41:0)
[23:33:06.010] Timer tick, asking Buildbucket for the build status
[23:33:06.304] Build 8889345261990182320: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:33:06.304] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:42:0) after 1m0s
[23:34:06.449] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:42:0)
[23:34:06.449] Timer tick, asking Buildbucket for the build status
[23:34:06.740] Build 8889345261990182320: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:34:06.740] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:43:0) after 1m0s
[23:35:06.811] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:43:0)
[23:35:06.811] Timer tick, asking Buildbucket for the build status
[23:35:07.142] Build 8889345261990182320: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:35:07.143] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:44:0) after 1m0s
[23:36:07.187] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:44:0)
[23:36:07.187] Timer tick, asking Buildbucket for the build status
[23:36:07.558] Build 8889345261990182320: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:36:07.558] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:45:0) after 1m0s
[23:37:07.578] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:45:0)
[23:37:07.578] Timer tick, asking Buildbucket for the build status
[23:37:07.920] Build 8889345261990182320: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:37:07.920] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:46:0) after 1m0s
[23:38:07.945] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:46:0)
[23:38:07.945] Timer tick, asking Buildbucket for the build status
[23:38:08.189] Build 8889345261990182320: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:38:08.189] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:47:0) after 1m0s
[23:39:08.366] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:47:0)
[23:39:08.366] Timer tick, asking Buildbucket for the build status
[23:39:08.615] Build 8889345261990182320: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:39:08.615] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:48:0) after 1m0s
[23:40:08.704] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:48:0)
[23:40:08.704] Timer tick, asking Buildbucket for the build status
[23:40:08.954] Build 8889345261990182320: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:40:08.954] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:49:0) after 1m0s
[23:41:09.015] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:49:0)
[23:41:09.015] Timer tick, asking Buildbucket for the build status
[23:41:09.231] Build 8889345261990182320: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:41:09.231] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:50:0) after 1m0s
[23:41:09.970] Received PubSub notification, asking Buildbucket for the build status
[23:41:10.054] Build 8889345261990182320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:42:09.380] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:50:0)
[23:42:09.380] Timer tick, asking Buildbucket for the build status
[23:42:09.605] Build 8889345261990182320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:42:09.605] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:52:0) after 1m0s
[23:43:09.701] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:52:0)
[23:43:09.701] Timer tick, asking Buildbucket for the build status
[23:43:09.882] Build 8889345261990182320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:43:09.882] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:53:0) after 1m0s
[23:44:09.992] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:53:0)
[23:44:09.992] Timer tick, asking Buildbucket for the build status
[23:44:10.299] Build 8889345261990182320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:44:10.299] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:54:0) after 1m0s
[23:45:10.324] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:54:0)
[23:45:10.324] Timer tick, asking Buildbucket for the build status
[23:45:10.777] Build 8889345261990182320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:45:10.777] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:55:0) after 1m0s
[23:46:10.878] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:55:0)
[23:46:10.878] Timer tick, asking Buildbucket for the build status
[23:46:11.343] Build 8889345261990182320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:46:11.343] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:56:0) after 1m0s
[23:47:11.367] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:56:0)
[23:47:11.367] Timer tick, asking Buildbucket for the build status
[23:47:12.173] Build 8889345261990182320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:47:12.173] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:57:0) after 1m0s
[23:48:12.221] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:57:0)
[23:48:12.221] Timer tick, asking Buildbucket for the build status
[23:48:12.809] Build 8889345261990182320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:48:12.809] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:58:0) after 1m0s
[23:49:12.982] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:58:0)
[23:49:12.982] Timer tick, asking Buildbucket for the build status
[23:49:14.169] Build 8889345261990182320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:49:14.169] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:59:0) after 1m0s
[23:50:15.556] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:59:0)
[23:50:15.556] Timer tick, asking Buildbucket for the build status
[23:50:15.649] Build 8889345261990182320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:50:15.649] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:60:0) after 1m0s
[23:51:15.797] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:60:0)
[23:51:15.797] Timer tick, asking Buildbucket for the build status
[23:51:15.942] Build 8889345261990182320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:51:15.942] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:61:0) after 1m0s
[23:52:16.078] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:61:0)
[23:52:16.078] Timer tick, asking Buildbucket for the build status
[23:52:16.592] Build 8889345261990182320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:52:16.592] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:62:0) after 1m0s
[23:53:16.661] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:62:0)
[23:53:16.661] Timer tick, asking Buildbucket for the build status
[23:53:17.555] Build 8889345261990182320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:53:17.555] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:63:0) after 1m0s
[23:54:17.643] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:63:0)
[23:54:17.643] Timer tick, asking Buildbucket for the build status
[23:54:17.805] Build 8889345261990182320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:54:17.805] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:64:0) after 1m0s
[23:55:17.944] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:64:0)
[23:55:17.944] Timer tick, asking Buildbucket for the build status
[23:55:18.293] Build 8889345261990182320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:55:18.293] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:65:0) after 1m0s
[23:56:18.469] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:65:0)
[23:56:18.469] Timer tick, asking Buildbucket for the build status
[23:56:18.655] Build 8889345261990182320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:56:18.655] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:66:0) after 1m0s
[23:57:18.784] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:66:0)
[23:57:18.784] Timer tick, asking Buildbucket for the build status
[23:57:20.172] Build 8889345261990182320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:57:20.172] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:67:0) after 1m0s
[23:58:20.490] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:67:0)
[23:58:20.490] Timer tick, asking Buildbucket for the build status
[23:58:21.551] Build 8889345261990182320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:58:21.551] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:68:0) after 1m0s
[23:59:21.612] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:68:0)
[23:59:21.612] Timer tick, asking Buildbucket for the build status
[23:59:21.781] Build 8889345261990182320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:59:21.781] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:69:0) after 1m0s
[00:00:21.909] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:69:0)
[00:00:21.909] Timer tick, asking Buildbucket for the build status
[00:00:22.098] Build 8889345261990182320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:00:22.098] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:70:0) after 1m0s
[00:01:22.118] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:70:0)
[00:01:22.118] Timer tick, asking Buildbucket for the build status
[00:01:22.453] Build 8889345261990182320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:01:22.453] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:71:0) after 1m0s
[00:02:22.553] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:71:0)
[00:02:22.553] Timer tick, asking Buildbucket for the build status
[00:02:22.859] Build 8889345261990182320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:02:22.859] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:72:0) after 1m0s
[00:03:22.894] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:72:0)
[00:03:22.894] Timer tick, asking Buildbucket for the build status
[00:03:23.272] Build 8889345261990182320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:03:23.272] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:73:0) after 1m0s
[00:04:23.346] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:73:0)
[00:04:23.346] Timer tick, asking Buildbucket for the build status
[00:04:23.514] Build 8889345261990182320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:04:23.514] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:74:0) after 1m0s
[00:05:23.857] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:74:0)
[00:05:23.857] Timer tick, asking Buildbucket for the build status
[00:05:23.958] Build 8889345261990182320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:05:23.958] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:75:0) after 1m0s
[00:06:24.085] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:75:0)
[00:06:24.085] Timer tick, asking Buildbucket for the build status
[00:06:24.314] Build 8889345261990182320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:06:24.314] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:76:0) after 1m0s
[00:07:24.482] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:76:0)
[00:07:24.482] Timer tick, asking Buildbucket for the build status
[00:07:25.238] Build 8889345261990182320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:07:25.238] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:77:0) after 1m0s
[00:08:25.371] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:77:0)
[00:08:25.371] Timer tick, asking Buildbucket for the build status
[00:08:25.533] Build 8889345261990182320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:08:25.533] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:78:0) after 1m0s
[00:09:25.759] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:78:0)
[00:09:25.759] Timer tick, asking Buildbucket for the build status
[00:09:25.882] Build 8889345261990182320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:09:25.882] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:79:0) after 1m0s
[00:10:27.515] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:79:0)
[00:10:27.515] Timer tick, asking Buildbucket for the build status
[00:10:27.648] Build 8889345261990182320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:10:27.648] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:80:0) after 1m0s
[00:11:27.742] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:80:0)
[00:11:27.742] Timer tick, asking Buildbucket for the build status
[00:11:28.323] Build 8889345261990182320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:11:28.323] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:81:0) after 1m0s
[00:12:28.347] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:81:0)
[00:12:28.347] Timer tick, asking Buildbucket for the build status
[00:12:29.068] Build 8889345261990182320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:12:29.068] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:82:0) after 1m0s
[00:13:29.074] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:82:0)
[00:13:29.074] Timer tick, asking Buildbucket for the build status
[00:13:29.415] Build 8889345261990182320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:13:29.415] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:83:0) after 1m0s
[00:14:29.477] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:83:0)
[00:14:29.477] Timer tick, asking Buildbucket for the build status
[00:14:29.880] Build 8889345261990182320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:14:29.880] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:84:0) after 1m0s
[00:15:29.882] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:84:0)
[00:15:29.882] Timer tick, asking Buildbucket for the build status
[00:15:30.116] Build 8889345261990182320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:15:30.116] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:85:0) after 1m0s
[00:16:30.207] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:85:0)
[00:16:30.207] Timer tick, asking Buildbucket for the build status
[00:16:30.302] Build 8889345261990182320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:16:30.302] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:86:0) after 1m0s
[00:17:30.357] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:86:0)
[00:17:30.357] Timer tick, asking Buildbucket for the build status
[00:17:30.743] Build 8889345261990182320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:17:30.743] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:87:0) after 1m0s
[00:18:31.187] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:87:0)
[00:18:31.187] Timer tick, asking Buildbucket for the build status
[00:18:31.386] Build 8889345261990182320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:18:31.386] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:88:0) after 1m0s
[00:19:31.584] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:88:0)
[00:19:31.584] Timer tick, asking Buildbucket for the build status
[00:19:31.798] Build 8889345261990182320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:19:31.798] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:89:0) after 1m0s
[00:20:32.020] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:89:0)
[00:20:32.020] Timer tick, asking Buildbucket for the build status
[00:20:32.679] Build 8889345261990182320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:20:32.679] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:90:0) after 1m0s
[00:21:32.800] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:90:0)
[00:21:32.800] Timer tick, asking Buildbucket for the build status
[00:21:33.018] Build 8889345261990182320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:21:33.018] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:91:0) after 1m0s
[00:22:33.088] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:91:0)
[00:22:33.088] Timer tick, asking Buildbucket for the build status
[00:22:33.242] Build 8889345261990182320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:22:33.243] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:92:0) after 1m0s
[00:23:33.505] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:92:0)
[00:23:33.505] Timer tick, asking Buildbucket for the build status
[00:23:33.677] Build 8889345261990182320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:23:33.678] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:93:0) after 1m0s
[00:24:33.952] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:93:0)
[00:24:33.952] Timer tick, asking Buildbucket for the build status
[00:24:34.270] Build 8889345261990182320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:24:34.270] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:94:0) after 1m0s
[00:25:34.394] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:94:0)
[00:25:34.394] Timer tick, asking Buildbucket for the build status
[00:25:34.747] Build 8889345261990182320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:25:34.748] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:95:0) after 1m0s
[00:26:35.053] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:95:0)
[00:26:35.053] Timer tick, asking Buildbucket for the build status
[00:26:35.427] Build 8889345261990182320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:26:35.427] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:96:0) after 1m0s
[00:27:35.851] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:96:0)
[00:27:35.851] Timer tick, asking Buildbucket for the build status
[00:27:36.355] Build 8889345261990182320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:27:36.355] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:97:0) after 1m0s
[00:28:36.559] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:97:0)
[00:28:36.559] Timer tick, asking Buildbucket for the build status
[00:28:36.838] Build 8889345261990182320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:28:36.838] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:98:0) after 1m0s
[00:29:36.883] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:98:0)
[00:29:36.883] Timer tick, asking Buildbucket for the build status
[00:29:36.996] Build 8889345261990182320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:29:36.996] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:99:0) after 1m0s
[00:30:37.103] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:99:0)
[00:30:37.103] Timer tick, asking Buildbucket for the build status
[00:30:37.287] Build 8889345261990182320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:30:37.287] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:100:0) after 1m0s
[00:31:37.314] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:100:0)
[00:31:37.314] Timer tick, asking Buildbucket for the build status
[00:31:39.975] Build 8889345261990182320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:31:39.975] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:101:0) after 1m0s
[00:32:40.138] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:101:0)
[00:32:40.138] Timer tick, asking Buildbucket for the build status
[00:32:40.389] Build 8889345261990182320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:32:40.389] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:102:0) after 1m0s
[00:33:40.541] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:102:0)
[00:33:40.541] Timer tick, asking Buildbucket for the build status
[00:33:40.718] Build 8889345261990182320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:33:40.718] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:103:0) after 1m0s
[00:34:40.853] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:103:0)
[00:34:40.853] Timer tick, asking Buildbucket for the build status
[00:34:41.128] Build 8889345261990182320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:34:41.128] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:104:0) after 1m0s
[00:35:41.187] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:104:0)
[00:35:41.187] Timer tick, asking Buildbucket for the build status
[00:35:41.945] Build 8889345261990182320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:35:41.945] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:105:0) after 1m0s
[00:36:42.043] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:105:0)
[00:36:42.043] Timer tick, asking Buildbucket for the build status
[00:36:42.262] Build 8889345261990182320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:36:42.262] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:106:0) after 1m0s
[00:37:42.418] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:106:0)
[00:37:42.418] Timer tick, asking Buildbucket for the build status
[00:37:42.622] Build 8889345261990182320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:37:42.622] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:107:0) after 1m0s
[00:38:42.775] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:107:0)
[00:38:42.775] Timer tick, asking Buildbucket for the build status
[00:38:42.984] Build 8889345261990182320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:38:42.984] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:108:0) after 1m0s
[00:39:43.131] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:108:0)
[00:39:43.132] Timer tick, asking Buildbucket for the build status
[00:39:43.576] Build 8889345261990182320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:39:43.576] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:109:0) after 1m0s
[00:40:43.697] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:109:0)
[00:40:43.697] Timer tick, asking Buildbucket for the build status
[00:40:43.943] Build 8889345261990182320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:40:43.943] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:110:0) after 1m0s
[00:41:44.521] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:110:0)
[00:41:44.521] Timer tick, asking Buildbucket for the build status
[00:41:44.833] Build 8889345261990182320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:41:44.833] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:111:0) after 1m0s
[00:42:45.226] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:111:0)
[00:42:45.226] Timer tick, asking Buildbucket for the build status
[00:42:45.412] Build 8889345261990182320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:42:45.412] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:112:0) after 1m0s
[00:43:45.556] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:112:0)
[00:43:45.556] Timer tick, asking Buildbucket for the build status
[00:43:45.789] Build 8889345261990182320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:43:45.789] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:113:0) after 1m0s
[00:44:46.202] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:113:0)
[00:44:46.202] Timer tick, asking Buildbucket for the build status
[00:44:46.559] Build 8889345261990182320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:44:46.559] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:114:0) after 1m0s
[00:45:46.807] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:114:0)
[00:45:46.807] Timer tick, asking Buildbucket for the build status
[00:45:47.014] Build 8889345261990182320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:45:47.014] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:115:0) after 1m0s
[00:46:47.042] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:115:0)
[00:46:47.042] Timer tick, asking Buildbucket for the build status
[00:46:47.222] Build 8889345261990182320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:46:47.222] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:116:0) after 1m0s
[00:47:47.301] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:116:0)
[00:47:47.301] Timer tick, asking Buildbucket for the build status
[00:47:47.737] Build 8889345261990182320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:47:47.737] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:117:0) after 1m0s
[00:48:47.799] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:117:0)
[00:48:47.799] Timer tick, asking Buildbucket for the build status
[00:48:48.057] Build 8889345261990182320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:48:48.057] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:118:0) after 1m0s
[00:49:48.195] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:118:0)
[00:49:48.195] Timer tick, asking Buildbucket for the build status
[00:49:48.363] Build 8889345261990182320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:49:48.363] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:119:0) after 1m0s
[00:50:48.429] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:119:0)
[00:50:48.429] Timer tick, asking Buildbucket for the build status
[00:50:48.622] Build 8889345261990182320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:50:48.622] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:120:0) after 1m0s
[00:51:48.813] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:120:0)
[00:51:48.813] Timer tick, asking Buildbucket for the build status
[00:51:49.004] Build 8889345261990182320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:51:49.005] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:121:0) after 1m0s
[00:52:49.129] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:121:0)
[00:52:49.129] Timer tick, asking Buildbucket for the build status
[00:52:49.471] Build 8889345261990182320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:52:49.471] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:122:0) after 1m0s
[00:53:49.486] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:122:0)
[00:53:49.486] Timer tick, asking Buildbucket for the build status
[00:53:49.767] Build 8889345261990182320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:53:49.767] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:123:0) after 1m0s
[00:54:49.949] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:123:0)
[00:54:49.949] Timer tick, asking Buildbucket for the build status
[00:54:50.265] Build 8889345261990182320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:54:50.265] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:124:0) after 1m0s
[00:55:50.484] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:124:0)
[00:55:50.484] Timer tick, asking Buildbucket for the build status
[00:55:50.842] Build 8889345261990182320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:55:50.842] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:125:0) after 1m0s
[00:56:50.915] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:125:0)
[00:56:50.915] Timer tick, asking Buildbucket for the build status
[00:56:51.472] Build 8889345261990182320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:56:51.472] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:126:0) after 1m0s
[00:57:51.811] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:126:0)
[00:57:51.811] Timer tick, asking Buildbucket for the build status
[00:57:51.997] Build 8889345261990182320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:57:51.997] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:127:0) after 1m0s
[00:58:52.148] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:127:0)
[00:58:52.148] Timer tick, asking Buildbucket for the build status
[00:58:52.305] Build 8889345261990182320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:58:52.305] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:128:0) after 1m0s
[00:59:52.381] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:128:0)
[00:59:52.381] Timer tick, asking Buildbucket for the build status
[00:59:52.583] Build 8889345261990182320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:59:52.583] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:129:0) after 1m0s
[01:00:52.698] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:129:0)
[01:00:52.698] Timer tick, asking Buildbucket for the build status
[01:00:52.864] Build 8889345261990182320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:00:52.864] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:130:0) after 1m0s
[01:01:53.012] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:130:0)
[01:01:53.012] Timer tick, asking Buildbucket for the build status
[01:01:53.221] Build 8889345261990182320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:01:53.221] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9054775324134464496:131:0) after 1m0s
[01:02:13.285] Received PubSub notification, asking Buildbucket for the build status
[01:02:13.434] Build 8889345261990182320: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[01:02:13.434] Buildbucket build:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary_preference": "PROD",
"completed_ts": "1580864532544396",
"created_by": "project:emscripten-releases",
"created_ts": "1580856756180907",
"id": "8889345261990182320",
"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\": \"c362227a2ec43e381283129d2cdba8ae2b415240\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@c362227a2ec43e381283129d2cdba8ae2b415240\", \"title\": \"c362227a2ec43e381283129d2cdba8ae2b415240\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/c362227a2ec43e381283129d2cdba8ae2b415240\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"c362227a2ec43e381283129d2cdba8ae2b415240\"}}",
"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\": \"c362227a2ec43e381283129d2cdba8ae2b415240\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@c362227a2ec43e381283129d2cdba8ae2b415240\", \"title\": \"c362227a2ec43e381283129d2cdba8ae2b415240\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/c362227a2ec43e381283129d2cdba8ae2b415240\"}]}, \"bot_id\": \"luci-emscripten-releases-ci-win10-0-df7q\", \"branch\": \"refs/heads/master\", \"buildername\": \"win\", \"got_revision\": \"c362227a2ec43e381283129d2cdba8ae2b415240\", \"path_config\": \"generic\", \"recipe\": \"emscripten_releases\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"c362227a2ec43e381283129d2cdba8ae2b415240\"}, \"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-df7q\"], \"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\": [\"4809-bb2013c\"], \"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": "1580859669345773",
"status": "COMPLETED",
"status_changed_ts": "1580864532879720",
"tags": [
"builder:win",
"buildset:commit/git/c362227a2ec43e381283129d2cdba8ae2b415240",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/c362227a2ec43e381283129d2cdba8ae2b415240",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9054775324134464496",
"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/8889345261990182320/+/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:4a2b3954f4456b10",
"user_agent:luci-scheduler"
],
"updated_ts": "1580864532889460",
"url": "https://ci.chromium.org/b/8889345261990182320",
"utcnow_ts": "1580864533424188"
}
}
[01:02:13.434] Invocation finished in 2h9m38.683570543s with status SUCCEEDED