[05:29:30.575] New invocation is queued and will start shortly
[05:29:33.863] Starting the invocation (attempt 1)
[05:29:33.928] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[05:29:33.928] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[05:29:33.928] Buildbucket request:
{
"bucket": "luci.emscripten-releases.ci",
"client_operation_id": "9061545123948334496",
"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\":\"3efd9db648cfe373518631afa02b3db8c8778545\"},\"id\":\"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@3efd9db648cfe373518631afa02b3db8c8778545\",\"title\":\"3efd9db648cfe373518631afa02b3db8c8778545\",\"url\":\"https://chromium.googlesource.com/emscripten-releases/+/3efd9db648cfe373518631afa02b3db8c8778545\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/emscripten-releases\",\"revision\":\"3efd9db648cfe373518631afa02b3db8c8778545\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win",
"scheduler_invocation_id:9061545123948334496",
"scheduler_job_id:emscripten-releases/win",
"user_agent:luci-scheduler",
"buildset:commit/git/3efd9db648cfe373518631afa02b3db8c8778545",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/3efd9db648cfe373518631afa02b3db8c8778545",
"gitiles_ref:refs/heads/master"
]
}
[05:29:35.048] Buildbucket response:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary_preference": "PROD",
"created_by": "project:emscripten-releases",
"created_ts": "1574400574069010",
"id": "8896115059603653536",
"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\": \"3efd9db648cfe373518631afa02b3db8c8778545\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@3efd9db648cfe373518631afa02b3db8c8778545\", \"title\": \"3efd9db648cfe373518631afa02b3db8c8778545\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/3efd9db648cfe373518631afa02b3db8c8778545\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"3efd9db648cfe373518631afa02b3db8c8778545\"}}",
"project": "emscripten-releases",
"result_details_json": "{\"properties\": {}}",
"service_account": "emscripten-releases-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1574400574738805",
"tags": [
"builder:win",
"buildset:commit/git/3efd9db648cfe373518631afa02b3db8c8778545",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/3efd9db648cfe373518631afa02b3db8c8778545",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9061545123948334496",
"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/8896115059603653536/+/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": "1574400574738996",
"url": "https://ci.chromium.org/b/8896115059603653536",
"utcnow_ts": "1574400575039686"
}
}
[05:29:35.048] Task URL: https://ci.chromium.org/b/8896115059603653536
[05:29:35.048] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:2:0) after 1m0s
[05:30:00.684] Received PubSub notification, asking Buildbucket for the build status
[05:30:00.933] Build 8896115059603653536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:30:35.036] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:2:0)
[05:30:35.036] Timer tick, asking Buildbucket for the build status
[05:30:35.213] Build 8896115059603653536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:30:35.213] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:4:0) after 1m0s
[05:31:35.246] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:4:0)
[05:31:35.246] Timer tick, asking Buildbucket for the build status
[05:31:35.626] Build 8896115059603653536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:31:35.626] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:5:0) after 1m0s
[05:32:35.616] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:5:0)
[05:32:35.616] Timer tick, asking Buildbucket for the build status
[05:32:35.849] Build 8896115059603653536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:32:35.849] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:6:0) after 1m0s
[05:33:35.892] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:6:0)
[05:33:35.892] Timer tick, asking Buildbucket for the build status
[05:33:36.106] Build 8896115059603653536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:33:36.106] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:7:0) after 1m0s
[05:34:36.189] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:7:0)
[05:34:36.189] Timer tick, asking Buildbucket for the build status
[05:34:36.326] Build 8896115059603653536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:34:36.326] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:8:0) after 1m0s
[05:35:36.355] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:8:0)
[05:35:36.355] Timer tick, asking Buildbucket for the build status
[05:35:36.534] Build 8896115059603653536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:35:36.534] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:9:0) after 1m0s
[05:36:36.595] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:9:0)
[05:36:36.595] Timer tick, asking Buildbucket for the build status
[05:36:36.851] Build 8896115059603653536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:36:36.851] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:10:0) after 1m0s
[05:37:36.914] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:10:0)
[05:37:36.915] Timer tick, asking Buildbucket for the build status
[05:37:37.034] Build 8896115059603653536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:37:37.034] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:11:0) after 1m0s
[05:38:37.123] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:11:0)
[05:38:37.123] Timer tick, asking Buildbucket for the build status
[05:38:37.805] Build 8896115059603653536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:38:37.805] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:12:0) after 1m0s
[05:39:37.893] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:12:0)
[05:39:37.893] Timer tick, asking Buildbucket for the build status
[05:39:38.023] Build 8896115059603653536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:39:38.023] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:13:0) after 1m0s
[05:40:38.093] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:13:0)
[05:40:38.093] Timer tick, asking Buildbucket for the build status
[05:40:38.219] Build 8896115059603653536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:40:38.219] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:14:0) after 1m0s
[05:41:38.324] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:14:0)
[05:41:38.324] Timer tick, asking Buildbucket for the build status
[05:41:38.473] Build 8896115059603653536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:41:38.473] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:15:0) after 1m0s
[05:42:38.529] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:15:0)
[05:42:38.529] Timer tick, asking Buildbucket for the build status
[05:42:38.720] Build 8896115059603653536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:42:38.720] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:16:0) after 1m0s
[05:43:38.775] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:16:0)
[05:43:38.775] Timer tick, asking Buildbucket for the build status
[05:43:39.275] Build 8896115059603653536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:43:39.275] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:17:0) after 1m0s
[05:44:39.369] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:17:0)
[05:44:39.369] Timer tick, asking Buildbucket for the build status
[05:44:39.656] Build 8896115059603653536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:44:39.656] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:18:0) after 1m0s
[05:45:39.771] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:18:0)
[05:45:39.771] Timer tick, asking Buildbucket for the build status
[05:45:40.112] Build 8896115059603653536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:45:40.112] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:19:0) after 1m0s
[05:46:40.186] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:19:0)
[05:46:40.186] Timer tick, asking Buildbucket for the build status
[05:46:40.462] Build 8896115059603653536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:46:40.462] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:20:0) after 1m0s
[05:47:40.593] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:20:0)
[05:47:40.593] Timer tick, asking Buildbucket for the build status
[05:47:40.808] Build 8896115059603653536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:47:40.808] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:21:0) after 1m0s
[05:48:40.923] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:21:0)
[05:48:40.923] Timer tick, asking Buildbucket for the build status
[05:48:41.070] Build 8896115059603653536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:48:41.070] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:22:0) after 1m0s
[05:49:41.217] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:22:0)
[05:49:41.217] Timer tick, asking Buildbucket for the build status
[05:49:41.436] Build 8896115059603653536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:49:41.436] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:23:0) after 1m0s
[05:50:41.500] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:23:0)
[05:50:41.500] Timer tick, asking Buildbucket for the build status
[05:50:41.651] Build 8896115059603653536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:50:41.651] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:24:0) after 1m0s
[05:51:41.718] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:24:0)
[05:51:41.718] Timer tick, asking Buildbucket for the build status
[05:51:41.861] Build 8896115059603653536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:51:41.861] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:25:0) after 1m0s
[05:52:41.842] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:25:0)
[05:52:41.842] Timer tick, asking Buildbucket for the build status
[05:52:42.008] Build 8896115059603653536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:52:42.008] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:26:0) after 1m0s
[05:53:42.062] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:26:0)
[05:53:42.062] Timer tick, asking Buildbucket for the build status
[05:53:42.646] Build 8896115059603653536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:53:42.646] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:27:0) after 1m0s
[05:54:42.815] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:27:0)
[05:54:42.815] Timer tick, asking Buildbucket for the build status
[05:54:42.926] Build 8896115059603653536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:54:42.926] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:28:0) after 1m0s
[05:55:42.985] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:28:0)
[05:55:42.985] Timer tick, asking Buildbucket for the build status
[05:55:43.149] Build 8896115059603653536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:55:43.149] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:29:0) after 1m0s
[05:56:43.185] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:29:0)
[05:56:43.185] Timer tick, asking Buildbucket for the build status
[05:56:43.404] Build 8896115059603653536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:56:43.405] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:30:0) after 1m0s
[05:57:43.458] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:30:0)
[05:57:43.458] Timer tick, asking Buildbucket for the build status
[05:57:43.850] Build 8896115059603653536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:57:43.850] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:31:0) after 1m0s
[05:58:43.979] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:31:0)
[05:58:43.979] Timer tick, asking Buildbucket for the build status
[05:58:44.295] Build 8896115059603653536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:58:44.295] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:32:0) after 1m0s
[05:59:44.351] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:32:0)
[05:59:44.351] Timer tick, asking Buildbucket for the build status
[05:59:44.491] Build 8896115059603653536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:59:44.491] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:33:0) after 1m0s
[06:00:44.499] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:33:0)
[06:00:44.499] Timer tick, asking Buildbucket for the build status
[06:00:44.581] Build 8896115059603653536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:00:44.581] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:34:0) after 1m0s
[06:01:44.700] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:34:0)
[06:01:44.700] Timer tick, asking Buildbucket for the build status
[06:01:44.908] Build 8896115059603653536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:01:44.908] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:35:0) after 1m0s
[06:02:44.970] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:35:0)
[06:02:44.970] Timer tick, asking Buildbucket for the build status
[06:02:45.272] Build 8896115059603653536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:02:45.272] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:36:0) after 1m0s
[06:03:45.300] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:36:0)
[06:03:45.300] Timer tick, asking Buildbucket for the build status
[06:03:45.453] Build 8896115059603653536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:03:45.453] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:37:0) after 1m0s
[06:04:45.500] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:37:0)
[06:04:45.500] Timer tick, asking Buildbucket for the build status
[06:04:45.900] Build 8896115059603653536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:04:45.900] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:38:0) after 1m0s
[06:05:46.020] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:38:0)
[06:05:46.021] Timer tick, asking Buildbucket for the build status
[06:05:46.334] Build 8896115059603653536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:05:46.334] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:39:0) after 1m0s
[06:06:46.427] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:39:0)
[06:06:46.427] Timer tick, asking Buildbucket for the build status
[06:06:46.605] Build 8896115059603653536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:06:46.605] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:40:0) after 1m0s
[06:07:46.631] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:40:0)
[06:07:46.631] Timer tick, asking Buildbucket for the build status
[06:07:46.876] Build 8896115059603653536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:07:46.876] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:41:0) after 1m0s
[06:08:46.906] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:41:0)
[06:08:46.906] Timer tick, asking Buildbucket for the build status
[06:08:49.735] Build 8896115059603653536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:08:49.735] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:42:0) after 1m0s
[06:09:49.812] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:42:0)
[06:09:49.812] Timer tick, asking Buildbucket for the build status
[06:09:50.063] Build 8896115059603653536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:09:50.063] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:43:0) after 1m0s
[06:10:50.181] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:43:0)
[06:10:50.181] Timer tick, asking Buildbucket for the build status
[06:10:50.516] Build 8896115059603653536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:10:50.516] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:44:0) after 1m0s
[06:11:50.547] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:44:0)
[06:11:50.547] Timer tick, asking Buildbucket for the build status
[06:11:51.301] Build 8896115059603653536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:11:51.302] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:45:0) after 1m0s
[06:12:51.328] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:45:0)
[06:12:51.328] Timer tick, asking Buildbucket for the build status
[06:12:51.531] Build 8896115059603653536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:12:51.531] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:46:0) after 1m0s
[06:13:51.554] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:46:0)
[06:13:51.554] Timer tick, asking Buildbucket for the build status
[06:13:51.675] Build 8896115059603653536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:13:51.675] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:47:0) after 1m0s
[06:14:51.718] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:47:0)
[06:14:51.718] Timer tick, asking Buildbucket for the build status
[06:14:51.799] Build 8896115059603653536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:14:51.799] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:48:0) after 1m0s
[06:15:51.829] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:48:0)
[06:15:51.829] Timer tick, asking Buildbucket for the build status
[06:15:52.457] Build 8896115059603653536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:15:52.457] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:49:0) after 1m0s
[06:16:52.576] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:49:0)
[06:16:52.577] Timer tick, asking Buildbucket for the build status
[06:16:52.728] Build 8896115059603653536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:16:52.728] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:50:0) after 1m0s
[06:17:52.774] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:50:0)
[06:17:52.774] Timer tick, asking Buildbucket for the build status
[06:17:53.053] Build 8896115059603653536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:17:53.053] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:51:0) after 1m0s
[06:18:53.086] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:51:0)
[06:18:53.086] Timer tick, asking Buildbucket for the build status
[06:18:53.314] Build 8896115059603653536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:18:53.314] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:52:0) after 1m0s
[06:19:53.339] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:52:0)
[06:19:53.339] Timer tick, asking Buildbucket for the build status
[06:19:53.560] Build 8896115059603653536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:19:53.560] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:53:0) after 1m0s
[06:20:53.598] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:53:0)
[06:20:53.598] Timer tick, asking Buildbucket for the build status
[06:20:53.767] Build 8896115059603653536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:20:53.767] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:54:0) after 1m0s
[06:21:53.785] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:54:0)
[06:21:53.785] Timer tick, asking Buildbucket for the build status
[06:21:54.014] Build 8896115059603653536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:21:54.014] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:55:0) after 1m0s
[06:22:54.024] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:55:0)
[06:22:54.024] Timer tick, asking Buildbucket for the build status
[06:22:54.315] Build 8896115059603653536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:22:54.315] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:56:0) after 1m0s
[06:23:54.507] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:56:0)
[06:23:54.507] Timer tick, asking Buildbucket for the build status
[06:23:54.709] Build 8896115059603653536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:23:54.709] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:57:0) after 1m0s
[06:24:54.906] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:57:0)
[06:24:54.906] Timer tick, asking Buildbucket for the build status
[06:24:55.036] Build 8896115059603653536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:24:55.036] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:58:0) after 1m0s
[06:25:55.061] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:58:0)
[06:25:55.061] Timer tick, asking Buildbucket for the build status
[06:25:55.265] Build 8896115059603653536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:25:55.265] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:59:0) after 1m0s
[06:26:55.329] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:59:0)
[06:26:55.329] Timer tick, asking Buildbucket for the build status
[06:26:55.402] Build 8896115059603653536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:26:55.402] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:60:0) after 1m0s
[06:27:55.470] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:60:0)
[06:27:55.470] Timer tick, asking Buildbucket for the build status
[06:27:55.654] Build 8896115059603653536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:27:55.654] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:61:0) after 1m0s
[06:28:55.802] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:61:0)
[06:28:55.802] Timer tick, asking Buildbucket for the build status
[06:28:55.907] Build 8896115059603653536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:28:55.907] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:62:0) after 1m0s
[06:29:55.958] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:62:0)
[06:29:55.958] Timer tick, asking Buildbucket for the build status
[06:29:56.118] Build 8896115059603653536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:29:56.118] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:63:0) after 1m0s
[06:30:56.164] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:63:0)
[06:30:56.164] Timer tick, asking Buildbucket for the build status
[06:30:56.303] Build 8896115059603653536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:30:56.303] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:64:0) after 1m0s
[06:31:56.600] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:64:0)
[06:31:56.600] Timer tick, asking Buildbucket for the build status
[06:31:56.947] Build 8896115059603653536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:31:56.947] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:65:0) after 1m0s
[06:32:57.162] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:65:0)
[06:32:57.162] Timer tick, asking Buildbucket for the build status
[06:32:57.318] Build 8896115059603653536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:32:57.318] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:66:0) after 1m0s
[06:33:57.346] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:66:0)
[06:33:57.346] Timer tick, asking Buildbucket for the build status
[06:33:57.605] Build 8896115059603653536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:33:57.605] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:67:0) after 1m0s
[06:34:57.788] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:67:0)
[06:34:57.788] Timer tick, asking Buildbucket for the build status
[06:34:58.132] Build 8896115059603653536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:34:58.132] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:68:0) after 1m0s
[06:35:58.192] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:68:0)
[06:35:58.192] Timer tick, asking Buildbucket for the build status
[06:35:58.452] Build 8896115059603653536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:35:58.452] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:69:0) after 1m0s
[06:36:58.508] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:69:0)
[06:36:58.508] Timer tick, asking Buildbucket for the build status
[06:36:59.175] Build 8896115059603653536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:36:59.175] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:70:0) after 1m0s
[06:37:59.300] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:70:0)
[06:37:59.300] Timer tick, asking Buildbucket for the build status
[06:37:59.649] Build 8896115059603653536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:37:59.649] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:71:0) after 1m0s
[06:38:59.736] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:71:0)
[06:38:59.736] Timer tick, asking Buildbucket for the build status
[06:39:00.075] Build 8896115059603653536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:39:00.075] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:72:0) after 1m0s
[06:40:00.217] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:72:0)
[06:40:00.217] Timer tick, asking Buildbucket for the build status
[06:40:00.756] Build 8896115059603653536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:40:00.756] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:73:0) after 1m0s
[06:41:00.772] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:73:0)
[06:41:00.772] Timer tick, asking Buildbucket for the build status
[06:41:01.004] Build 8896115059603653536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:41:01.004] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:74:0) after 1m0s
[06:42:01.041] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:74:0)
[06:42:01.042] Timer tick, asking Buildbucket for the build status
[06:42:01.249] Build 8896115059603653536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:42:01.249] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:75:0) after 1m0s
[06:43:01.282] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:75:0)
[06:43:01.282] Timer tick, asking Buildbucket for the build status
[06:43:01.681] Build 8896115059603653536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:43:01.681] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:76:0) after 1m0s
[06:44:01.672] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:76:0)
[06:44:01.672] Timer tick, asking Buildbucket for the build status
[06:44:03.252] Build 8896115059603653536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:44:03.252] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:77:0) after 1m0s
[06:45:03.338] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:77:0)
[06:45:03.338] Timer tick, asking Buildbucket for the build status
[06:45:03.413] Build 8896115059603653536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:45:03.413] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:78:0) after 1m0s
[06:46:03.390] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:78:0)
[06:46:03.390] Timer tick, asking Buildbucket for the build status
[06:46:03.506] Build 8896115059603653536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:46:03.506] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:79:0) after 1m0s
[06:47:03.633] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:79:0)
[06:47:03.633] Timer tick, asking Buildbucket for the build status
[06:47:03.828] Build 8896115059603653536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:47:03.828] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:80:0) after 1m0s
[06:48:03.857] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:80:0)
[06:48:03.857] Timer tick, asking Buildbucket for the build status
[06:48:04.054] Build 8896115059603653536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:48:04.054] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:81:0) after 1m0s
[06:49:04.202] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:81:0)
[06:49:04.202] Timer tick, asking Buildbucket for the build status
[06:49:04.348] Build 8896115059603653536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:49:04.348] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:82:0) after 1m0s
[06:50:04.426] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:82:0)
[06:50:04.426] Timer tick, asking Buildbucket for the build status
[06:50:04.990] Build 8896115059603653536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:50:04.990] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:83:0) after 1m0s
[06:51:05.171] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:83:0)
[06:51:05.171] Timer tick, asking Buildbucket for the build status
[06:51:05.446] Build 8896115059603653536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:51:05.446] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:84:0) after 1m0s
[06:52:05.553] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:84:0)
[06:52:05.553] Timer tick, asking Buildbucket for the build status
[06:52:05.618] Build 8896115059603653536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:52:05.618] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:85:0) after 1m0s
[06:53:05.671] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:85:0)
[06:53:05.671] Timer tick, asking Buildbucket for the build status
[06:53:06.209] Build 8896115059603653536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:53:06.209] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:86:0) after 1m0s
[06:54:06.303] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:86:0)
[06:54:06.303] Timer tick, asking Buildbucket for the build status
[06:54:06.560] Build 8896115059603653536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:54:06.560] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:87:0) after 1m0s
[06:55:06.598] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:87:0)
[06:55:06.598] Timer tick, asking Buildbucket for the build status
[06:55:06.758] Build 8896115059603653536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:55:06.758] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:88:0) after 1m0s
[06:56:06.933] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:88:0)
[06:56:06.933] Timer tick, asking Buildbucket for the build status
[06:56:07.082] Build 8896115059603653536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:56:07.082] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:89:0) after 1m0s
[06:57:07.117] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:89:0)
[06:57:07.117] Timer tick, asking Buildbucket for the build status
[06:57:07.278] Build 8896115059603653536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:57:07.279] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:90:0) after 1m0s
[06:58:07.349] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:90:0)
[06:58:07.349] Timer tick, asking Buildbucket for the build status
[06:58:07.440] Build 8896115059603653536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:58:07.440] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:91:0) after 1m0s
[06:59:07.499] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:91:0)
[06:59:07.499] Timer tick, asking Buildbucket for the build status
[06:59:07.629] Build 8896115059603653536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:59:07.629] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:92:0) after 1m0s
[07:00:07.676] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:92:0)
[07:00:07.677] Timer tick, asking Buildbucket for the build status
[07:00:07.914] Build 8896115059603653536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:00:07.914] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:93:0) after 1m0s
[07:01:08.005] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:93:0)
[07:01:08.005] Timer tick, asking Buildbucket for the build status
[07:01:08.453] Build 8896115059603653536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:01:08.453] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:94:0) after 1m0s
[07:02:08.537] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:94:0)
[07:02:08.537] Timer tick, asking Buildbucket for the build status
[07:02:08.740] Build 8896115059603653536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:02:08.740] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:95:0) after 1m0s
[07:03:08.781] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:95:0)
[07:03:08.781] Timer tick, asking Buildbucket for the build status
[07:03:08.955] Build 8896115059603653536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:03:08.955] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:96:0) after 1m0s
[07:04:09.028] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:96:0)
[07:04:09.028] Timer tick, asking Buildbucket for the build status
[07:04:09.294] Build 8896115059603653536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:04:09.294] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:97:0) after 1m0s
[07:05:09.317] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:97:0)
[07:05:09.317] Timer tick, asking Buildbucket for the build status
[07:05:09.829] Build 8896115059603653536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:05:09.829] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:98:0) after 1m0s
[07:06:09.865] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:98:0)
[07:06:09.865] Timer tick, asking Buildbucket for the build status
[07:06:12.416] Build 8896115059603653536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:06:12.416] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:99:0) after 1m0s
[07:07:12.512] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:99:0)
[07:07:12.512] Timer tick, asking Buildbucket for the build status
[07:07:12.876] Build 8896115059603653536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:07:12.876] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:100:0) after 1m0s
[07:08:12.936] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:100:0)
[07:08:12.936] Timer tick, asking Buildbucket for the build status
[07:08:13.241] Build 8896115059603653536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:08:13.241] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:101:0) after 1m0s
[07:09:13.266] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:101:0)
[07:09:13.266] Timer tick, asking Buildbucket for the build status
[07:09:13.492] Build 8896115059603653536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:09:13.492] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:102:0) after 1m0s
[07:10:13.542] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:102:0)
[07:10:13.542] Timer tick, asking Buildbucket for the build status
[07:10:13.968] Build 8896115059603653536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:10:13.968] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:103:0) after 1m0s
[07:11:14.058] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:103:0)
[07:11:14.058] Timer tick, asking Buildbucket for the build status
[07:11:14.401] Build 8896115059603653536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:11:14.401] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:104:0) after 1m0s
[07:12:14.456] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:104:0)
[07:12:14.456] Timer tick, asking Buildbucket for the build status
[07:12:14.899] Build 8896115059603653536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:12:14.899] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:105:0) after 1m0s
[07:13:14.930] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:105:0)
[07:13:14.930] Timer tick, asking Buildbucket for the build status
[07:13:15.065] Build 8896115059603653536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:13:15.065] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:106:0) after 1m0s
[07:14:15.107] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:106:0)
[07:14:15.107] Timer tick, asking Buildbucket for the build status
[07:14:15.376] Build 8896115059603653536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:14:15.376] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:107:0) after 1m0s
[07:15:15.510] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:107:0)
[07:15:15.510] Timer tick, asking Buildbucket for the build status
[07:15:15.669] Build 8896115059603653536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:15:15.669] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:108:0) after 1m0s
[07:16:15.667] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:108:0)
[07:16:15.667] Timer tick, asking Buildbucket for the build status
[07:16:15.807] Build 8896115059603653536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:16:15.807] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:109:0) after 1m0s
[07:17:15.841] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:109:0)
[07:17:15.841] Timer tick, asking Buildbucket for the build status
[07:17:15.987] Build 8896115059603653536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:17:15.987] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:110:0) after 1m0s
[07:18:16.016] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:110:0)
[07:18:16.016] Timer tick, asking Buildbucket for the build status
[07:18:16.599] Build 8896115059603653536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:18:16.599] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:111:0) after 1m0s
[07:19:16.672] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:111:0)
[07:19:16.672] Timer tick, asking Buildbucket for the build status
[07:19:16.778] Build 8896115059603653536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:19:16.778] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:112:0) after 1m0s
[07:20:16.811] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:112:0)
[07:20:16.811] Timer tick, asking Buildbucket for the build status
[07:20:17.284] Build 8896115059603653536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:20:17.284] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:113:0) after 1m0s
[07:21:17.635] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:113:0)
[07:21:17.635] Timer tick, asking Buildbucket for the build status
[07:21:18.381] Build 8896115059603653536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:21:18.381] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:114:0) after 1m0s
[07:22:18.437] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:114:0)
[07:22:18.437] Timer tick, asking Buildbucket for the build status
[07:22:18.869] Build 8896115059603653536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:22:18.869] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:115:0) after 1m0s
[07:23:18.947] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:115:0)
[07:23:18.947] Timer tick, asking Buildbucket for the build status
[07:23:19.100] Build 8896115059603653536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:23:19.100] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:116:0) after 1m0s
[07:24:19.166] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:116:0)
[07:24:19.166] Timer tick, asking Buildbucket for the build status
[07:24:19.468] Build 8896115059603653536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:24:19.468] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:117:0) after 1m0s
[07:25:19.563] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:117:0)
[07:25:19.563] Timer tick, asking Buildbucket for the build status
[07:25:19.727] Build 8896115059603653536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:25:19.727] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:118:0) after 1m0s
[07:26:19.832] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:118:0)
[07:26:19.832] Timer tick, asking Buildbucket for the build status
[07:26:20.047] Build 8896115059603653536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:26:20.047] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:119:0) after 1m0s
[07:27:20.117] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:119:0)
[07:27:20.117] Timer tick, asking Buildbucket for the build status
[07:27:20.257] Build 8896115059603653536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:27:20.257] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:120:0) after 1m0s
[07:28:20.280] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:120:0)
[07:28:20.280] Timer tick, asking Buildbucket for the build status
[07:28:20.554] Build 8896115059603653536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:28:20.554] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:121:0) after 1m0s
[07:29:20.618] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:121:0)
[07:29:20.618] Timer tick, asking Buildbucket for the build status
[07:29:20.879] Build 8896115059603653536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:29:20.879] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:122:0) after 1m0s
[07:30:20.984] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:122:0)
[07:30:20.984] Timer tick, asking Buildbucket for the build status
[07:30:21.180] Build 8896115059603653536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:30:21.180] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:123:0) after 1m0s
[07:31:21.228] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:123:0)
[07:31:21.228] Timer tick, asking Buildbucket for the build status
[07:31:21.546] Build 8896115059603653536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:31:21.546] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:124:0) after 1m0s
[07:32:21.661] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:124:0)
[07:32:21.661] Timer tick, asking Buildbucket for the build status
[07:32:21.825] Build 8896115059603653536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:32:21.825] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:125:0) after 1m0s
[07:33:21.990] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:125:0)
[07:33:21.990] Timer tick, asking Buildbucket for the build status
[07:33:22.239] Build 8896115059603653536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:33:22.239] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9061545123948334496:126:0) after 1m0s
[07:33:32.157] Received PubSub notification, asking Buildbucket for the build status
[07:33:32.230] Build 8896115059603653536: status "COMPLETED", result "CANCELED", failure_reason "", cancelation_reason "TIMEOUT"
[07:33:32.230] Buildbucket build:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary_preference": "PROD",
"cancelation_reason": "TIMEOUT",
"completed_ts": "1574408009997924",
"created_by": "project:emscripten-releases",
"created_ts": "1574400574069010",
"id": "8896115059603653536",
"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\": \"3efd9db648cfe373518631afa02b3db8c8778545\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@3efd9db648cfe373518631afa02b3db8c8778545\", \"title\": \"3efd9db648cfe373518631afa02b3db8c8778545\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/3efd9db648cfe373518631afa02b3db8c8778545\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"3efd9db648cfe373518631afa02b3db8c8778545\"}}",
"project": "emscripten-releases",
"result": "CANCELED",
"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\": \"3efd9db648cfe373518631afa02b3db8c8778545\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@3efd9db648cfe373518631afa02b3db8c8778545\", \"title\": \"3efd9db648cfe373518631afa02b3db8c8778545\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/3efd9db648cfe373518631afa02b3db8c8778545\"}]}, \"bot_id\": \"luci-emscripten-releases-ci-win10-0-x36t\", \"branch\": \"refs/heads/master\", \"buildername\": \"win\", \"got_revision\": \"3efd9db648cfe373518631afa02b3db8c8778545\", \"path_config\": \"generic\", \"recipe\": \"emscripten_releases\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"3efd9db648cfe373518631afa02b3db8c8778545\"}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"builder_0175bca77590805533ed6e20351c9bfa7effd036e42d11a5b619a6ce75a8e486_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"host_class\": [\"default\"], \"id\": [\"luci-emscripten-releases-ci-win10-0-x36t\"], \"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\": [\"4645-7f6a85d\"], \"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": "1574400599541038",
"status": "COMPLETED",
"status_changed_ts": "1574408010507013",
"tags": [
"builder:win",
"buildset:commit/git/3efd9db648cfe373518631afa02b3db8c8778545",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/3efd9db648cfe373518631afa02b3db8c8778545",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9061545123948334496",
"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/8896115059603653536/+/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:48aa67f367510f10",
"user_agent:luci-scheduler"
],
"updated_ts": "1574408010642756",
"url": "https://ci.chromium.org/b/8896115059603653536",
"utcnow_ts": "1574408012218113"
}
}
[07:33:32.230] Invocation finished in 2h4m1.687946872s with status FAILED