[23:41:48.093] New invocation is queued and will start shortly
[23:41:49.204] Starting the invocation (attempt 1)
[23:41:49.251] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[23:41:49.251] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[23:41:49.251] Buildbucket request:
{
"bucket": "luci.emscripten-releases.ci",
"client_operation_id": "9039914324859848272",
"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\":\"f510bd9ffc46dcefc29804c666a2a5f0df2173dd\"},\"id\":\"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@f510bd9ffc46dcefc29804c666a2a5f0df2173dd\",\"title\":\"f510bd9ffc46dcefc29804c666a2a5f0df2173dd\",\"url\":\"https://chromium.googlesource.com/emscripten-releases/+/f510bd9ffc46dcefc29804c666a2a5f0df2173dd\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/emscripten-releases\",\"revision\":\"f510bd9ffc46dcefc29804c666a2a5f0df2173dd\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win",
"scheduler_invocation_id:9039914324859848272",
"scheduler_job_id:emscripten-releases/win",
"user_agent:luci-scheduler",
"buildset:commit/git/f510bd9ffc46dcefc29804c666a2a5f0df2173dd",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/f510bd9ffc46dcefc29804c666a2a5f0df2173dd",
"gitiles_ref:refs/heads/master"
]
}
[23:41:49.797] Buildbucket response:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary_preference": "PROD",
"created_by": "project:emscripten-releases",
"created_ts": "1595029309350429",
"id": "8874484262877196848",
"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\": \"f510bd9ffc46dcefc29804c666a2a5f0df2173dd\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@f510bd9ffc46dcefc29804c666a2a5f0df2173dd\", \"title\": \"f510bd9ffc46dcefc29804c666a2a5f0df2173dd\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/f510bd9ffc46dcefc29804c666a2a5f0df2173dd\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"f510bd9ffc46dcefc29804c666a2a5f0df2173dd\"}}",
"project": "emscripten-releases",
"result_details_json": "{\"properties\": {}}",
"service_account": "emscripten-releases-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1595029309671832",
"tags": [
"builder:win",
"buildset:commit/git/f510bd9ffc46dcefc29804c666a2a5f0df2173dd",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/f510bd9ffc46dcefc29804c666a2a5f0df2173dd",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9039914324859848272",
"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/8874484262877196848/+/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": "1595029309671942",
"url": "https://ci.chromium.org/b/8874484262877196848",
"utcnow_ts": "1595029309788247"
}
}
[23:41:49.797] Task URL: https://ci.chromium.org/b/8874484262877196848
[23:41:49.797] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:2:0) after 1m0s
[23:42:49.943] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:2:0)
[23:42:49.943] Timer tick, asking Buildbucket for the build status
[23:42:50.163] Build 8874484262877196848: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:42:50.163] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:3:0) after 1m0s
[23:43:50.218] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:3:0)
[23:43:50.218] Timer tick, asking Buildbucket for the build status
[23:43:50.342] Build 8874484262877196848: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:43:50.342] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:4:0) after 1m0s
[23:44:50.462] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:4:0)
[23:44:50.462] Timer tick, asking Buildbucket for the build status
[23:44:50.580] Build 8874484262877196848: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:44:50.580] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:5:0) after 1m0s
[23:45:50.622] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:5:0)
[23:45:50.622] Timer tick, asking Buildbucket for the build status
[23:45:51.032] Build 8874484262877196848: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:45:51.032] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:6:0) after 1m0s
[23:46:51.087] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:6:0)
[23:46:51.087] Timer tick, asking Buildbucket for the build status
[23:46:51.357] Build 8874484262877196848: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:46:51.357] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:7:0) after 1m0s
[23:47:51.395] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:7:0)
[23:47:51.395] Timer tick, asking Buildbucket for the build status
[23:47:51.490] Build 8874484262877196848: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:47:51.490] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:8:0) after 1m0s
[23:48:51.764] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:8:0)
[23:48:51.764] Timer tick, asking Buildbucket for the build status
[23:48:51.979] Build 8874484262877196848: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:48:51.979] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:9:0) after 1m0s
[23:49:52.096] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:9:0)
[23:49:52.096] Timer tick, asking Buildbucket for the build status
[23:49:52.253] Build 8874484262877196848: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:49:52.253] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:10:0) after 1m0s
[23:50:52.274] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:10:0)
[23:50:52.274] Timer tick, asking Buildbucket for the build status
[23:50:52.449] Build 8874484262877196848: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:50:52.449] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:11:0) after 1m0s
[23:51:52.572] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:11:0)
[23:51:52.572] Timer tick, asking Buildbucket for the build status
[23:51:52.705] Build 8874484262877196848: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:51:52.705] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:12:0) after 1m0s
[23:52:52.770] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:12:0)
[23:52:52.770] Timer tick, asking Buildbucket for the build status
[23:52:52.851] Build 8874484262877196848: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:52:52.851] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:13:0) after 1m0s
[23:53:52.894] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:13:0)
[23:53:52.894] Timer tick, asking Buildbucket for the build status
[23:53:53.019] Build 8874484262877196848: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:53:53.019] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:14:0) after 1m0s
[23:54:53.128] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:14:0)
[23:54:53.128] Timer tick, asking Buildbucket for the build status
[23:54:53.214] Build 8874484262877196848: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:54:53.214] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:15:0) after 1m0s
[23:55:53.286] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:15:0)
[23:55:53.286] Timer tick, asking Buildbucket for the build status
[23:55:53.406] Build 8874484262877196848: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:55:53.406] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:16:0) after 1m0s
[23:56:53.477] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:16:0)
[23:56:53.477] Timer tick, asking Buildbucket for the build status
[23:56:53.630] Build 8874484262877196848: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:56:53.630] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:17:0) after 1m0s
[23:57:53.693] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:17:0)
[23:57:53.693] Timer tick, asking Buildbucket for the build status
[23:57:53.787] Build 8874484262877196848: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:57:53.787] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:18:0) after 1m0s
[23:58:53.801] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:18:0)
[23:58:53.801] Timer tick, asking Buildbucket for the build status
[23:58:53.915] Build 8874484262877196848: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:58:53.915] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:19:0) after 1m0s
[23:59:53.935] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:19:0)
[23:59:53.935] Timer tick, asking Buildbucket for the build status
[23:59:54.222] Build 8874484262877196848: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:59:54.222] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:20:0) after 1m0s
[00:00:54.336] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:20:0)
[00:00:54.336] Timer tick, asking Buildbucket for the build status
[00:00:54.468] Build 8874484262877196848: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:00:54.468] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:21:0) after 1m0s
[00:01:54.517] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:21:0)
[00:01:54.517] Timer tick, asking Buildbucket for the build status
[00:01:54.634] Build 8874484262877196848: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:01:54.634] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:22:0) after 1m0s
[00:02:54.748] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:22:0)
[00:02:54.748] Timer tick, asking Buildbucket for the build status
[00:02:55.057] Build 8874484262877196848: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:02:55.057] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:23:0) after 1m0s
[00:03:55.245] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:23:0)
[00:03:55.245] Timer tick, asking Buildbucket for the build status
[00:03:55.404] Build 8874484262877196848: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:03:55.404] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:24:0) after 1m0s
[00:04:55.425] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:24:0)
[00:04:55.425] Timer tick, asking Buildbucket for the build status
[00:04:55.794] Build 8874484262877196848: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:04:55.794] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:25:0) after 1m0s
[00:05:55.851] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:25:0)
[00:05:55.851] Timer tick, asking Buildbucket for the build status
[00:05:55.964] Build 8874484262877196848: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:05:55.964] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:26:0) after 1m0s
[00:06:56.037] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:26:0)
[00:06:56.037] Timer tick, asking Buildbucket for the build status
[00:06:56.238] Build 8874484262877196848: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:06:56.238] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:27:0) after 1m0s
[00:07:56.253] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:27:0)
[00:07:56.253] Timer tick, asking Buildbucket for the build status
[00:07:56.434] Build 8874484262877196848: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:07:56.434] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:28:0) after 1m0s
[00:08:56.463] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:28:0)
[00:08:56.463] Timer tick, asking Buildbucket for the build status
[00:08:56.643] Build 8874484262877196848: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:08:56.643] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:29:0) after 1m0s
[00:09:56.678] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:29:0)
[00:09:56.678] Timer tick, asking Buildbucket for the build status
[00:09:56.796] Build 8874484262877196848: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:09:56.796] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:30:0) after 1m0s
[00:10:56.873] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:30:0)
[00:10:56.873] Timer tick, asking Buildbucket for the build status
[00:10:56.979] Build 8874484262877196848: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:10:56.979] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:31:0) after 1m0s
[00:11:57.068] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:31:0)
[00:11:57.068] Timer tick, asking Buildbucket for the build status
[00:11:57.192] Build 8874484262877196848: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:11:57.192] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:32:0) after 1m0s
[00:12:57.187] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:32:0)
[00:12:57.187] Timer tick, asking Buildbucket for the build status
[00:12:57.406] Build 8874484262877196848: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:12:57.406] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:33:0) after 1m0s
[00:13:57.496] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:33:0)
[00:13:57.496] Timer tick, asking Buildbucket for the build status
[00:13:57.587] Build 8874484262877196848: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:13:57.587] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:34:0) after 1m0s
[00:14:57.678] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:34:0)
[00:14:57.678] Timer tick, asking Buildbucket for the build status
[00:14:57.897] Build 8874484262877196848: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:14:57.897] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:35:0) after 1m0s
[00:15:57.965] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:35:0)
[00:15:57.965] Timer tick, asking Buildbucket for the build status
[00:15:58.110] Build 8874484262877196848: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:15:58.110] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:36:0) after 1m0s
[00:16:58.166] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:36:0)
[00:16:58.166] Timer tick, asking Buildbucket for the build status
[00:16:58.320] Build 8874484262877196848: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:16:58.320] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:37:0) after 1m0s
[00:17:58.408] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:37:0)
[00:17:58.408] Timer tick, asking Buildbucket for the build status
[00:17:58.709] Build 8874484262877196848: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:17:58.709] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:38:0) after 1m0s
[00:18:58.762] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:38:0)
[00:18:58.762] Timer tick, asking Buildbucket for the build status
[00:18:59.109] Build 8874484262877196848: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:18:59.109] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:39:0) after 1m0s
[00:19:59.181] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:39:0)
[00:19:59.181] Timer tick, asking Buildbucket for the build status
[00:19:59.288] Build 8874484262877196848: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:19:59.288] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:40:0) after 1m0s
[00:20:59.319] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:40:0)
[00:20:59.319] Timer tick, asking Buildbucket for the build status
[00:20:59.534] Build 8874484262877196848: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:20:59.534] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:41:0) after 1m0s
[00:21:59.576] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:41:0)
[00:21:59.576] Timer tick, asking Buildbucket for the build status
[00:21:59.707] Build 8874484262877196848: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:21:59.707] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:42:0) after 1m0s
[00:22:59.745] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:42:0)
[00:22:59.745] Timer tick, asking Buildbucket for the build status
[00:22:59.842] Build 8874484262877196848: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:22:59.842] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:43:0) after 1m0s
[00:23:59.879] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:43:0)
[00:23:59.879] Timer tick, asking Buildbucket for the build status
[00:24:00.457] Build 8874484262877196848: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:24:00.457] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:44:0) after 1m0s
[00:25:01.437] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:44:0)
[00:25:01.437] Timer tick, asking Buildbucket for the build status
[00:25:01.866] Build 8874484262877196848: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:25:01.866] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:45:0) after 1m0s
[00:26:02.409] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:45:0)
[00:26:02.409] Timer tick, asking Buildbucket for the build status
[00:26:02.543] Build 8874484262877196848: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:26:02.543] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:46:0) after 1m0s
[00:27:02.688] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:46:0)
[00:27:02.688] Timer tick, asking Buildbucket for the build status
[00:27:02.822] Build 8874484262877196848: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:27:02.822] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:47:0) after 1m0s
[00:28:02.912] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:47:0)
[00:28:02.912] Timer tick, asking Buildbucket for the build status
[00:28:02.996] Build 8874484262877196848: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:28:02.996] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:48:0) after 1m0s
[00:29:03.008] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:48:0)
[00:29:03.008] Timer tick, asking Buildbucket for the build status
[00:29:03.274] Build 8874484262877196848: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:29:03.274] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:49:0) after 1m0s
[00:30:03.357] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:49:0)
[00:30:03.357] Timer tick, asking Buildbucket for the build status
[00:30:03.464] Build 8874484262877196848: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:30:03.464] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:50:0) after 1m0s
[00:31:03.542] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:50:0)
[00:31:03.542] Timer tick, asking Buildbucket for the build status
[00:31:03.650] Build 8874484262877196848: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:31:03.650] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:51:0) after 1m0s
[00:32:03.768] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:51:0)
[00:32:03.768] Timer tick, asking Buildbucket for the build status
[00:32:03.914] Build 8874484262877196848: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:32:03.914] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:52:0) after 1m0s
[00:33:03.937] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:52:0)
[00:33:03.937] Timer tick, asking Buildbucket for the build status
[00:33:04.391] Build 8874484262877196848: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:33:04.391] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:53:0) after 1m0s
[00:34:04.510] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:53:0)
[00:34:04.510] Timer tick, asking Buildbucket for the build status
[00:34:04.705] Build 8874484262877196848: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:34:04.705] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:54:0) after 1m0s
[00:35:04.729] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:54:0)
[00:35:04.729] Timer tick, asking Buildbucket for the build status
[00:35:05.086] Build 8874484262877196848: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:35:05.086] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:55:0) after 1m0s
[00:36:05.185] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:55:0)
[00:36:05.185] Timer tick, asking Buildbucket for the build status
[00:36:05.683] Build 8874484262877196848: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:36:05.683] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:56:0) after 1m0s
[00:37:05.706] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:56:0)
[00:37:05.706] Timer tick, asking Buildbucket for the build status
[00:37:13.658] Build 8874484262877196848: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:37:13.658] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:57:0) after 1m0s
[00:38:13.732] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:57:0)
[00:38:13.732] Timer tick, asking Buildbucket for the build status
[00:38:13.853] Build 8874484262877196848: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:38:13.853] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:58:0) after 1m0s
[00:39:14.137] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:58:0)
[00:39:14.137] Timer tick, asking Buildbucket for the build status
[00:39:14.264] Build 8874484262877196848: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:39:14.264] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:59:0) after 1m0s
[00:40:14.318] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:59:0)
[00:40:14.318] Timer tick, asking Buildbucket for the build status
[00:40:14.440] Build 8874484262877196848: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:40:14.440] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:60:0) after 1m0s
[00:41:14.506] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:60:0)
[00:41:14.506] Timer tick, asking Buildbucket for the build status
[00:41:14.615] Build 8874484262877196848: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:41:14.615] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:61:0) after 1m0s
[00:42:14.676] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:61:0)
[00:42:14.676] Timer tick, asking Buildbucket for the build status
[00:42:14.778] Build 8874484262877196848: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:42:14.778] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:62:0) after 1m0s
[00:43:14.865] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:62:0)
[00:43:14.865] Timer tick, asking Buildbucket for the build status
[00:43:15.116] Build 8874484262877196848: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:43:15.116] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:63:0) after 1m0s
[00:44:15.237] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:63:0)
[00:44:15.237] Timer tick, asking Buildbucket for the build status
[00:44:15.544] Build 8874484262877196848: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:44:15.544] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:64:0) after 1m0s
[00:44:54.484] Received PubSub notification, asking Buildbucket for the build status
[00:44:54.659] Build 8874484262877196848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:45:15.793] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:64:0)
[00:45:15.793] Timer tick, asking Buildbucket for the build status
[00:45:16.173] Build 8874484262877196848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:45:16.173] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:66:0) after 1m0s
[00:46:16.238] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:66:0)
[00:46:16.238] Timer tick, asking Buildbucket for the build status
[00:46:16.650] Build 8874484262877196848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:46:16.650] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:67:0) after 1m0s
[00:47:16.795] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:67:0)
[00:47:16.795] Timer tick, asking Buildbucket for the build status
[00:47:16.927] Build 8874484262877196848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:47:16.927] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:68:0) after 1m0s
[00:48:17.128] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:68:0)
[00:48:17.128] Timer tick, asking Buildbucket for the build status
[00:48:17.241] Build 8874484262877196848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:48:17.241] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:69:0) after 1m0s
[00:49:17.432] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:69:0)
[00:49:17.432] Timer tick, asking Buildbucket for the build status
[00:49:17.569] Build 8874484262877196848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:49:17.569] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:70:0) after 1m0s
[00:50:17.704] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:70:0)
[00:50:17.704] Timer tick, asking Buildbucket for the build status
[00:50:17.789] Build 8874484262877196848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:50:17.789] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:71:0) after 1m0s
[00:51:17.878] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:71:0)
[00:51:17.878] Timer tick, asking Buildbucket for the build status
[00:51:17.986] Build 8874484262877196848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:51:17.986] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:72:0) after 1m0s
[00:52:18.007] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:72:0)
[00:52:18.007] Timer tick, asking Buildbucket for the build status
[00:52:18.135] Build 8874484262877196848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:52:18.135] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:73:0) after 1m0s
[00:53:18.171] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:73:0)
[00:53:18.171] Timer tick, asking Buildbucket for the build status
[00:53:18.229] Build 8874484262877196848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:53:18.229] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:74:0) after 1m0s
[00:54:18.313] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:74:0)
[00:54:18.314] Timer tick, asking Buildbucket for the build status
[00:54:18.432] Build 8874484262877196848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:54:18.432] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:75:0) after 1m0s
[00:55:18.454] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:75:0)
[00:55:18.454] Timer tick, asking Buildbucket for the build status
[00:55:19.132] Build 8874484262877196848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:55:19.132] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:76:0) after 1m0s
[00:56:19.157] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:76:0)
[00:56:19.157] Timer tick, asking Buildbucket for the build status
[00:56:19.230] Build 8874484262877196848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:56:19.230] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:77:0) after 1m0s
[00:57:19.251] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:77:0)
[00:57:19.251] Timer tick, asking Buildbucket for the build status
[00:57:19.396] Build 8874484262877196848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:57:19.396] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:78:0) after 1m0s
[00:58:19.536] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:78:0)
[00:58:19.536] Timer tick, asking Buildbucket for the build status
[00:58:20.234] Build 8874484262877196848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:58:20.234] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:79:0) after 1m0s
[00:59:20.231] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:79:0)
[00:59:20.231] Timer tick, asking Buildbucket for the build status
[00:59:20.343] Build 8874484262877196848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:59:20.343] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:80:0) after 1m0s
[01:00:20.386] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:80:0)
[01:00:20.386] Timer tick, asking Buildbucket for the build status
[01:00:20.848] Build 8874484262877196848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:00:20.848] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:81:0) after 1m0s
[01:01:20.985] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:81:0)
[01:01:20.985] Timer tick, asking Buildbucket for the build status
[01:01:21.176] Build 8874484262877196848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:01:21.176] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:82:0) after 1m0s
[01:02:21.203] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:82:0)
[01:02:21.203] Timer tick, asking Buildbucket for the build status
[01:02:21.596] Build 8874484262877196848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:02:21.596] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:83:0) after 1m0s
[01:03:21.648] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:83:0)
[01:03:21.648] Timer tick, asking Buildbucket for the build status
[01:03:21.763] Build 8874484262877196848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:03:21.764] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:84:0) after 1m0s
[01:04:21.849] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:84:0)
[01:04:21.849] Timer tick, asking Buildbucket for the build status
[01:04:22.071] Build 8874484262877196848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:04:22.071] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:85:0) after 1m0s
[01:05:22.168] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:85:0)
[01:05:22.168] Timer tick, asking Buildbucket for the build status
[01:05:22.338] Build 8874484262877196848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:05:22.338] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:86:0) after 1m0s
[01:06:22.439] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:86:0)
[01:06:22.439] Timer tick, asking Buildbucket for the build status
[01:06:22.516] Build 8874484262877196848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:06:22.516] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:87:0) after 1m0s
[01:07:22.507] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:87:0)
[01:07:22.507] Timer tick, asking Buildbucket for the build status
[01:07:22.881] Build 8874484262877196848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:07:22.881] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:88:0) after 1m0s
[01:08:22.940] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:88:0)
[01:08:22.940] Timer tick, asking Buildbucket for the build status
[01:08:23.065] Build 8874484262877196848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:08:23.065] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:89:0) after 1m0s
[01:09:23.054] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:89:0)
[01:09:23.054] Timer tick, asking Buildbucket for the build status
[01:09:23.208] Build 8874484262877196848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:09:23.208] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:90:0) after 1m0s
[01:10:23.318] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:90:0)
[01:10:23.318] Timer tick, asking Buildbucket for the build status
[01:10:23.456] Build 8874484262877196848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:10:23.456] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:91:0) after 1m0s
[01:11:23.660] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:91:0)
[01:11:23.660] Timer tick, asking Buildbucket for the build status
[01:11:23.864] Build 8874484262877196848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:11:23.864] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:92:0) after 1m0s
[01:12:23.906] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:92:0)
[01:12:23.906] Timer tick, asking Buildbucket for the build status
[01:12:24.021] Build 8874484262877196848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:12:24.021] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:93:0) after 1m0s
[01:13:24.122] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:93:0)
[01:13:24.122] Timer tick, asking Buildbucket for the build status
[01:13:24.475] Build 8874484262877196848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:13:24.476] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:94:0) after 1m0s
[01:14:24.494] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:94:0)
[01:14:24.494] Timer tick, asking Buildbucket for the build status
[01:14:24.791] Build 8874484262877196848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:14:24.791] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:95:0) after 1m0s
[01:15:24.827] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:95:0)
[01:15:24.827] Timer tick, asking Buildbucket for the build status
[01:15:25.017] Build 8874484262877196848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:15:25.017] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:96:0) after 1m0s
[01:16:25.042] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:96:0)
[01:16:25.042] Timer tick, asking Buildbucket for the build status
[01:16:25.174] Build 8874484262877196848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:16:25.174] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:97:0) after 1m0s
[01:17:25.300] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:97:0)
[01:17:25.300] Timer tick, asking Buildbucket for the build status
[01:17:25.470] Build 8874484262877196848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:17:25.470] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:98:0) after 1m0s
[01:18:25.646] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:98:0)
[01:18:25.646] Timer tick, asking Buildbucket for the build status
[01:18:26.003] Build 8874484262877196848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:18:26.003] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:99:0) after 1m0s
[01:19:26.082] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:99:0)
[01:19:26.082] Timer tick, asking Buildbucket for the build status
[01:19:26.160] Build 8874484262877196848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:19:26.160] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:100:0) after 1m0s
[01:20:26.153] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:100:0)
[01:20:26.153] Timer tick, asking Buildbucket for the build status
[01:20:26.338] Build 8874484262877196848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:20:26.338] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:101:0) after 1m0s
[01:21:26.396] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:101:0)
[01:21:26.396] Timer tick, asking Buildbucket for the build status
[01:21:26.702] Build 8874484262877196848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:21:26.702] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:102:0) after 1m0s
[01:22:26.829] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:102:0)
[01:22:26.829] Timer tick, asking Buildbucket for the build status
[01:22:26.916] Build 8874484262877196848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:22:26.916] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:103:0) after 1m0s
[01:23:26.959] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:103:0)
[01:23:26.959] Timer tick, asking Buildbucket for the build status
[01:23:27.292] Build 8874484262877196848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:23:27.292] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:104:0) after 1m0s
[01:24:27.422] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:104:0)
[01:24:27.422] Timer tick, asking Buildbucket for the build status
[01:24:27.593] Build 8874484262877196848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:24:27.593] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:105:0) after 1m0s
[01:25:27.649] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:105:0)
[01:25:27.649] Timer tick, asking Buildbucket for the build status
[01:25:27.728] Build 8874484262877196848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:25:27.728] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:106:0) after 1m0s
[01:26:27.794] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:106:0)
[01:26:27.794] Timer tick, asking Buildbucket for the build status
[01:26:27.950] Build 8874484262877196848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:26:27.950] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:107:0) after 1m0s
[01:27:28.088] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:107:0)
[01:27:28.088] Timer tick, asking Buildbucket for the build status
[01:27:28.239] Build 8874484262877196848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:27:28.239] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:108:0) after 1m0s
[01:28:28.274] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:108:0)
[01:28:28.274] Timer tick, asking Buildbucket for the build status
[01:28:28.412] Build 8874484262877196848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:28:28.412] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:109:0) after 1m0s
[01:29:28.456] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:109:0)
[01:29:28.456] Timer tick, asking Buildbucket for the build status
[01:29:28.606] Build 8874484262877196848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:29:28.606] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:110:0) after 1m0s
[01:30:28.863] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:110:0)
[01:30:28.863] Timer tick, asking Buildbucket for the build status
[01:30:29.010] Build 8874484262877196848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:30:29.010] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:111:0) after 1m0s
[01:31:29.149] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:111:0)
[01:31:29.149] Timer tick, asking Buildbucket for the build status
[01:31:29.215] Build 8874484262877196848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:31:29.215] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:112:0) after 1m0s
[01:32:30.330] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:112:0)
[01:32:30.330] Timer tick, asking Buildbucket for the build status
[01:32:30.628] Build 8874484262877196848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:32:30.628] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:113:0) after 1m0s
[01:33:30.859] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:113:0)
[01:33:30.859] Timer tick, asking Buildbucket for the build status
[01:33:31.282] Build 8874484262877196848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:33:31.282] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:114:0) after 1m0s
[01:34:31.520] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:114:0)
[01:34:31.520] Timer tick, asking Buildbucket for the build status
[01:34:31.724] Build 8874484262877196848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:34:31.724] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:115:0) after 1m0s
[01:35:31.880] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:115:0)
[01:35:31.880] Timer tick, asking Buildbucket for the build status
[01:35:32.261] Build 8874484262877196848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:35:32.261] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:116:0) after 1m0s
[01:36:32.364] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:116:0)
[01:36:32.364] Timer tick, asking Buildbucket for the build status
[01:36:32.706] Build 8874484262877196848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:36:32.706] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:117:0) after 1m0s
[01:37:32.746] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:117:0)
[01:37:32.746] Timer tick, asking Buildbucket for the build status
[01:37:32.872] Build 8874484262877196848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:37:32.872] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:118:0) after 1m0s
[01:38:32.922] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:118:0)
[01:38:32.922] Timer tick, asking Buildbucket for the build status
[01:38:33.547] Build 8874484262877196848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:38:33.547] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:119:0) after 1m0s
[01:39:33.707] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:119:0)
[01:39:33.707] Timer tick, asking Buildbucket for the build status
[01:39:33.920] Build 8874484262877196848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:39:33.920] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:120:0) after 1m0s
[01:40:33.969] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:120:0)
[01:40:33.969] Timer tick, asking Buildbucket for the build status
[01:40:34.219] Build 8874484262877196848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:40:34.219] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:121:0) after 1m0s
[01:41:34.228] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:121:0)
[01:41:34.228] Timer tick, asking Buildbucket for the build status
[01:41:34.593] Build 8874484262877196848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:41:34.593] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:122:0) after 1m0s
[01:42:34.673] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:122:0)
[01:42:34.673] Timer tick, asking Buildbucket for the build status
[01:42:34.886] Build 8874484262877196848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:42:34.886] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:123:0) after 1m0s
[01:43:34.919] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:123:0)
[01:43:34.919] Timer tick, asking Buildbucket for the build status
[01:43:35.025] Build 8874484262877196848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:43:35.025] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:124:0) after 1m0s
[01:44:35.065] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:124:0)
[01:44:35.065] Timer tick, asking Buildbucket for the build status
[01:44:35.188] Build 8874484262877196848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:44:35.188] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:125:0) after 1m0s
[01:45:35.222] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:125:0)
[01:45:35.222] Timer tick, asking Buildbucket for the build status
[01:45:35.640] Build 8874484262877196848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:45:35.640] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:126:0) after 1m0s
[01:46:35.779] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:126:0)
[01:46:35.779] Timer tick, asking Buildbucket for the build status
[01:46:35.848] Build 8874484262877196848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:46:35.848] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:127:0) after 1m0s
[01:47:35.907] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:127:0)
[01:47:35.907] Timer tick, asking Buildbucket for the build status
[01:47:36.093] Build 8874484262877196848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:47:36.093] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:128:0) after 1m0s
[01:48:36.168] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:128:0)
[01:48:36.168] Timer tick, asking Buildbucket for the build status
[01:48:36.344] Build 8874484262877196848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:48:36.344] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:129:0) after 1m0s
[01:49:36.471] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:129:0)
[01:49:36.471] Timer tick, asking Buildbucket for the build status
[01:49:36.745] Build 8874484262877196848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:49:36.745] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:130:0) after 1m0s
[01:50:36.725] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:130:0)
[01:50:36.725] Timer tick, asking Buildbucket for the build status
[01:50:37.770] Build 8874484262877196848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:50:37.770] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:131:0) after 1m0s
[01:51:37.867] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:131:0)
[01:51:37.867] Timer tick, asking Buildbucket for the build status
[01:51:38.196] Build 8874484262877196848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:51:38.196] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:132:0) after 1m0s
[01:52:38.308] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:132:0)
[01:52:38.308] Timer tick, asking Buildbucket for the build status
[01:52:38.727] Build 8874484262877196848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:52:38.727] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:133:0) after 1m0s
[01:53:38.798] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:133:0)
[01:53:38.798] Timer tick, asking Buildbucket for the build status
[01:53:38.945] Build 8874484262877196848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:53:38.945] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9039914324859848272:134:0) after 1m0s
[01:53:47.626] Received PubSub notification, asking Buildbucket for the build status
[01:53:47.692] Build 8874484262877196848: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[01:53:47.692] Buildbucket build:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary_preference": "PROD",
"completed_ts": "1595037226799001",
"created_by": "project:emscripten-releases",
"created_ts": "1595029309350429",
"id": "8874484262877196848",
"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\": \"f510bd9ffc46dcefc29804c666a2a5f0df2173dd\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@f510bd9ffc46dcefc29804c666a2a5f0df2173dd\", \"title\": \"f510bd9ffc46dcefc29804c666a2a5f0df2173dd\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/f510bd9ffc46dcefc29804c666a2a5f0df2173dd\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"f510bd9ffc46dcefc29804c666a2a5f0df2173dd\"}}",
"project": "emscripten-releases",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/goma\": {\"enable_ats\": true, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\"}, \"$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\": \"f510bd9ffc46dcefc29804c666a2a5f0df2173dd\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@f510bd9ffc46dcefc29804c666a2a5f0df2173dd\", \"title\": \"f510bd9ffc46dcefc29804c666a2a5f0df2173dd\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/f510bd9ffc46dcefc29804c666a2a5f0df2173dd\"}]}, \"bot_id\": \"luci-emscripten-releases-ci-win10-0-q49l\", \"branch\": \"refs/heads/master\", \"buildername\": \"win\", \"got_revision\": \"f510bd9ffc46dcefc29804c666a2a5f0df2173dd\", \"path_config\": \"generic\", \"recipe\": \"emscripten_releases\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"f510bd9ffc46dcefc29804c666a2a5f0df2173dd\"}, \"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-q49l\"], \"image\": [\"chrome-win10-1703-20071600-faa8609efbc\"], \"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\": [\"5409-9d77717\"], \"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": "1595033093149718",
"status": "COMPLETED",
"status_changed_ts": "1595037227250283",
"tags": [
"builder:win",
"buildset:commit/git/f510bd9ffc46dcefc29804c666a2a5f0df2173dd",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/f510bd9ffc46dcefc29804c666a2a5f0df2173dd",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9039914324859848272",
"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/8874484262877196848/+/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:4d77f952b092a910",
"user_agent:luci-scheduler"
],
"updated_ts": "1595037227289243",
"url": "https://ci.chromium.org/b/8874484262877196848",
"utcnow_ts": "1595037227679870"
}
}
[01:53:47.692] Invocation finished in 2h11m59.615897969s with status SUCCEEDED