[01:04:31.037] New invocation is queued and will start shortly
[01:04:32.513] Starting the invocation (attempt 1)
[01:04:32.613] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[01:04:32.613] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[01:04:32.614] Buildbucket request:
{
"bucket": "luci.emscripten-releases.ci",
"client_operation_id": "9058934483783019568",
"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\":\"0eae1d33b3929a81c586b7859d272a89774c0a85\"},\"id\":\"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@0eae1d33b3929a81c586b7859d272a89774c0a85\",\"title\":\"0eae1d33b3929a81c586b7859d272a89774c0a85\",\"url\":\"https://chromium.googlesource.com/emscripten-releases/+/0eae1d33b3929a81c586b7859d272a89774c0a85\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/emscripten-releases\",\"revision\":\"0eae1d33b3929a81c586b7859d272a89774c0a85\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win",
"scheduler_invocation_id:9058934483783019568",
"scheduler_job_id:emscripten-releases/win",
"user_agent:luci-scheduler",
"buildset:commit/git/0eae1d33b3929a81c586b7859d272a89774c0a85",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/0eae1d33b3929a81c586b7859d272a89774c0a85",
"gitiles_ref:refs/heads/master"
]
}
[01:04:33.677] Buildbucket response:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary_preference": "PROD",
"created_by": "project:emscripten-releases",
"created_ts": "1576890272815975",
"id": "8893504421251287232",
"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\": \"0eae1d33b3929a81c586b7859d272a89774c0a85\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@0eae1d33b3929a81c586b7859d272a89774c0a85\", \"title\": \"0eae1d33b3929a81c586b7859d272a89774c0a85\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/0eae1d33b3929a81c586b7859d272a89774c0a85\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"0eae1d33b3929a81c586b7859d272a89774c0a85\"}}",
"project": "emscripten-releases",
"result_details_json": "{\"properties\": {}}",
"service_account": "emscripten-releases-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1576890273517595",
"tags": [
"builder:win",
"buildset:commit/git/0eae1d33b3929a81c586b7859d272a89774c0a85",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/0eae1d33b3929a81c586b7859d272a89774c0a85",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9058934483783019568",
"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/8893504421251287232/+/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": "1576890273517757",
"url": "https://ci.chromium.org/b/8893504421251287232",
"utcnow_ts": "1576890273666383"
}
}
[01:04:33.677] Task URL: https://ci.chromium.org/b/8893504421251287232
[01:04:33.677] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:2:0) after 1m0s
[01:05:33.789] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:2:0)
[01:05:33.789] Timer tick, asking Buildbucket for the build status
[01:05:33.942] Build 8893504421251287232: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:05:33.942] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:3:0) after 1m0s
[01:06:34.137] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:3:0)
[01:06:34.137] Timer tick, asking Buildbucket for the build status
[01:06:34.288] Build 8893504421251287232: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:06:34.288] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:4:0) after 1m0s
[01:07:34.395] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:4:0)
[01:07:34.395] Timer tick, asking Buildbucket for the build status
[01:07:34.510] Build 8893504421251287232: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:07:34.510] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:5:0) after 1m0s
[01:08:34.616] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:5:0)
[01:08:34.616] Timer tick, asking Buildbucket for the build status
[01:08:34.974] Build 8893504421251287232: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:08:34.974] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:6:0) after 1m0s
[01:09:34.983] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:6:0)
[01:09:34.983] Timer tick, asking Buildbucket for the build status
[01:09:35.276] Build 8893504421251287232: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:09:35.276] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:7:0) after 1m0s
[01:10:35.302] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:7:0)
[01:10:35.302] Timer tick, asking Buildbucket for the build status
[01:10:35.593] Build 8893504421251287232: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:10:35.593] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:8:0) after 1m0s
[01:11:35.607] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:8:0)
[01:11:35.607] Timer tick, asking Buildbucket for the build status
[01:11:35.735] Build 8893504421251287232: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:11:35.735] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:9:0) after 1m0s
[01:12:35.758] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:9:0)
[01:12:35.758] Timer tick, asking Buildbucket for the build status
[01:12:36.270] Build 8893504421251287232: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:12:36.270] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:10:0) after 1m0s
[01:13:36.323] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:10:0)
[01:13:36.323] Timer tick, asking Buildbucket for the build status
[01:13:36.637] Build 8893504421251287232: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:13:36.637] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:11:0) after 1m0s
[01:14:36.700] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:11:0)
[01:14:36.700] Timer tick, asking Buildbucket for the build status
[01:14:37.035] Build 8893504421251287232: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:14:37.035] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:12:0) after 1m0s
[01:15:37.059] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:12:0)
[01:15:37.059] Timer tick, asking Buildbucket for the build status
[01:15:37.238] Build 8893504421251287232: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:15:37.238] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:13:0) after 1m0s
[01:16:37.348] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:13:0)
[01:16:37.348] Timer tick, asking Buildbucket for the build status
[01:16:37.546] Build 8893504421251287232: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:16:37.546] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:14:0) after 1m0s
[01:17:37.603] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:14:0)
[01:17:37.603] Timer tick, asking Buildbucket for the build status
[01:17:37.749] Build 8893504421251287232: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:17:37.749] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:15:0) after 1m0s
[01:18:37.812] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:15:0)
[01:18:37.812] Timer tick, asking Buildbucket for the build status
[01:18:37.933] Build 8893504421251287232: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:18:37.934] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:16:0) after 1m0s
[01:19:38.138] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:16:0)
[01:19:38.138] Timer tick, asking Buildbucket for the build status
[01:19:38.395] Build 8893504421251287232: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:19:38.395] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:17:0) after 1m0s
[01:20:38.516] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:17:0)
[01:20:38.516] Timer tick, asking Buildbucket for the build status
[01:20:38.698] Build 8893504421251287232: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:20:38.698] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:18:0) after 1m0s
[01:21:38.765] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:18:0)
[01:21:38.765] Timer tick, asking Buildbucket for the build status
[01:21:38.973] Build 8893504421251287232: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:21:38.973] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:19:0) after 1m0s
[01:22:38.996] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:19:0)
[01:22:38.996] Timer tick, asking Buildbucket for the build status
[01:22:39.256] Build 8893504421251287232: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:22:39.256] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:20:0) after 1m0s
[01:23:39.330] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:20:0)
[01:23:39.330] Timer tick, asking Buildbucket for the build status
[01:23:39.464] Build 8893504421251287232: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:23:39.464] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:21:0) after 1m0s
[01:24:39.442] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:21:0)
[01:24:39.442] Timer tick, asking Buildbucket for the build status
[01:24:39.589] Build 8893504421251287232: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:24:39.589] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:22:0) after 1m0s
[01:25:39.621] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:22:0)
[01:25:39.621] Timer tick, asking Buildbucket for the build status
[01:25:39.744] Build 8893504421251287232: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:25:39.744] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:23:0) after 1m0s
[01:26:39.904] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:23:0)
[01:26:39.904] Timer tick, asking Buildbucket for the build status
[01:26:40.145] Build 8893504421251287232: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:26:40.145] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:24:0) after 1m0s
[01:27:40.285] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:24:0)
[01:27:40.285] Timer tick, asking Buildbucket for the build status
[01:27:40.436] Build 8893504421251287232: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:27:40.436] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:25:0) after 1m0s
[01:28:40.600] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:25:0)
[01:28:40.600] Timer tick, asking Buildbucket for the build status
[01:28:40.755] Build 8893504421251287232: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:28:40.755] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:26:0) after 1m0s
[01:29:40.779] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:26:0)
[01:29:40.779] Timer tick, asking Buildbucket for the build status
[01:29:40.966] Build 8893504421251287232: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:29:40.966] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:27:0) after 1m0s
[01:30:40.980] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:27:0)
[01:30:40.980] Timer tick, asking Buildbucket for the build status
[01:30:41.105] Build 8893504421251287232: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:30:41.105] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:28:0) after 1m0s
[01:31:41.325] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:28:0)
[01:31:41.325] Timer tick, asking Buildbucket for the build status
[01:31:41.478] Build 8893504421251287232: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:31:41.478] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:29:0) after 1m0s
[01:32:41.541] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:29:0)
[01:32:41.541] Timer tick, asking Buildbucket for the build status
[01:32:41.927] Build 8893504421251287232: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:32:41.927] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:30:0) after 1m0s
[01:33:41.988] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:30:0)
[01:33:41.988] Timer tick, asking Buildbucket for the build status
[01:33:42.215] Build 8893504421251287232: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:33:42.215] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:31:0) after 1m0s
[01:34:42.242] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:31:0)
[01:34:42.242] Timer tick, asking Buildbucket for the build status
[01:34:42.659] Build 8893504421251287232: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:34:42.659] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:32:0) after 1m0s
[01:35:42.696] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:32:0)
[01:35:42.696] Timer tick, asking Buildbucket for the build status
[01:35:42.811] Build 8893504421251287232: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:35:42.811] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:33:0) after 1m0s
[01:36:42.834] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:33:0)
[01:36:42.834] Timer tick, asking Buildbucket for the build status
[01:36:43.219] Build 8893504421251287232: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:36:43.219] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:34:0) after 1m0s
[01:37:43.325] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:34:0)
[01:37:43.325] Timer tick, asking Buildbucket for the build status
[01:37:43.661] Build 8893504421251287232: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:37:43.661] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:35:0) after 1m0s
[01:38:43.672] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:35:0)
[01:38:43.672] Timer tick, asking Buildbucket for the build status
[01:38:43.773] Build 8893504421251287232: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:38:43.773] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:36:0) after 1m0s
[01:39:43.811] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:36:0)
[01:39:43.811] Timer tick, asking Buildbucket for the build status
[01:39:44.022] Build 8893504421251287232: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:39:44.022] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:37:0) after 1m0s
[01:40:44.036] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:37:0)
[01:40:44.036] Timer tick, asking Buildbucket for the build status
[01:40:44.160] Build 8893504421251287232: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:40:44.160] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:38:0) after 1m0s
[01:41:44.230] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:38:0)
[01:41:44.230] Timer tick, asking Buildbucket for the build status
[01:41:44.466] Build 8893504421251287232: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:41:44.466] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:39:0) after 1m0s
[01:42:44.509] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:39:0)
[01:42:44.509] Timer tick, asking Buildbucket for the build status
[01:42:44.968] Build 8893504421251287232: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:42:44.968] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:40:0) after 1m0s
[01:43:45.002] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:40:0)
[01:43:45.002] Timer tick, asking Buildbucket for the build status
[01:43:45.346] Build 8893504421251287232: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:43:45.346] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:41:0) after 1m0s
[01:44:45.395] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:41:0)
[01:44:45.395] Timer tick, asking Buildbucket for the build status
[01:44:45.734] Build 8893504421251287232: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:44:45.734] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:42:0) after 1m0s
[01:45:45.804] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:42:0)
[01:45:45.804] Timer tick, asking Buildbucket for the build status
[01:45:46.242] Build 8893504421251287232: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:45:46.242] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:43:0) after 1m0s
[01:46:46.287] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:43:0)
[01:46:46.287] Timer tick, asking Buildbucket for the build status
[01:46:46.532] Build 8893504421251287232: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:46:46.532] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:44:0) after 1m0s
[01:47:46.574] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:44:0)
[01:47:46.574] Timer tick, asking Buildbucket for the build status
[01:47:46.720] Build 8893504421251287232: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:47:46.720] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:45:0) after 1m0s
[01:48:46.831] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:45:0)
[01:48:46.831] Timer tick, asking Buildbucket for the build status
[01:48:47.001] Build 8893504421251287232: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:48:47.001] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:46:0) after 1m0s
[01:49:47.146] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:46:0)
[01:49:47.146] Timer tick, asking Buildbucket for the build status
[01:49:47.655] Build 8893504421251287232: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:49:47.655] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:47:0) after 1m0s
[01:50:47.772] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:47:0)
[01:50:47.772] Timer tick, asking Buildbucket for the build status
[01:50:48.012] Build 8893504421251287232: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:50:48.012] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:48:0) after 1m0s
[01:51:48.038] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:48:0)
[01:51:48.038] Timer tick, asking Buildbucket for the build status
[01:51:48.202] Build 8893504421251287232: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:51:48.202] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:49:0) after 1m0s
[01:52:48.335] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:49:0)
[01:52:48.335] Timer tick, asking Buildbucket for the build status
[01:52:48.517] Build 8893504421251287232: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:52:48.517] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:50:0) after 1m0s
[01:53:48.550] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:50:0)
[01:53:48.550] Timer tick, asking Buildbucket for the build status
[01:53:48.665] Build 8893504421251287232: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:53:48.665] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:51:0) after 1m0s
[01:54:48.703] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:51:0)
[01:54:48.703] Timer tick, asking Buildbucket for the build status
[01:54:48.854] Build 8893504421251287232: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:54:48.854] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:52:0) after 1m0s
[01:55:48.924] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:52:0)
[01:55:48.924] Timer tick, asking Buildbucket for the build status
[01:55:49.132] Build 8893504421251287232: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:55:49.133] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:53:0) after 1m0s
[01:56:49.197] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:53:0)
[01:56:49.197] Timer tick, asking Buildbucket for the build status
[01:56:49.336] Build 8893504421251287232: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:56:49.336] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:54:0) after 1m0s
[01:57:49.374] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:54:0)
[01:57:49.374] Timer tick, asking Buildbucket for the build status
[01:57:49.588] Build 8893504421251287232: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:57:49.588] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:55:0) after 1m0s
[01:58:49.657] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:55:0)
[01:58:49.657] Timer tick, asking Buildbucket for the build status
[01:58:49.792] Build 8893504421251287232: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:58:49.792] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:56:0) after 1m0s
[01:59:49.780] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:56:0)
[01:59:49.780] Timer tick, asking Buildbucket for the build status
[01:59:49.940] Build 8893504421251287232: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:59:49.940] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:57:0) after 1m0s
[02:00:50.026] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:57:0)
[02:00:50.026] Timer tick, asking Buildbucket for the build status
[02:00:50.233] Build 8893504421251287232: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:00:50.233] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:58:0) after 1m0s
[02:01:50.260] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:58:0)
[02:01:50.260] Timer tick, asking Buildbucket for the build status
[02:01:50.529] Build 8893504421251287232: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:01:50.529] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:59:0) after 1m0s
[02:02:50.606] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:59:0)
[02:02:50.606] Timer tick, asking Buildbucket for the build status
[02:02:50.838] Build 8893504421251287232: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:02:50.839] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:60:0) after 1m0s
[02:03:50.984] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:60:0)
[02:03:50.984] Timer tick, asking Buildbucket for the build status
[02:03:51.774] Build 8893504421251287232: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:03:51.774] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:61:0) after 1m0s
[02:04:51.889] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:61:0)
[02:04:51.889] Timer tick, asking Buildbucket for the build status
[02:04:52.171] Build 8893504421251287232: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:04:52.171] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:62:0) after 1m0s
[02:05:52.214] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:62:0)
[02:05:52.214] Timer tick, asking Buildbucket for the build status
[02:05:52.358] Build 8893504421251287232: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:05:52.358] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:63:0) after 1m0s
[02:06:52.350] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:63:0)
[02:06:52.350] Timer tick, asking Buildbucket for the build status
[02:06:52.448] Build 8893504421251287232: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:06:52.448] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:64:0) after 1m0s
[02:07:52.528] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:64:0)
[02:07:52.528] Timer tick, asking Buildbucket for the build status
[02:07:52.797] Build 8893504421251287232: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:07:52.797] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:65:0) after 1m0s
[02:08:52.827] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:65:0)
[02:08:52.827] Timer tick, asking Buildbucket for the build status
[02:08:53.354] Build 8893504421251287232: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:08:53.354] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:66:0) after 1m0s
[02:09:53.377] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:66:0)
[02:09:53.377] Timer tick, asking Buildbucket for the build status
[02:09:53.505] Build 8893504421251287232: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:09:53.505] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:67:0) after 1m0s
[02:09:55.420] Received PubSub notification, asking Buildbucket for the build status
[02:09:55.719] Build 8893504421251287232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:10:53.518] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:67:0)
[02:10:53.518] Timer tick, asking Buildbucket for the build status
[02:10:53.670] Build 8893504421251287232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:10:53.670] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:69:0) after 1m0s
[02:11:53.732] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:69:0)
[02:11:53.732] Timer tick, asking Buildbucket for the build status
[02:11:53.977] Build 8893504421251287232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:11:53.977] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:70:0) after 1m0s
[02:12:54.036] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:70:0)
[02:12:54.036] Timer tick, asking Buildbucket for the build status
[02:12:54.228] Build 8893504421251287232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:12:54.228] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:71:0) after 1m0s
[02:13:54.269] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:71:0)
[02:13:54.269] Timer tick, asking Buildbucket for the build status
[02:13:54.444] Build 8893504421251287232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:13:54.444] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:72:0) after 1m0s
[02:14:54.556] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:72:0)
[02:14:54.556] Timer tick, asking Buildbucket for the build status
[02:14:54.699] Build 8893504421251287232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:14:54.699] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:73:0) after 1m0s
[02:15:54.752] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:73:0)
[02:15:54.752] Timer tick, asking Buildbucket for the build status
[02:15:54.932] Build 8893504421251287232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:15:54.932] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:74:0) after 1m0s
[02:16:54.925] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:74:0)
[02:16:54.925] Timer tick, asking Buildbucket for the build status
[02:16:55.089] Build 8893504421251287232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:16:55.090] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:75:0) after 1m0s
[02:17:55.203] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:75:0)
[02:17:55.203] Timer tick, asking Buildbucket for the build status
[02:17:55.615] Build 8893504421251287232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:17:55.615] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:76:0) after 1m0s
[02:18:55.676] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:76:0)
[02:18:55.676] Timer tick, asking Buildbucket for the build status
[02:18:56.079] Build 8893504421251287232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:18:56.079] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:77:0) after 1m0s
[02:19:56.136] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:77:0)
[02:19:56.136] Timer tick, asking Buildbucket for the build status
[02:19:56.411] Build 8893504421251287232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:19:56.411] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:78:0) after 1m0s
[02:20:56.666] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:78:0)
[02:20:56.666] Timer tick, asking Buildbucket for the build status
[02:20:56.801] Build 8893504421251287232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:20:56.801] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:79:0) after 1m0s
[02:21:56.941] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:79:0)
[02:21:56.941] Timer tick, asking Buildbucket for the build status
[02:21:57.113] Build 8893504421251287232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:21:57.113] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:80:0) after 1m0s
[02:22:57.217] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:80:0)
[02:22:57.217] Timer tick, asking Buildbucket for the build status
[02:22:57.315] Build 8893504421251287232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:22:57.315] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:81:0) after 1m0s
[02:23:57.325] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:81:0)
[02:23:57.325] Timer tick, asking Buildbucket for the build status
[02:23:57.705] Build 8893504421251287232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:23:57.706] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:82:0) after 1m0s
[02:24:57.714] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:82:0)
[02:24:57.714] Timer tick, asking Buildbucket for the build status
[02:24:57.895] Build 8893504421251287232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:24:57.895] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:83:0) after 1m0s
[02:25:57.899] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:83:0)
[02:25:57.899] Timer tick, asking Buildbucket for the build status
[02:25:58.211] Build 8893504421251287232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:25:58.211] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:84:0) after 1m0s
[02:26:58.316] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:84:0)
[02:26:58.317] Timer tick, asking Buildbucket for the build status
[02:26:58.622] Build 8893504421251287232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:26:58.622] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:85:0) after 1m0s
[02:27:58.628] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:85:0)
[02:27:58.628] Timer tick, asking Buildbucket for the build status
[02:27:58.761] Build 8893504421251287232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:27:58.761] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:86:0) after 1m0s
[02:28:58.789] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:86:0)
[02:28:58.789] Timer tick, asking Buildbucket for the build status
[02:28:58.991] Build 8893504421251287232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:28:58.991] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:87:0) after 1m0s
[02:29:59.087] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:87:0)
[02:29:59.087] Timer tick, asking Buildbucket for the build status
[02:29:59.428] Build 8893504421251287232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:29:59.428] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:88:0) after 1m0s
[02:30:59.463] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:88:0)
[02:30:59.463] Timer tick, asking Buildbucket for the build status
[02:30:59.737] Build 8893504421251287232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:30:59.737] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:89:0) after 1m0s
[02:31:59.819] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:89:0)
[02:31:59.819] Timer tick, asking Buildbucket for the build status
[02:31:59.964] Build 8893504421251287232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:31:59.964] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:90:0) after 1m0s
[02:32:59.966] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:90:0)
[02:32:59.966] Timer tick, asking Buildbucket for the build status
[02:33:02.392] Build 8893504421251287232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:33:02.392] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:91:0) after 1m0s
[02:34:02.507] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:91:0)
[02:34:02.507] Timer tick, asking Buildbucket for the build status
[02:34:03.897] Build 8893504421251287232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:34:03.897] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:92:0) after 1m0s
[02:35:03.897] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:92:0)
[02:35:03.897] Timer tick, asking Buildbucket for the build status
[02:35:04.072] Build 8893504421251287232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:35:04.072] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:93:0) after 1m0s
[02:36:04.112] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:93:0)
[02:36:04.112] Timer tick, asking Buildbucket for the build status
[02:36:04.377] Build 8893504421251287232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:36:04.377] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:94:0) after 1m0s
[02:37:04.416] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:94:0)
[02:37:04.416] Timer tick, asking Buildbucket for the build status
[02:37:04.664] Build 8893504421251287232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:37:04.664] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:95:0) after 1m0s
[02:38:04.689] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:95:0)
[02:38:04.689] Timer tick, asking Buildbucket for the build status
[02:38:04.855] Build 8893504421251287232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:38:04.855] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:96:0) after 1m0s
[02:39:04.968] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:96:0)
[02:39:04.968] Timer tick, asking Buildbucket for the build status
[02:39:05.306] Build 8893504421251287232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:39:05.306] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:97:0) after 1m0s
[02:40:05.389] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:97:0)
[02:40:05.389] Timer tick, asking Buildbucket for the build status
[02:40:05.510] Build 8893504421251287232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:40:05.510] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:98:0) after 1m0s
[02:41:05.629] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:98:0)
[02:41:05.629] Timer tick, asking Buildbucket for the build status
[02:41:05.769] Build 8893504421251287232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:41:05.769] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:99:0) after 1m0s
[02:42:05.820] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:99:0)
[02:42:05.820] Timer tick, asking Buildbucket for the build status
[02:42:06.151] Build 8893504421251287232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:42:06.151] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:100:0) after 1m0s
[02:43:06.176] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:100:0)
[02:43:06.176] Timer tick, asking Buildbucket for the build status
[02:43:06.296] Build 8893504421251287232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:43:06.296] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:101:0) after 1m0s
[02:44:06.432] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:101:0)
[02:44:06.432] Timer tick, asking Buildbucket for the build status
[02:44:06.640] Build 8893504421251287232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:44:06.640] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:102:0) after 1m0s
[02:45:06.792] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:102:0)
[02:45:06.792] Timer tick, asking Buildbucket for the build status
[02:45:07.099] Build 8893504421251287232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:45:07.099] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:103:0) after 1m0s
[02:46:07.226] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:103:0)
[02:46:07.226] Timer tick, asking Buildbucket for the build status
[02:46:07.368] Build 8893504421251287232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:46:07.368] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:104:0) after 1m0s
[02:47:07.405] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:104:0)
[02:47:07.405] Timer tick, asking Buildbucket for the build status
[02:47:07.616] Build 8893504421251287232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:47:07.616] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:105:0) after 1m0s
[02:48:07.726] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:105:0)
[02:48:07.726] Timer tick, asking Buildbucket for the build status
[02:48:08.053] Build 8893504421251287232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:48:08.053] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:106:0) after 1m0s
[02:49:08.148] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:106:0)
[02:49:08.148] Timer tick, asking Buildbucket for the build status
[02:49:08.332] Build 8893504421251287232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:49:08.332] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:107:0) after 1m0s
[02:50:08.643] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:107:0)
[02:50:08.643] Timer tick, asking Buildbucket for the build status
[02:50:08.836] Build 8893504421251287232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:50:08.836] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:108:0) after 1m0s
[02:51:08.959] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:108:0)
[02:51:08.959] Timer tick, asking Buildbucket for the build status
[02:51:09.099] Build 8893504421251287232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:51:09.099] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:109:0) after 1m0s
[02:52:09.126] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:109:0)
[02:52:09.126] Timer tick, asking Buildbucket for the build status
[02:52:09.651] Build 8893504421251287232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:52:09.651] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:110:0) after 1m0s
[02:53:09.922] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:110:0)
[02:53:09.922] Timer tick, asking Buildbucket for the build status
[02:53:10.088] Build 8893504421251287232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:53:10.088] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:111:0) after 1m0s
[02:54:10.342] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:111:0)
[02:54:10.342] Timer tick, asking Buildbucket for the build status
[02:54:10.546] Build 8893504421251287232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:54:10.546] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:112:0) after 1m0s
[02:55:10.620] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:112:0)
[02:55:10.620] Timer tick, asking Buildbucket for the build status
[02:55:10.846] Build 8893504421251287232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:55:10.846] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:113:0) after 1m0s
[02:56:11.078] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:113:0)
[02:56:11.078] Timer tick, asking Buildbucket for the build status
[02:56:11.260] Build 8893504421251287232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:56:11.260] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:114:0) after 1m0s
[02:57:11.367] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:114:0)
[02:57:11.367] Timer tick, asking Buildbucket for the build status
[02:57:11.552] Build 8893504421251287232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:57:11.552] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:115:0) after 1m0s
[02:58:11.669] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:115:0)
[02:58:11.669] Timer tick, asking Buildbucket for the build status
[02:58:12.136] Build 8893504421251287232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:58:12.136] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:116:0) after 1m0s
[02:59:12.213] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:116:0)
[02:59:12.213] Timer tick, asking Buildbucket for the build status
[02:59:12.359] Build 8893504421251287232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:59:12.359] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:117:0) after 1m0s
[03:00:12.391] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:117:0)
[03:00:12.391] Timer tick, asking Buildbucket for the build status
[03:00:12.552] Build 8893504421251287232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:00:12.552] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:118:0) after 1m0s
[03:01:12.591] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:118:0)
[03:01:12.591] Timer tick, asking Buildbucket for the build status
[03:01:12.729] Build 8893504421251287232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:01:12.729] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:119:0) after 1m0s
[03:02:12.813] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:119:0)
[03:02:12.813] Timer tick, asking Buildbucket for the build status
[03:02:12.933] Build 8893504421251287232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:02:12.933] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:120:0) after 1m0s
[03:03:13.086] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:120:0)
[03:03:13.086] Timer tick, asking Buildbucket for the build status
[03:03:13.287] Build 8893504421251287232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:03:13.287] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:121:0) after 1m0s
[03:04:13.335] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:121:0)
[03:04:13.335] Timer tick, asking Buildbucket for the build status
[03:04:13.453] Build 8893504421251287232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:04:13.453] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:122:0) after 1m0s
[03:05:13.502] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:122:0)
[03:05:13.502] Timer tick, asking Buildbucket for the build status
[03:05:13.756] Build 8893504421251287232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:05:13.756] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:123:0) after 1m0s
[03:06:13.828] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:123:0)
[03:06:13.828] Timer tick, asking Buildbucket for the build status
[03:06:13.944] Build 8893504421251287232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:06:13.944] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:124:0) after 1m0s
[03:07:13.968] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:124:0)
[03:07:13.968] Timer tick, asking Buildbucket for the build status
[03:07:14.504] Build 8893504421251287232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:07:14.504] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:125:0) after 1m0s
[03:08:14.630] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:125:0)
[03:08:14.630] Timer tick, asking Buildbucket for the build status
[03:08:15.072] Build 8893504421251287232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:08:15.072] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:126:0) after 1m0s
[03:09:15.133] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:126:0)
[03:09:15.133] Timer tick, asking Buildbucket for the build status
[03:09:17.158] Build 8893504421251287232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:09:17.158] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:127:0) after 1m0s
[03:10:17.169] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:127:0)
[03:10:17.169] Timer tick, asking Buildbucket for the build status
[03:10:17.286] Build 8893504421251287232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:10:17.286] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:128:0) after 1m0s
[03:11:17.527] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:128:0)
[03:11:17.527] Timer tick, asking Buildbucket for the build status
[03:11:17.653] Build 8893504421251287232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:11:17.653] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:129:0) after 1m0s
[03:12:17.786] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:129:0)
[03:12:17.787] Timer tick, asking Buildbucket for the build status
[03:12:17.984] Build 8893504421251287232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:12:17.984] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:130:0) after 1m0s
[03:13:18.100] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:130:0)
[03:13:18.100] Timer tick, asking Buildbucket for the build status
[03:13:18.286] Build 8893504421251287232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:13:18.286] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:131:0) after 1m0s
[03:14:18.384] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:131:0)
[03:14:18.384] Timer tick, asking Buildbucket for the build status
[03:14:18.772] Build 8893504421251287232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:14:18.772] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:132:0) after 1m0s
[03:15:18.887] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:132:0)
[03:15:18.887] Timer tick, asking Buildbucket for the build status
[03:15:19.089] Build 8893504421251287232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:15:19.089] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:133:0) after 1m0s
[03:16:19.153] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:133:0)
[03:16:19.153] Timer tick, asking Buildbucket for the build status
[03:16:19.311] Build 8893504421251287232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:16:19.311] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:134:0) after 1m0s
[03:17:19.338] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:134:0)
[03:17:19.338] Timer tick, asking Buildbucket for the build status
[03:17:19.661] Build 8893504421251287232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:17:19.661] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:135:0) after 1m0s
[03:18:19.686] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:135:0)
[03:18:19.686] Timer tick, asking Buildbucket for the build status
[03:18:19.924] Build 8893504421251287232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:18:19.924] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:136:0) after 1m0s
[03:19:20.072] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:136:0)
[03:19:20.072] Timer tick, asking Buildbucket for the build status
[03:19:20.300] Build 8893504421251287232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:19:20.300] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:137:0) after 1m0s
[03:20:20.342] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:137:0)
[03:20:20.342] Timer tick, asking Buildbucket for the build status
[03:20:20.455] Build 8893504421251287232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:20:20.455] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:138:0) after 1m0s
[03:21:20.593] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:138:0)
[03:21:20.593] Timer tick, asking Buildbucket for the build status
[03:21:20.739] Build 8893504421251287232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:21:20.739] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9058934483783019568:139:0) after 1m0s
[03:22:04.017] Received PubSub notification, asking Buildbucket for the build status
[03:22:04.088] Build 8893504421251287232: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[03:22:04.088] Buildbucket build:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary_preference": "PROD",
"completed_ts": "1576898523234174",
"created_by": "project:emscripten-releases",
"created_ts": "1576890272815975",
"id": "8893504421251287232",
"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\": \"0eae1d33b3929a81c586b7859d272a89774c0a85\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@0eae1d33b3929a81c586b7859d272a89774c0a85\", \"title\": \"0eae1d33b3929a81c586b7859d272a89774c0a85\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/0eae1d33b3929a81c586b7859d272a89774c0a85\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"0eae1d33b3929a81c586b7859d272a89774c0a85\"}}",
"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\": \"0eae1d33b3929a81c586b7859d272a89774c0a85\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@0eae1d33b3929a81c586b7859d272a89774c0a85\", \"title\": \"0eae1d33b3929a81c586b7859d272a89774c0a85\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/0eae1d33b3929a81c586b7859d272a89774c0a85\"}]}, \"bot_id\": \"luci-emscripten-releases-ci-win10-0-njsa\", \"branch\": \"refs/heads/master\", \"buildername\": \"win\", \"got_revision\": \"0eae1d33b3929a81c586b7859d272a89774c0a85\", \"path_config\": \"generic\", \"recipe\": \"emscripten_releases\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"0eae1d33b3929a81c586b7859d272a89774c0a85\"}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"builder_0175bca77590805533ed6e20351c9bfa7effd036e42d11a5b619a6ce75a8e486_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"host_class\": [\"default\"], \"id\": [\"luci-emscripten-releases-ci-win10-0-njsa\"], \"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\": [\"4733-6bd8c41\"], \"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": "1576894194146285",
"status": "COMPLETED",
"status_changed_ts": "1576898523581580",
"tags": [
"builder:win",
"buildset:commit/git/0eae1d33b3929a81c586b7859d272a89774c0a85",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/0eae1d33b3929a81c586b7859d272a89774c0a85",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9058934483783019568",
"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/8893504421251287232/+/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:493ecd982416be10",
"user_agent:luci-scheduler"
],
"updated_ts": "1576898523601539",
"url": "https://ci.chromium.org/b/8893504421251287232",
"utcnow_ts": "1576898524078844"
}
}
[03:22:04.088] Invocation finished in 2h17m33.071385369s with status SUCCEEDED