[20:17:28.773] New invocation is queued and will start shortly
[20:17:31.079] Starting the invocation (attempt 1)
[20:17:31.115] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[20:17:31.115] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[20:17:31.115] Buildbucket request:
{
"bucket": "luci.emscripten-releases.ci",
"client_operation_id": "9045091206771604992",
"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\":\"24ebfbdb547633bed4e2eb03f1c20fcfd797c2ed\"},\"id\":\"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@24ebfbdb547633bed4e2eb03f1c20fcfd797c2ed\",\"title\":\"24ebfbdb547633bed4e2eb03f1c20fcfd797c2ed\",\"url\":\"https://chromium.googlesource.com/emscripten-releases/+/24ebfbdb547633bed4e2eb03f1c20fcfd797c2ed\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/emscripten-releases\",\"revision\":\"24ebfbdb547633bed4e2eb03f1c20fcfd797c2ed\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win",
"scheduler_invocation_id:9045091206771604992",
"scheduler_job_id:emscripten-releases/win",
"user_agent:luci-scheduler",
"buildset:commit/git/24ebfbdb547633bed4e2eb03f1c20fcfd797c2ed",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/24ebfbdb547633bed4e2eb03f1c20fcfd797c2ed",
"gitiles_ref:refs/heads/master"
]
}
[20:17:31.758] Buildbucket response:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary_preference": "PROD",
"created_by": "project:emscripten-releases",
"created_ts": "1590092251259836",
"id": "8879661143502572496",
"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\": \"24ebfbdb547633bed4e2eb03f1c20fcfd797c2ed\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@24ebfbdb547633bed4e2eb03f1c20fcfd797c2ed\", \"title\": \"24ebfbdb547633bed4e2eb03f1c20fcfd797c2ed\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/24ebfbdb547633bed4e2eb03f1c20fcfd797c2ed\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"24ebfbdb547633bed4e2eb03f1c20fcfd797c2ed\"}}",
"project": "emscripten-releases",
"result_details_json": "{\"properties\": {}}",
"service_account": "emscripten-releases-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1590092251557167",
"tags": [
"builder:win",
"buildset:commit/git/24ebfbdb547633bed4e2eb03f1c20fcfd797c2ed",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/24ebfbdb547633bed4e2eb03f1c20fcfd797c2ed",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9045091206771604992",
"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/8879661143502572496/+/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": "1590092251557320",
"url": "https://ci.chromium.org/b/8879661143502572496",
"utcnow_ts": "1590092251738322"
}
}
[20:17:31.758] Task URL: https://ci.chromium.org/b/8879661143502572496
[20:17:31.758] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:2:0) after 1m0s
[20:18:31.815] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:2:0)
[20:18:31.815] Timer tick, asking Buildbucket for the build status
[20:18:31.955] Build 8879661143502572496: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:18:31.955] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:3:0) after 1m0s
[20:19:32.028] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:3:0)
[20:19:32.028] Timer tick, asking Buildbucket for the build status
[20:19:32.394] Build 8879661143502572496: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:19:32.394] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:4:0) after 1m0s
[20:20:32.517] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:4:0)
[20:20:32.517] Timer tick, asking Buildbucket for the build status
[20:20:33.801] Build 8879661143502572496: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:20:33.801] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:5:0) after 1m0s
[20:21:33.923] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:5:0)
[20:21:33.923] Timer tick, asking Buildbucket for the build status
[20:21:34.143] Build 8879661143502572496: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:21:34.143] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:6:0) after 1m0s
[20:22:34.135] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:6:0)
[20:22:34.135] Timer tick, asking Buildbucket for the build status
[20:22:34.301] Build 8879661143502572496: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:22:34.301] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:7:0) after 1m0s
[20:23:34.324] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:7:0)
[20:23:34.324] Timer tick, asking Buildbucket for the build status
[20:23:34.553] Build 8879661143502572496: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:23:34.553] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:8:0) after 1m0s
[20:24:34.617] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:8:0)
[20:24:34.617] Timer tick, asking Buildbucket for the build status
[20:24:35.253] Build 8879661143502572496: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:24:35.253] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:9:0) after 1m0s
[20:25:35.320] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:9:0)
[20:25:35.320] Timer tick, asking Buildbucket for the build status
[20:25:35.561] Build 8879661143502572496: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:25:35.561] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:10:0) after 1m0s
[20:26:35.619] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:10:0)
[20:26:35.619] Timer tick, asking Buildbucket for the build status
[20:26:35.939] Build 8879661143502572496: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:26:35.939] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:11:0) after 1m0s
[20:27:35.981] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:11:0)
[20:27:35.981] Timer tick, asking Buildbucket for the build status
[20:27:36.395] Build 8879661143502572496: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:27:36.395] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:12:0) after 1m0s
[20:28:36.463] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:12:0)
[20:28:36.463] Timer tick, asking Buildbucket for the build status
[20:28:36.949] Build 8879661143502572496: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:28:36.949] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:13:0) after 1m0s
[20:29:37.089] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:13:0)
[20:29:37.089] Timer tick, asking Buildbucket for the build status
[20:29:37.259] Build 8879661143502572496: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:29:37.259] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:14:0) after 1m0s
[20:30:37.305] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:14:0)
[20:30:37.305] Timer tick, asking Buildbucket for the build status
[20:30:37.510] Build 8879661143502572496: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:30:37.510] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:15:0) after 1m0s
[20:31:37.568] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:15:0)
[20:31:37.568] Timer tick, asking Buildbucket for the build status
[20:31:38.027] Build 8879661143502572496: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:31:38.027] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:16:0) after 1m0s
[20:32:38.097] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:16:0)
[20:32:38.097] Timer tick, asking Buildbucket for the build status
[20:32:38.251] Build 8879661143502572496: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:32:38.252] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:17:0) after 1m0s
[20:33:38.291] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:17:0)
[20:33:38.291] Timer tick, asking Buildbucket for the build status
[20:33:38.565] Build 8879661143502572496: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:33:38.565] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:18:0) after 1m0s
[20:34:38.629] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:18:0)
[20:34:38.629] Timer tick, asking Buildbucket for the build status
[20:34:38.768] Build 8879661143502572496: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:34:38.768] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:19:0) after 1m0s
[20:35:38.795] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:19:0)
[20:35:38.795] Timer tick, asking Buildbucket for the build status
[20:35:39.130] Build 8879661143502572496: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:35:39.130] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:20:0) after 1m0s
[20:36:39.154] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:20:0)
[20:36:39.154] Timer tick, asking Buildbucket for the build status
[20:36:39.360] Build 8879661143502572496: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:36:39.360] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:21:0) after 1m0s
[20:37:39.414] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:21:0)
[20:37:39.414] Timer tick, asking Buildbucket for the build status
[20:37:39.589] Build 8879661143502572496: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:37:39.589] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:22:0) after 1m0s
[20:38:39.613] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:22:0)
[20:38:39.613] Timer tick, asking Buildbucket for the build status
[20:38:39.816] Build 8879661143502572496: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:38:39.816] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:23:0) after 1m0s
[20:39:39.861] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:23:0)
[20:39:39.861] Timer tick, asking Buildbucket for the build status
[20:39:40.073] Build 8879661143502572496: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:39:40.073] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:24:0) after 1m0s
[20:40:40.098] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:24:0)
[20:40:40.098] Timer tick, asking Buildbucket for the build status
[20:40:40.569] Build 8879661143502572496: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:40:40.569] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:25:0) after 1m0s
[20:41:40.587] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:25:0)
[20:41:40.587] Timer tick, asking Buildbucket for the build status
[20:41:40.829] Build 8879661143502572496: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:41:40.829] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:26:0) after 1m0s
[20:42:40.856] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:26:0)
[20:42:40.856] Timer tick, asking Buildbucket for the build status
[20:42:41.061] Build 8879661143502572496: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:42:41.061] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:27:0) after 1m0s
[20:43:41.121] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:27:0)
[20:43:41.121] Timer tick, asking Buildbucket for the build status
[20:43:41.404] Build 8879661143502572496: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:43:41.404] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:28:0) after 1m0s
[20:44:41.426] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:28:0)
[20:44:41.426] Timer tick, asking Buildbucket for the build status
[20:44:41.812] Build 8879661143502572496: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:44:41.812] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:29:0) after 1m0s
[20:45:41.844] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:29:0)
[20:45:41.844] Timer tick, asking Buildbucket for the build status
[20:45:42.492] Build 8879661143502572496: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:45:42.492] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:30:0) after 1m0s
[20:46:42.518] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:30:0)
[20:46:42.518] Timer tick, asking Buildbucket for the build status
[20:46:42.673] Build 8879661143502572496: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:46:42.673] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:31:0) after 1m0s
[20:47:42.700] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:31:0)
[20:47:42.700] Timer tick, asking Buildbucket for the build status
[20:47:42.975] Build 8879661143502572496: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:47:42.975] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:32:0) after 1m0s
[20:48:43.013] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:32:0)
[20:48:43.013] Timer tick, asking Buildbucket for the build status
[20:48:43.195] Build 8879661143502572496: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:48:43.195] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:33:0) after 1m0s
[20:49:43.220] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:33:0)
[20:49:43.220] Timer tick, asking Buildbucket for the build status
[20:49:43.559] Build 8879661143502572496: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:49:43.559] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:34:0) after 1m0s
[20:50:43.569] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:34:0)
[20:50:43.569] Timer tick, asking Buildbucket for the build status
[20:50:43.842] Build 8879661143502572496: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:50:43.842] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:35:0) after 1m0s
[20:51:43.870] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:35:0)
[20:51:43.870] Timer tick, asking Buildbucket for the build status
[20:51:44.014] Build 8879661143502572496: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:51:44.014] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:36:0) after 1m0s
[20:52:44.057] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:36:0)
[20:52:44.057] Timer tick, asking Buildbucket for the build status
[20:52:44.581] Build 8879661143502572496: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:52:44.582] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:37:0) after 1m0s
[20:53:44.650] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:37:0)
[20:53:44.650] Timer tick, asking Buildbucket for the build status
[20:53:45.061] Build 8879661143502572496: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:53:45.061] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:38:0) after 1m0s
[20:54:45.089] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:38:0)
[20:54:45.089] Timer tick, asking Buildbucket for the build status
[20:54:45.224] Build 8879661143502572496: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:54:45.224] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:39:0) after 1m0s
[20:55:45.351] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:39:0)
[20:55:45.351] Timer tick, asking Buildbucket for the build status
[20:55:51.933] Build 8879661143502572496: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:55:51.933] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:40:0) after 1m0s
[20:56:52.009] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:40:0)
[20:56:52.009] Timer tick, asking Buildbucket for the build status
[20:56:52.181] Build 8879661143502572496: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:56:52.181] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:41:0) after 1m0s
[20:57:52.287] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:41:0)
[20:57:52.287] Timer tick, asking Buildbucket for the build status
[20:57:52.474] Build 8879661143502572496: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:57:52.474] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:42:0) after 1m0s
[20:58:52.685] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:42:0)
[20:58:52.685] Timer tick, asking Buildbucket for the build status
[20:58:55.106] Build 8879661143502572496: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:58:55.106] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:43:0) after 1m0s
[20:59:55.161] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:43:0)
[20:59:55.161] Timer tick, asking Buildbucket for the build status
[20:59:55.459] Build 8879661143502572496: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:59:55.459] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:44:0) after 1m0s
[21:00:55.488] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:44:0)
[21:00:55.488] Timer tick, asking Buildbucket for the build status
[21:00:55.671] Build 8879661143502572496: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:00:55.671] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:45:0) after 1m0s
[21:01:55.743] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:45:0)
[21:01:55.743] Timer tick, asking Buildbucket for the build status
[21:01:56.209] Build 8879661143502572496: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:01:56.209] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:46:0) after 1m0s
[21:02:56.239] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:46:0)
[21:02:56.239] Timer tick, asking Buildbucket for the build status
[21:02:56.413] Build 8879661143502572496: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:02:56.413] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:47:0) after 1m0s
[21:03:56.592] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:47:0)
[21:03:56.592] Timer tick, asking Buildbucket for the build status
[21:03:58.339] Build 8879661143502572496: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:03:58.339] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:48:0) after 1m0s
[21:04:58.432] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:48:0)
[21:04:58.432] Timer tick, asking Buildbucket for the build status
[21:04:58.758] Build 8879661143502572496: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:04:58.758] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:49:0) after 1m0s
[21:05:58.861] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:49:0)
[21:05:58.861] Timer tick, asking Buildbucket for the build status
[21:05:59.671] Build 8879661143502572496: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:05:59.671] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:50:0) after 1m0s
[21:06:47.445] Received PubSub notification, asking Buildbucket for the build status
[21:06:47.743] Build 8879661143502572496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:06:59.777] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:50:0)
[21:06:59.777] Timer tick, asking Buildbucket for the build status
[21:06:59.935] Build 8879661143502572496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:06:59.935] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:52:0) after 1m0s
[21:08:00.025] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:52:0)
[21:08:00.025] Timer tick, asking Buildbucket for the build status
[21:08:00.416] Build 8879661143502572496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:08:00.417] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:53:0) after 1m0s
[21:09:00.494] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:53:0)
[21:09:00.494] Timer tick, asking Buildbucket for the build status
[21:09:00.742] Build 8879661143502572496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:09:00.742] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:54:0) after 1m0s
[21:10:01.415] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:54:0)
[21:10:01.415] Timer tick, asking Buildbucket for the build status
[21:10:01.694] Build 8879661143502572496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:10:01.694] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:55:0) after 1m0s
[21:11:01.835] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:55:0)
[21:11:01.835] Timer tick, asking Buildbucket for the build status
[21:11:02.035] Build 8879661143502572496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:11:02.035] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:56:0) after 1m0s
[21:12:02.493] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:56:0)
[21:12:02.493] Timer tick, asking Buildbucket for the build status
[21:12:06.087] Build 8879661143502572496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:12:06.087] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:57:0) after 1m0s
[21:13:06.279] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:57:0)
[21:13:06.279] Timer tick, asking Buildbucket for the build status
[21:13:06.411] Build 8879661143502572496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:13:06.411] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:58:0) after 1m0s
[21:14:06.909] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:58:0)
[21:14:06.909] Timer tick, asking Buildbucket for the build status
[21:14:07.805] Build 8879661143502572496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:14:07.805] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:59:0) after 1m0s
[21:15:08.080] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:59:0)
[21:15:08.080] Timer tick, asking Buildbucket for the build status
[21:15:08.298] Build 8879661143502572496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:15:08.298] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:60:0) after 1m0s
[21:16:08.436] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:60:0)
[21:16:08.437] Timer tick, asking Buildbucket for the build status
[21:16:08.612] Build 8879661143502572496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:16:08.612] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:61:0) after 1m0s
[21:17:08.840] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:61:0)
[21:17:08.840] Timer tick, asking Buildbucket for the build status
[21:17:09.092] Build 8879661143502572496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:17:09.092] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:62:0) after 1m0s
[21:18:09.364] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:62:0)
[21:18:09.364] Timer tick, asking Buildbucket for the build status
[21:18:09.538] Build 8879661143502572496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:18:09.538] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:63:0) after 1m0s
[21:19:09.665] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:63:0)
[21:19:09.665] Timer tick, asking Buildbucket for the build status
[21:19:10.052] Build 8879661143502572496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:19:10.052] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:64:0) after 1m0s
[21:20:10.217] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:64:0)
[21:20:10.218] Timer tick, asking Buildbucket for the build status
[21:20:10.402] Build 8879661143502572496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:20:10.403] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:65:0) after 1m0s
[21:21:10.654] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:65:0)
[21:21:10.654] Timer tick, asking Buildbucket for the build status
[21:21:10.836] Build 8879661143502572496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:21:10.836] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:66:0) after 1m0s
[21:22:11.044] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:66:0)
[21:22:11.044] Timer tick, asking Buildbucket for the build status
[21:22:12.412] Build 8879661143502572496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:22:12.412] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:67:0) after 1m0s
[21:23:12.609] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:67:0)
[21:23:12.609] Timer tick, asking Buildbucket for the build status
[21:23:12.907] Build 8879661143502572496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:23:12.907] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:68:0) after 1m0s
[21:24:12.988] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:68:0)
[21:24:12.988] Timer tick, asking Buildbucket for the build status
[21:24:13.186] Build 8879661143502572496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:24:13.186] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:69:0) after 1m0s
[21:25:13.262] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:69:0)
[21:25:13.262] Timer tick, asking Buildbucket for the build status
[21:25:13.437] Build 8879661143502572496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:25:13.437] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:70:0) after 1m0s
[21:26:13.552] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:70:0)
[21:26:13.552] Timer tick, asking Buildbucket for the build status
[21:26:13.786] Build 8879661143502572496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:26:13.786] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:71:0) after 1m0s
[21:27:13.857] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:71:0)
[21:27:13.857] Timer tick, asking Buildbucket for the build status
[21:27:14.262] Build 8879661143502572496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:27:14.262] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:72:0) after 1m0s
[21:28:14.406] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:72:0)
[21:28:14.406] Timer tick, asking Buildbucket for the build status
[21:28:14.567] Build 8879661143502572496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:28:14.567] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:73:0) after 1m0s
[21:29:14.837] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:73:0)
[21:29:14.837] Timer tick, asking Buildbucket for the build status
[21:29:15.109] Build 8879661143502572496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:29:15.109] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:74:0) after 1m0s
[21:30:15.190] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:74:0)
[21:30:15.190] Timer tick, asking Buildbucket for the build status
[21:30:15.698] Build 8879661143502572496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:30:15.698] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:75:0) after 1m0s
[21:31:15.851] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:75:0)
[21:31:15.851] Timer tick, asking Buildbucket for the build status
[21:31:16.153] Build 8879661143502572496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:31:16.153] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:76:0) after 1m0s
[21:32:16.250] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:76:0)
[21:32:16.250] Timer tick, asking Buildbucket for the build status
[21:32:16.440] Build 8879661143502572496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:32:16.440] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:77:0) after 1m0s
[21:33:16.894] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:77:0)
[21:33:16.894] Timer tick, asking Buildbucket for the build status
[21:33:17.204] Build 8879661143502572496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:33:17.204] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:78:0) after 1m0s
[21:34:17.362] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:78:0)
[21:34:17.362] Timer tick, asking Buildbucket for the build status
[21:34:17.513] Build 8879661143502572496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:34:17.513] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:79:0) after 1m0s
[21:35:17.726] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:79:0)
[21:35:17.726] Timer tick, asking Buildbucket for the build status
[21:35:17.860] Build 8879661143502572496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:35:17.860] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:80:0) after 1m0s
[21:36:18.276] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:80:0)
[21:36:18.276] Timer tick, asking Buildbucket for the build status
[21:36:18.487] Build 8879661143502572496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:36:18.487] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:81:0) after 1m0s
[21:37:18.727] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:81:0)
[21:37:18.727] Timer tick, asking Buildbucket for the build status
[21:37:18.888] Build 8879661143502572496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:37:18.888] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:82:0) after 1m0s
[21:38:18.975] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:82:0)
[21:38:18.975] Timer tick, asking Buildbucket for the build status
[21:38:19.219] Build 8879661143502572496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:38:19.219] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:83:0) after 1m0s
[21:39:19.424] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:83:0)
[21:39:19.424] Timer tick, asking Buildbucket for the build status
[21:39:19.603] Build 8879661143502572496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:39:19.603] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:84:0) after 1m0s
[21:40:19.778] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:84:0)
[21:40:19.778] Timer tick, asking Buildbucket for the build status
[21:40:19.924] Build 8879661143502572496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:40:19.924] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:85:0) after 1m0s
[21:41:19.982] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:85:0)
[21:41:19.982] Timer tick, asking Buildbucket for the build status
[21:41:20.260] Build 8879661143502572496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:41:20.260] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:86:0) after 1m0s
[21:42:20.422] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:86:0)
[21:42:20.422] Timer tick, asking Buildbucket for the build status
[21:42:20.753] Build 8879661143502572496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:42:20.753] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:87:0) after 1m0s
[21:43:20.788] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:87:0)
[21:43:20.788] Timer tick, asking Buildbucket for the build status
[21:43:20.919] Build 8879661143502572496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:43:20.919] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:88:0) after 1m0s
[21:44:20.925] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:88:0)
[21:44:20.925] Timer tick, asking Buildbucket for the build status
[21:44:21.073] Build 8879661143502572496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:44:21.073] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:89:0) after 1m0s
[21:45:21.098] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:89:0)
[21:45:21.098] Timer tick, asking Buildbucket for the build status
[21:45:21.314] Build 8879661143502572496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:45:21.314] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:90:0) after 1m0s
[21:46:21.741] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:90:0)
[21:46:21.741] Timer tick, asking Buildbucket for the build status
[21:46:21.921] Build 8879661143502572496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:46:21.921] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:91:0) after 1m0s
[21:47:22.869] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:91:0)
[21:47:22.869] Timer tick, asking Buildbucket for the build status
[21:47:23.011] Build 8879661143502572496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:47:23.011] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:92:0) after 1m0s
[21:48:23.213] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:92:0)
[21:48:23.213] Timer tick, asking Buildbucket for the build status
[21:48:23.752] Build 8879661143502572496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:48:23.752] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:93:0) after 1m0s
[21:49:23.937] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:93:0)
[21:49:23.937] Timer tick, asking Buildbucket for the build status
[21:49:24.349] Build 8879661143502572496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:49:24.349] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:94:0) after 1m0s
[21:50:24.483] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:94:0)
[21:50:24.483] Timer tick, asking Buildbucket for the build status
[21:50:25.091] Build 8879661143502572496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:50:25.091] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:95:0) after 1m0s
[21:51:25.313] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:95:0)
[21:51:25.313] Timer tick, asking Buildbucket for the build status
[21:51:25.629] Build 8879661143502572496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:51:25.629] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:96:0) after 1m0s
[21:52:25.661] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:96:0)
[21:52:25.661] Timer tick, asking Buildbucket for the build status
[21:52:25.849] Build 8879661143502572496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:52:25.849] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:97:0) after 1m0s
[21:53:25.871] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:97:0)
[21:53:25.871] Timer tick, asking Buildbucket for the build status
[21:53:26.467] Build 8879661143502572496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:53:26.467] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:98:0) after 1m0s
[21:54:26.520] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:98:0)
[21:54:26.520] Timer tick, asking Buildbucket for the build status
[21:54:26.668] Build 8879661143502572496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:54:26.668] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:99:0) after 1m0s
[21:55:26.762] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:99:0)
[21:55:26.762] Timer tick, asking Buildbucket for the build status
[21:55:27.278] Build 8879661143502572496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:55:27.278] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:100:0) after 1m0s
[21:56:27.377] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:100:0)
[21:56:27.377] Timer tick, asking Buildbucket for the build status
[21:56:27.529] Build 8879661143502572496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:56:27.529] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:101:0) after 1m0s
[21:57:27.561] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:101:0)
[21:57:27.561] Timer tick, asking Buildbucket for the build status
[21:57:29.298] Build 8879661143502572496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:57:29.298] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:102:0) after 1m0s
[21:58:29.415] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:102:0)
[21:58:29.415] Timer tick, asking Buildbucket for the build status
[21:58:29.665] Build 8879661143502572496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:58:29.665] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:103:0) after 1m0s
[21:59:29.688] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:103:0)
[21:59:29.688] Timer tick, asking Buildbucket for the build status
[21:59:29.908] Build 8879661143502572496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:59:29.908] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:104:0) after 1m0s
[22:00:29.961] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:104:0)
[22:00:29.961] Timer tick, asking Buildbucket for the build status
[22:00:36.061] Build 8879661143502572496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:00:36.061] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:105:0) after 1m0s
[22:01:36.161] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:105:0)
[22:01:36.161] Timer tick, asking Buildbucket for the build status
[22:01:36.435] Build 8879661143502572496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:01:36.435] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:106:0) after 1m0s
[22:02:36.521] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:106:0)
[22:02:36.521] Timer tick, asking Buildbucket for the build status
[22:02:36.917] Build 8879661143502572496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:02:36.917] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:107:0) after 1m0s
[22:03:36.900] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:107:0)
[22:03:36.900] Timer tick, asking Buildbucket for the build status
[22:03:37.389] Build 8879661143502572496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:03:37.389] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:108:0) after 1m0s
[22:04:37.437] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:108:0)
[22:04:37.437] Timer tick, asking Buildbucket for the build status
[22:04:37.902] Build 8879661143502572496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:04:37.902] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:109:0) after 1m0s
[22:05:37.962] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:109:0)
[22:05:37.962] Timer tick, asking Buildbucket for the build status
[22:05:41.042] Build 8879661143502572496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:05:41.042] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:110:0) after 1m0s
[22:06:41.072] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:110:0)
[22:06:41.072] Timer tick, asking Buildbucket for the build status
[22:06:42.045] Build 8879661143502572496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:06:42.045] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:111:0) after 1m0s
[22:07:42.119] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:111:0)
[22:07:42.119] Timer tick, asking Buildbucket for the build status
[22:07:42.468] Build 8879661143502572496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:07:42.468] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:112:0) after 1m0s
[22:08:42.538] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:112:0)
[22:08:42.538] Timer tick, asking Buildbucket for the build status
[22:08:42.728] Build 8879661143502572496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:08:42.728] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:113:0) after 1m0s
[22:09:42.798] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:113:0)
[22:09:42.798] Timer tick, asking Buildbucket for the build status
[22:09:43.031] Build 8879661143502572496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:09:43.031] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:114:0) after 1m0s
[22:10:43.071] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:114:0)
[22:10:43.071] Timer tick, asking Buildbucket for the build status
[22:10:45.374] Build 8879661143502572496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:10:45.374] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:115:0) after 1m0s
[22:11:45.444] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:115:0)
[22:11:45.444] Timer tick, asking Buildbucket for the build status
[22:11:45.712] Build 8879661143502572496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:11:45.712] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:116:0) after 1m0s
[22:12:45.728] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:116:0)
[22:12:45.728] Timer tick, asking Buildbucket for the build status
[22:12:45.920] Build 8879661143502572496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:12:45.920] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:117:0) after 1m0s
[22:13:45.971] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:117:0)
[22:13:45.971] Timer tick, asking Buildbucket for the build status
[22:13:46.459] Build 8879661143502572496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:13:46.459] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:118:0) after 1m0s
[22:14:46.525] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:118:0)
[22:14:46.525] Timer tick, asking Buildbucket for the build status
[22:14:47.230] Build 8879661143502572496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:14:47.230] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:119:0) after 1m0s
[22:15:47.242] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:119:0)
[22:15:47.242] Timer tick, asking Buildbucket for the build status
[22:15:48.023] Build 8879661143502572496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:15:48.023] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:120:0) after 1m0s
[22:16:48.079] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:120:0)
[22:16:48.079] Timer tick, asking Buildbucket for the build status
[22:16:48.243] Build 8879661143502572496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:16:48.243] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:121:0) after 1m0s
[22:17:48.220] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:121:0)
[22:17:48.221] Timer tick, asking Buildbucket for the build status
[22:17:48.468] Build 8879661143502572496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:17:48.468] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:122:0) after 1m0s
[22:18:48.499] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:122:0)
[22:18:48.499] Timer tick, asking Buildbucket for the build status
[22:18:48.677] Build 8879661143502572496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:18:48.677] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:123:0) after 1m0s
[22:19:48.703] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:123:0)
[22:19:48.703] Timer tick, asking Buildbucket for the build status
[22:19:48.916] Build 8879661143502572496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:19:48.916] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9045091206771604992:124:0) after 1m0s
[22:20:25.670] Received PubSub notification, asking Buildbucket for the build status
[22:20:25.874] Build 8879661143502572496: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[22:20:25.874] Buildbucket build:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary_preference": "PROD",
"completed_ts": "1590099623896765",
"created_by": "project:emscripten-releases",
"created_ts": "1590092251259836",
"id": "8879661143502572496",
"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\": \"24ebfbdb547633bed4e2eb03f1c20fcfd797c2ed\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@24ebfbdb547633bed4e2eb03f1c20fcfd797c2ed\", \"title\": \"24ebfbdb547633bed4e2eb03f1c20fcfd797c2ed\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/24ebfbdb547633bed4e2eb03f1c20fcfd797c2ed\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"24ebfbdb547633bed4e2eb03f1c20fcfd797c2ed\"}}",
"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\": \"24ebfbdb547633bed4e2eb03f1c20fcfd797c2ed\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@24ebfbdb547633bed4e2eb03f1c20fcfd797c2ed\", \"title\": \"24ebfbdb547633bed4e2eb03f1c20fcfd797c2ed\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/24ebfbdb547633bed4e2eb03f1c20fcfd797c2ed\"}]}, \"bot_id\": \"luci-emscripten-releases-ci-win10-0-6yyo\", \"branch\": \"refs/heads/master\", \"buildername\": \"win\", \"got_revision\": \"24ebfbdb547633bed4e2eb03f1c20fcfd797c2ed\", \"path_config\": \"generic\", \"recipe\": \"emscripten_releases\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"24ebfbdb547633bed4e2eb03f1c20fcfd797c2ed\"}, \"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-6yyo\"], \"image\": [\"chrome-win10-1703-20042500-5e6af220c3f\"], \"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\": [\"5100-d2625c1\"], \"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": "1590095206602063",
"status": "COMPLETED",
"status_changed_ts": "1590099624864666",
"tags": [
"builder:win",
"buildset:commit/git/24ebfbdb547633bed4e2eb03f1c20fcfd797c2ed",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/24ebfbdb547633bed4e2eb03f1c20fcfd797c2ed",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9045091206771604992",
"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/8879661143502572496/+/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:4c51b3cb4b37aa10",
"user_agent:luci-scheduler"
],
"updated_ts": "1590099624941807",
"url": "https://ci.chromium.org/b/8879661143502572496",
"utcnow_ts": "1590099625865228"
}
}
[22:20:25.874] Invocation finished in 2h2m57.116366323s with status SUCCEEDED