[02:05:36.599] New invocation is queued and will start shortly
[02:05:37.746] Starting the invocation (attempt 1)
[02:05:37.817] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[02:05:37.817] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[02:05:37.817] Buildbucket request:
{
"bucket": "luci.emscripten-releases.ci",
"client_operation_id": "9062282727915613888",
"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\":\"689bc6b13c59d1710b51f938ddfc56fa480d2e59\"},\"id\":\"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@689bc6b13c59d1710b51f938ddfc56fa480d2e59\",\"title\":\"689bc6b13c59d1710b51f938ddfc56fa480d2e59\",\"url\":\"https://chromium.googlesource.com/emscripten-releases/+/689bc6b13c59d1710b51f938ddfc56fa480d2e59\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/emscripten-releases\",\"revision\":\"689bc6b13c59d1710b51f938ddfc56fa480d2e59\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win",
"scheduler_invocation_id:9062282727915613888",
"scheduler_job_id:emscripten-releases/win",
"user_agent:luci-scheduler",
"buildset:commit/git/689bc6b13c59d1710b51f938ddfc56fa480d2e59",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/689bc6b13c59d1710b51f938ddfc56fa480d2e59",
"gitiles_ref:refs/heads/master"
]
}
[02:05:38.526] Buildbucket response:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary_preference": "PROD",
"created_by": "project:emscripten-releases",
"created_ts": "1573697137938290",
"id": "8896852665847462112",
"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\": \"689bc6b13c59d1710b51f938ddfc56fa480d2e59\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@689bc6b13c59d1710b51f938ddfc56fa480d2e59\", \"title\": \"689bc6b13c59d1710b51f938ddfc56fa480d2e59\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/689bc6b13c59d1710b51f938ddfc56fa480d2e59\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"689bc6b13c59d1710b51f938ddfc56fa480d2e59\"}}",
"project": "emscripten-releases",
"result_details_json": "{\"properties\": {}}",
"service_account": "emscripten-releases-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1573697138279744",
"tags": [
"builder:win",
"buildset:commit/git/689bc6b13c59d1710b51f938ddfc56fa480d2e59",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/689bc6b13c59d1710b51f938ddfc56fa480d2e59",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9062282727915613888",
"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/8896852665847462112/+/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": "1573697138279918",
"url": "https://ci.chromium.org/b/8896852665847462112",
"utcnow_ts": "1573697138507531"
}
}
[02:05:38.526] Task URL: https://ci.chromium.org/b/8896852665847462112
[02:05:38.526] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:2:0) after 1m0s
[02:06:38.554] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:2:0)
[02:06:38.554] Timer tick, asking Buildbucket for the build status
[02:06:38.933] Build 8896852665847462112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:06:38.933] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:3:0) after 1m0s
[02:07:39.104] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:3:0)
[02:07:39.104] Timer tick, asking Buildbucket for the build status
[02:07:39.438] Build 8896852665847462112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:07:39.438] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:4:0) after 1m0s
[02:08:39.688] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:4:0)
[02:08:39.688] Timer tick, asking Buildbucket for the build status
[02:08:40.166] Build 8896852665847462112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:08:40.166] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:5:0) after 1m0s
[02:09:40.193] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:5:0)
[02:09:40.193] Timer tick, asking Buildbucket for the build status
[02:09:40.358] Build 8896852665847462112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:09:40.358] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:6:0) after 1m0s
[02:10:40.413] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:6:0)
[02:10:40.413] Timer tick, asking Buildbucket for the build status
[02:10:40.629] Build 8896852665847462112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:10:40.629] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:7:0) after 1m0s
[02:11:40.696] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:7:0)
[02:11:40.696] Timer tick, asking Buildbucket for the build status
[02:11:40.968] Build 8896852665847462112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:11:40.968] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:8:0) after 1m0s
[02:12:40.995] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:8:0)
[02:12:40.995] Timer tick, asking Buildbucket for the build status
[02:12:41.502] Build 8896852665847462112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:12:41.502] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:9:0) after 1m0s
[02:13:41.535] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:9:0)
[02:13:41.535] Timer tick, asking Buildbucket for the build status
[02:13:41.687] Build 8896852665847462112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:13:41.687] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:10:0) after 1m0s
[02:14:41.723] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:10:0)
[02:14:41.723] Timer tick, asking Buildbucket for the build status
[02:14:42.165] Build 8896852665847462112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:14:42.165] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:11:0) after 1m0s
[02:15:42.236] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:11:0)
[02:15:42.236] Timer tick, asking Buildbucket for the build status
[02:15:42.551] Build 8896852665847462112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:15:42.551] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:12:0) after 1m0s
[02:16:42.595] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:12:0)
[02:16:42.595] Timer tick, asking Buildbucket for the build status
[02:16:43.420] Build 8896852665847462112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:16:43.420] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:13:0) after 1m0s
[02:17:43.480] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:13:0)
[02:17:43.480] Timer tick, asking Buildbucket for the build status
[02:17:44.044] Build 8896852665847462112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:17:44.044] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:14:0) after 1m0s
[02:18:44.087] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:14:0)
[02:18:44.087] Timer tick, asking Buildbucket for the build status
[02:18:45.112] Build 8896852665847462112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:18:45.112] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:15:0) after 1m0s
[02:19:45.143] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:15:0)
[02:19:45.143] Timer tick, asking Buildbucket for the build status
[02:19:45.335] Build 8896852665847462112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:19:45.335] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:16:0) after 1m0s
[02:20:45.376] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:16:0)
[02:20:45.376] Timer tick, asking Buildbucket for the build status
[02:20:45.644] Build 8896852665847462112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:20:45.644] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:17:0) after 1m0s
[02:21:45.884] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:17:0)
[02:21:45.884] Timer tick, asking Buildbucket for the build status
[02:21:46.329] Build 8896852665847462112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:21:46.329] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:18:0) after 1m0s
[02:22:46.431] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:18:0)
[02:22:46.431] Timer tick, asking Buildbucket for the build status
[02:22:46.861] Build 8896852665847462112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:22:46.861] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:19:0) after 1m0s
[02:23:46.887] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:19:0)
[02:23:46.887] Timer tick, asking Buildbucket for the build status
[02:23:47.467] Build 8896852665847462112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:23:47.467] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:20:0) after 1m0s
[02:24:47.506] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:20:0)
[02:24:47.506] Timer tick, asking Buildbucket for the build status
[02:24:47.954] Build 8896852665847462112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:24:47.954] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:21:0) after 1m0s
[02:25:48.068] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:21:0)
[02:25:48.068] Timer tick, asking Buildbucket for the build status
[02:25:48.337] Build 8896852665847462112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:25:48.337] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:22:0) after 1m0s
[02:26:48.453] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:22:0)
[02:26:48.453] Timer tick, asking Buildbucket for the build status
[02:26:48.766] Build 8896852665847462112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:26:48.766] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:23:0) after 1m0s
[02:27:48.897] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:23:0)
[02:27:48.897] Timer tick, asking Buildbucket for the build status
[02:27:49.270] Build 8896852665847462112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:27:49.270] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:24:0) after 1m0s
[02:28:49.302] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:24:0)
[02:28:49.302] Timer tick, asking Buildbucket for the build status
[02:28:51.054] Build 8896852665847462112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:28:51.054] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:25:0) after 1m0s
[02:29:51.124] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:25:0)
[02:29:51.124] Timer tick, asking Buildbucket for the build status
[02:29:51.352] Build 8896852665847462112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:29:51.352] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:26:0) after 1m0s
[02:30:51.504] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:26:0)
[02:30:51.505] Timer tick, asking Buildbucket for the build status
[02:30:51.601] Build 8896852665847462112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:30:51.601] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:27:0) after 1m0s
[02:31:51.665] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:27:0)
[02:31:51.665] Timer tick, asking Buildbucket for the build status
[02:31:51.824] Build 8896852665847462112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:31:51.824] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:28:0) after 1m0s
[02:32:51.844] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:28:0)
[02:32:51.844] Timer tick, asking Buildbucket for the build status
[02:32:52.028] Build 8896852665847462112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:32:52.028] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:29:0) after 1m0s
[02:33:52.037] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:29:0)
[02:33:52.037] Timer tick, asking Buildbucket for the build status
[02:33:52.319] Build 8896852665847462112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:33:52.319] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:30:0) after 1m0s
[02:34:52.391] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:30:0)
[02:34:52.391] Timer tick, asking Buildbucket for the build status
[02:34:53.470] Build 8896852665847462112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:34:53.470] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:31:0) after 1m0s
[02:35:53.609] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:31:0)
[02:35:53.609] Timer tick, asking Buildbucket for the build status
[02:35:53.726] Build 8896852665847462112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:35:53.726] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:32:0) after 1m0s
[02:36:53.793] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:32:0)
[02:36:53.793] Timer tick, asking Buildbucket for the build status
[02:36:53.962] Build 8896852665847462112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:36:53.962] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:33:0) after 1m0s
[02:37:53.988] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:33:0)
[02:37:53.988] Timer tick, asking Buildbucket for the build status
[02:37:54.210] Build 8896852665847462112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:37:54.210] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:34:0) after 1m0s
[02:38:54.295] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:34:0)
[02:38:54.295] Timer tick, asking Buildbucket for the build status
[02:38:54.468] Build 8896852665847462112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:38:54.468] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:35:0) after 1m0s
[02:39:54.501] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:35:0)
[02:39:54.501] Timer tick, asking Buildbucket for the build status
[02:39:56.247] Build 8896852665847462112: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:39:56.247] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:36:0) after 1m0s
[02:40:04.496] Received PubSub notification, asking Buildbucket for the build status
[02:40:05.739] Build 8896852665847462112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:40:56.355] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:36:0)
[02:40:56.355] Timer tick, asking Buildbucket for the build status
[02:40:57.218] Build 8896852665847462112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:40:57.218] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:38:0) after 1m0s
[02:41:57.230] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:38:0)
[02:41:57.230] Timer tick, asking Buildbucket for the build status
[02:41:57.454] Build 8896852665847462112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:41:57.454] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:39:0) after 1m0s
[02:42:57.437] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:39:0)
[02:42:57.437] Timer tick, asking Buildbucket for the build status
[02:42:57.782] Build 8896852665847462112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:42:57.782] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:40:0) after 1m0s
[02:43:57.804] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:40:0)
[02:43:57.804] Timer tick, asking Buildbucket for the build status
[02:43:58.232] Build 8896852665847462112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:43:58.232] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:41:0) after 1m0s
[02:44:58.266] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:41:0)
[02:44:58.266] Timer tick, asking Buildbucket for the build status
[02:44:58.493] Build 8896852665847462112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:44:58.493] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:42:0) after 1m0s
[02:45:58.540] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:42:0)
[02:45:58.540] Timer tick, asking Buildbucket for the build status
[02:46:00.093] Build 8896852665847462112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:46:00.093] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:43:0) after 1m0s
[02:47:00.178] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:43:0)
[02:47:00.178] Timer tick, asking Buildbucket for the build status
[02:47:00.451] Build 8896852665847462112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:47:00.451] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:44:0) after 1m0s
[02:48:00.460] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:44:0)
[02:48:00.460] Timer tick, asking Buildbucket for the build status
[02:48:00.786] Build 8896852665847462112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:48:00.786] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:45:0) after 1m0s
[02:49:00.887] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:45:0)
[02:49:00.887] Timer tick, asking Buildbucket for the build status
[02:49:01.104] Build 8896852665847462112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:49:01.104] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:46:0) after 1m0s
[02:50:01.171] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:46:0)
[02:50:01.171] Timer tick, asking Buildbucket for the build status
[02:50:02.349] Build 8896852665847462112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:50:02.349] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:47:0) after 1m0s
[02:51:02.457] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:47:0)
[02:51:02.457] Timer tick, asking Buildbucket for the build status
[02:51:02.618] Build 8896852665847462112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:51:02.618] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:48:0) after 1m0s
[02:52:02.940] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:48:0)
[02:52:02.940] Timer tick, asking Buildbucket for the build status
[02:52:03.335] Build 8896852665847462112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:52:03.335] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:49:0) after 1m0s
[02:53:03.411] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:49:0)
[02:53:03.411] Timer tick, asking Buildbucket for the build status
[02:53:04.575] Build 8896852665847462112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:53:04.575] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:50:0) after 1m0s
[02:54:04.939] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:50:0)
[02:54:04.939] Timer tick, asking Buildbucket for the build status
[02:54:05.419] Build 8896852665847462112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:54:05.419] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:51:0) after 1m0s
[02:55:05.784] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:51:0)
[02:55:05.784] Timer tick, asking Buildbucket for the build status
[02:55:06.033] Build 8896852665847462112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:55:06.033] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:52:0) after 1m0s
[02:56:06.370] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:52:0)
[02:56:06.370] Timer tick, asking Buildbucket for the build status
[02:56:06.614] Build 8896852665847462112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:56:06.614] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:53:0) after 1m0s
[02:57:06.659] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:53:0)
[02:57:06.659] Timer tick, asking Buildbucket for the build status
[02:57:07.081] Build 8896852665847462112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:57:07.081] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:54:0) after 1m0s
[02:58:07.333] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:54:0)
[02:58:07.333] Timer tick, asking Buildbucket for the build status
[02:58:07.566] Build 8896852665847462112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:58:07.566] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:55:0) after 1m0s
[02:59:07.756] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:55:0)
[02:59:07.756] Timer tick, asking Buildbucket for the build status
[02:59:07.829] Build 8896852665847462112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:59:07.829] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:56:0) after 1m0s
[03:00:07.878] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:56:0)
[03:00:07.878] Timer tick, asking Buildbucket for the build status
[03:00:08.155] Build 8896852665847462112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:00:08.155] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:57:0) after 1m0s
[03:01:08.225] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:57:0)
[03:01:08.225] Timer tick, asking Buildbucket for the build status
[03:01:08.619] Build 8896852665847462112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:01:08.619] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:58:0) after 1m0s
[03:02:08.757] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:58:0)
[03:02:08.757] Timer tick, asking Buildbucket for the build status
[03:02:08.947] Build 8896852665847462112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:02:08.947] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:59:0) after 1m0s
[03:03:09.201] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:59:0)
[03:03:09.201] Timer tick, asking Buildbucket for the build status
[03:03:09.505] Build 8896852665847462112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:03:09.505] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:60:0) after 1m0s
[03:04:09.583] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:60:0)
[03:04:09.583] Timer tick, asking Buildbucket for the build status
[03:04:09.722] Build 8896852665847462112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:04:09.722] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:61:0) after 1m0s
[03:05:10.012] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:61:0)
[03:05:10.012] Timer tick, asking Buildbucket for the build status
[03:05:10.285] Build 8896852665847462112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:05:10.285] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:62:0) after 1m0s
[03:06:10.392] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:62:0)
[03:06:10.392] Timer tick, asking Buildbucket for the build status
[03:06:10.541] Build 8896852665847462112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:06:10.541] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:63:0) after 1m0s
[03:07:10.699] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:63:0)
[03:07:10.699] Timer tick, asking Buildbucket for the build status
[03:07:10.997] Build 8896852665847462112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:07:10.997] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:64:0) after 1m0s
[03:08:11.246] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:64:0)
[03:08:11.246] Timer tick, asking Buildbucket for the build status
[03:08:11.388] Build 8896852665847462112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:08:11.388] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:65:0) after 1m0s
[03:09:11.716] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:65:0)
[03:09:11.716] Timer tick, asking Buildbucket for the build status
[03:09:11.851] Build 8896852665847462112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:09:11.851] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:66:0) after 1m0s
[03:10:11.940] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:66:0)
[03:10:11.940] Timer tick, asking Buildbucket for the build status
[03:10:12.083] Build 8896852665847462112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:10:12.083] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:67:0) after 1m0s
[03:11:12.136] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:67:0)
[03:11:12.136] Timer tick, asking Buildbucket for the build status
[03:11:14.273] Build 8896852665847462112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:11:14.273] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:68:0) after 1m0s
[03:12:14.445] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:68:0)
[03:12:14.445] Timer tick, asking Buildbucket for the build status
[03:12:15.199] Build 8896852665847462112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:12:15.199] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:69:0) after 1m0s
[03:13:15.277] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:69:0)
[03:13:15.277] Timer tick, asking Buildbucket for the build status
[03:13:15.656] Build 8896852665847462112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:13:15.656] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:70:0) after 1m0s
[03:14:15.732] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:70:0)
[03:14:15.732] Timer tick, asking Buildbucket for the build status
[03:14:15.995] Build 8896852665847462112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:14:15.995] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:71:0) after 1m0s
[03:15:16.095] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:71:0)
[03:15:16.095] Timer tick, asking Buildbucket for the build status
[03:15:19.179] Build 8896852665847462112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:15:19.179] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:72:0) after 1m0s
[03:16:19.411] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:72:0)
[03:16:19.411] Timer tick, asking Buildbucket for the build status
[03:16:19.608] Build 8896852665847462112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:16:19.608] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:73:0) after 1m0s
[03:17:19.795] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:73:0)
[03:17:19.795] Timer tick, asking Buildbucket for the build status
[03:17:20.032] Build 8896852665847462112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:17:20.032] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:74:0) after 1m0s
[03:18:20.052] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:74:0)
[03:18:20.052] Timer tick, asking Buildbucket for the build status
[03:18:20.213] Build 8896852665847462112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:18:20.213] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:75:0) after 1m0s
[03:19:20.257] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:75:0)
[03:19:20.257] Timer tick, asking Buildbucket for the build status
[03:19:20.448] Build 8896852665847462112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:19:20.448] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:76:0) after 1m0s
[03:20:20.520] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:76:0)
[03:20:20.520] Timer tick, asking Buildbucket for the build status
[03:20:20.700] Build 8896852665847462112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:20:20.700] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:77:0) after 1m0s
[03:21:20.952] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:77:0)
[03:21:20.952] Timer tick, asking Buildbucket for the build status
[03:21:21.214] Build 8896852665847462112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:21:21.214] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:78:0) after 1m0s
[03:22:21.326] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:78:0)
[03:22:21.326] Timer tick, asking Buildbucket for the build status
[03:22:21.685] Build 8896852665847462112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:22:21.685] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:79:0) after 1m0s
[03:23:21.878] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:79:0)
[03:23:21.878] Timer tick, asking Buildbucket for the build status
[03:23:22.188] Build 8896852665847462112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:23:22.188] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:80:0) after 1m0s
[03:24:22.273] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:80:0)
[03:24:22.273] Timer tick, asking Buildbucket for the build status
[03:24:23.192] Build 8896852665847462112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:24:23.192] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:81:0) after 1m0s
[03:25:23.281] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:81:0)
[03:25:23.281] Timer tick, asking Buildbucket for the build status
[03:25:23.520] Build 8896852665847462112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:25:23.520] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:82:0) after 1m0s
[03:26:23.762] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:82:0)
[03:26:23.762] Timer tick, asking Buildbucket for the build status
[03:26:23.940] Build 8896852665847462112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:26:23.940] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:83:0) after 1m0s
[03:27:23.969] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:83:0)
[03:27:23.969] Timer tick, asking Buildbucket for the build status
[03:27:24.111] Build 8896852665847462112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:27:24.111] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:84:0) after 1m0s
[03:28:24.278] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:84:0)
[03:28:24.278] Timer tick, asking Buildbucket for the build status
[03:28:24.444] Build 8896852665847462112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:28:24.444] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:85:0) after 1m0s
[03:29:24.670] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:85:0)
[03:29:24.670] Timer tick, asking Buildbucket for the build status
[03:29:25.624] Build 8896852665847462112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:29:25.625] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:86:0) after 1m0s
[03:30:25.765] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:86:0)
[03:30:25.765] Timer tick, asking Buildbucket for the build status
[03:30:25.984] Build 8896852665847462112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:30:25.984] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:87:0) after 1m0s
[03:31:26.137] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:87:0)
[03:31:26.137] Timer tick, asking Buildbucket for the build status
[03:31:30.290] Build 8896852665847462112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:31:30.290] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:88:0) after 1m0s
[03:32:30.351] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:88:0)
[03:32:30.351] Timer tick, asking Buildbucket for the build status
[03:32:30.826] Build 8896852665847462112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:32:30.826] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:89:0) after 1m0s
[03:33:30.878] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:89:0)
[03:33:30.878] Timer tick, asking Buildbucket for the build status
[03:33:31.349] Build 8896852665847462112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:33:31.350] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:90:0) after 1m0s
[03:34:31.396] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:90:0)
[03:34:31.396] Timer tick, asking Buildbucket for the build status
[03:34:31.652] Build 8896852665847462112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:34:31.652] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:91:0) after 1m0s
[03:35:31.658] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:91:0)
[03:35:31.658] Timer tick, asking Buildbucket for the build status
[03:35:33.075] Build 8896852665847462112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:35:33.075] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:92:0) after 1m0s
[03:36:33.105] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:92:0)
[03:36:33.105] Timer tick, asking Buildbucket for the build status
[03:36:33.418] Build 8896852665847462112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:36:33.418] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:93:0) after 1m0s
[03:37:33.403] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:93:0)
[03:37:33.403] Timer tick, asking Buildbucket for the build status
[03:37:33.599] Build 8896852665847462112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:37:33.599] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:94:0) after 1m0s
[03:38:33.684] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:94:0)
[03:38:33.684] Timer tick, asking Buildbucket for the build status
[03:38:33.788] Build 8896852665847462112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:38:33.788] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:95:0) after 1m0s
[03:39:33.925] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:95:0)
[03:39:33.925] Timer tick, asking Buildbucket for the build status
[03:39:34.493] Build 8896852665847462112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:39:34.493] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:96:0) after 1m0s
[03:40:34.756] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:96:0)
[03:40:34.756] Timer tick, asking Buildbucket for the build status
[03:40:34.923] Build 8896852665847462112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:40:34.923] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:97:0) after 1m0s
[03:41:35.077] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:97:0)
[03:41:35.077] Timer tick, asking Buildbucket for the build status
[03:41:35.416] Build 8896852665847462112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:41:35.416] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:98:0) after 1m0s
[03:42:35.554] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:98:0)
[03:42:35.554] Timer tick, asking Buildbucket for the build status
[03:42:35.851] Build 8896852665847462112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:42:35.851] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:99:0) after 1m0s
[03:43:35.945] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:99:0)
[03:43:35.945] Timer tick, asking Buildbucket for the build status
[03:43:36.103] Build 8896852665847462112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:43:36.103] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:100:0) after 1m0s
[03:44:36.354] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:100:0)
[03:44:36.354] Timer tick, asking Buildbucket for the build status
[03:44:41.893] Build 8896852665847462112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:44:41.893] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:101:0) after 1m0s
[03:45:41.966] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:101:0)
[03:45:41.966] Timer tick, asking Buildbucket for the build status
[03:45:43.067] Build 8896852665847462112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:45:43.067] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:102:0) after 1m0s
[03:46:43.213] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:102:0)
[03:46:43.213] Timer tick, asking Buildbucket for the build status
[03:46:43.397] Build 8896852665847462112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:46:43.397] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:103:0) after 1m0s
[03:47:43.414] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:103:0)
[03:47:43.414] Timer tick, asking Buildbucket for the build status
[03:47:43.602] Build 8896852665847462112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:47:43.602] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:104:0) after 1m0s
[03:48:43.628] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:104:0)
[03:48:43.628] Timer tick, asking Buildbucket for the build status
[03:48:44.112] Build 8896852665847462112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:48:44.112] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:105:0) after 1m0s
[03:49:44.242] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:105:0)
[03:49:44.242] Timer tick, asking Buildbucket for the build status
[03:49:44.458] Build 8896852665847462112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:49:44.458] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:106:0) after 1m0s
[03:50:44.496] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:106:0)
[03:50:44.496] Timer tick, asking Buildbucket for the build status
[03:50:45.037] Build 8896852665847462112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:50:45.037] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:107:0) after 1m0s
[03:51:45.148] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:107:0)
[03:51:45.148] Timer tick, asking Buildbucket for the build status
[03:51:45.262] Build 8896852665847462112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:51:45.262] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:108:0) after 1m0s
[03:52:45.449] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:108:0)
[03:52:45.449] Timer tick, asking Buildbucket for the build status
[03:52:45.746] Build 8896852665847462112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:52:45.746] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:109:0) after 1m0s
[03:53:45.900] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:109:0)
[03:53:45.900] Timer tick, asking Buildbucket for the build status
[03:53:46.266] Build 8896852665847462112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:53:46.266] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:110:0) after 1m0s
[03:54:46.348] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:110:0)
[03:54:46.348] Timer tick, asking Buildbucket for the build status
[03:54:46.551] Build 8896852665847462112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:54:46.551] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:111:0) after 1m0s
[03:55:46.654] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:111:0)
[03:55:46.654] Timer tick, asking Buildbucket for the build status
[03:55:46.962] Build 8896852665847462112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:55:46.962] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:112:0) after 1m0s
[03:56:47.046] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:112:0)
[03:56:47.046] Timer tick, asking Buildbucket for the build status
[03:56:47.202] Build 8896852665847462112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:56:47.202] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:113:0) after 1m0s
[03:57:47.265] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:113:0)
[03:57:47.265] Timer tick, asking Buildbucket for the build status
[03:57:47.664] Build 8896852665847462112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:57:47.664] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:114:0) after 1m0s
[03:58:47.701] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:114:0)
[03:58:47.701] Timer tick, asking Buildbucket for the build status
[03:58:48.012] Build 8896852665847462112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:58:48.012] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:115:0) after 1m0s
[03:59:48.113] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:115:0)
[03:59:48.113] Timer tick, asking Buildbucket for the build status
[03:59:48.333] Build 8896852665847462112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:59:48.333] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:116:0) after 1m0s
[04:00:48.463] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:116:0)
[04:00:48.463] Timer tick, asking Buildbucket for the build status
[04:00:48.739] Build 8896852665847462112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:00:48.739] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:117:0) after 1m0s
[04:01:48.895] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:117:0)
[04:01:48.895] Timer tick, asking Buildbucket for the build status
[04:01:49.148] Build 8896852665847462112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:01:49.148] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:118:0) after 1m0s
[04:02:49.187] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:118:0)
[04:02:49.187] Timer tick, asking Buildbucket for the build status
[04:02:49.596] Build 8896852665847462112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:02:49.596] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:119:0) after 1m0s
[04:03:49.658] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:119:0)
[04:03:49.658] Timer tick, asking Buildbucket for the build status
[04:03:50.057] Build 8896852665847462112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:03:50.057] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:120:0) after 1m0s
[04:04:50.065] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:120:0)
[04:04:50.065] Timer tick, asking Buildbucket for the build status
[04:04:50.246] Build 8896852665847462112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:04:50.246] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:121:0) after 1m0s
[04:05:50.313] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:121:0)
[04:05:50.313] Timer tick, asking Buildbucket for the build status
[04:05:50.428] Build 8896852665847462112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:05:50.428] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:122:0) after 1m0s
[04:06:50.488] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:122:0)
[04:06:50.488] Timer tick, asking Buildbucket for the build status
[04:06:50.855] Build 8896852665847462112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:06:50.855] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:123:0) after 1m0s
[04:07:50.997] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:123:0)
[04:07:50.997] Timer tick, asking Buildbucket for the build status
[04:07:51.255] Build 8896852665847462112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:07:51.255] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:124:0) after 1m0s
[04:08:51.288] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:124:0)
[04:08:51.288] Timer tick, asking Buildbucket for the build status
[04:08:51.513] Build 8896852665847462112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:08:51.513] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:125:0) after 1m0s
[04:09:51.607] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:125:0)
[04:09:51.607] Timer tick, asking Buildbucket for the build status
[04:09:51.855] Build 8896852665847462112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:09:51.855] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:126:0) after 1m0s
[04:10:51.910] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:126:0)
[04:10:51.910] Timer tick, asking Buildbucket for the build status
[04:10:55.105] Build 8896852665847462112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:10:55.105] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:127:0) after 1m0s
[04:11:55.145] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:127:0)
[04:11:55.145] Timer tick, asking Buildbucket for the build status
[04:11:55.432] Build 8896852665847462112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:11:55.432] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:128:0) after 1m0s
[04:12:55.418] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:128:0)
[04:12:55.418] Timer tick, asking Buildbucket for the build status
[04:12:55.636] Build 8896852665847462112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:12:55.636] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:129:0) after 1m0s
[04:13:55.666] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:129:0)
[04:13:55.666] Timer tick, asking Buildbucket for the build status
[04:13:55.866] Build 8896852665847462112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:13:55.866] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:130:0) after 1m0s
[04:14:55.928] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:130:0)
[04:14:55.928] Timer tick, asking Buildbucket for the build status
[04:14:56.089] Build 8896852665847462112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:14:56.089] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9062282727915613888:131:0) after 1m0s
[04:15:40.120] Received PubSub notification, asking Buildbucket for the build status
[04:15:40.307] Build 8896852665847462112: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[04:15:40.307] Buildbucket build:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary_preference": "PROD",
"completed_ts": "1573704939262902",
"created_by": "project:emscripten-releases",
"created_ts": "1573697137938290",
"id": "8896852665847462112",
"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\": \"689bc6b13c59d1710b51f938ddfc56fa480d2e59\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@689bc6b13c59d1710b51f938ddfc56fa480d2e59\", \"title\": \"689bc6b13c59d1710b51f938ddfc56fa480d2e59\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/689bc6b13c59d1710b51f938ddfc56fa480d2e59\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"689bc6b13c59d1710b51f938ddfc56fa480d2e59\"}}",
"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\": \"689bc6b13c59d1710b51f938ddfc56fa480d2e59\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@689bc6b13c59d1710b51f938ddfc56fa480d2e59\", \"title\": \"689bc6b13c59d1710b51f938ddfc56fa480d2e59\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/689bc6b13c59d1710b51f938ddfc56fa480d2e59\"}]}, \"bot_id\": \"luci-emscripten-releases-ci-win10-0-se7t\", \"branch\": \"refs/heads/master\", \"buildername\": \"win\", \"got_revision\": \"689bc6b13c59d1710b51f938ddfc56fa480d2e59\", \"path_config\": \"generic\", \"recipe\": \"emscripten_releases\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"689bc6b13c59d1710b51f938ddfc56fa480d2e59\"}, \"swarming\": {\"bot_dimensions\": {\"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"host_class\": [\"default\"], \"id\": [\"luci-emscripten-releases-ci-win10-0-se7t\"], \"image\": [\"chrome-win10-1703-19102400-d35fedf5a0d\"], \"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\": [\"4628-14f2294\"], \"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": "1573699202233935",
"status": "COMPLETED",
"status_changed_ts": "1573704939780816",
"tags": [
"builder:win",
"buildset:commit/git/689bc6b13c59d1710b51f938ddfc56fa480d2e59",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/689bc6b13c59d1710b51f938ddfc56fa480d2e59",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9062282727915613888",
"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/8896852665847462112/+/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:48807a78ffd86f10",
"user_agent:luci-scheduler"
],
"updated_ts": "1573704939812620",
"url": "https://ci.chromium.org/b/8896852665847462112",
"utcnow_ts": "1573704940293181"
}
}
[04:15:40.307] Invocation finished in 2h10m3.725406392s with status SUCCEEDED