[21:05:14.504] New invocation is queued and will start shortly
[21:05:15.715] Starting the invocation (attempt 1)
[21:05:15.816] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[21:05:15.816] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[21:05:15.816] Buildbucket request:
{
"bucket": "luci.emscripten-releases.ci",
"client_operation_id": "9064566549614184448",
"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\":\"79dc3fcf87294d3652d173363af458188c8b3295\"},\"id\":\"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@79dc3fcf87294d3652d173363af458188c8b3295\",\"title\":\"79dc3fcf87294d3652d173363af458188c8b3295\",\"url\":\"https://chromium.googlesource.com/emscripten-releases/+/79dc3fcf87294d3652d173363af458188c8b3295\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/emscripten-releases\",\"revision\":\"79dc3fcf87294d3652d173363af458188c8b3295\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win",
"scheduler_invocation_id:9064566549614184448",
"scheduler_job_id:emscripten-releases/win",
"user_agent:luci-scheduler",
"buildset:commit/git/79dc3fcf87294d3652d173363af458188c8b3295",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/79dc3fcf87294d3652d173363af458188c8b3295",
"gitiles_ref:refs/heads/master"
]
}
[21:05:17.615] Buildbucket response:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary": true,
"canary_preference": "CANARY",
"created_by": "project:emscripten-releases",
"created_ts": "1571519116086562",
"id": "8899136487289221008",
"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\": \"79dc3fcf87294d3652d173363af458188c8b3295\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@79dc3fcf87294d3652d173363af458188c8b3295\", \"title\": \"79dc3fcf87294d3652d173363af458188c8b3295\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/79dc3fcf87294d3652d173363af458188c8b3295\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"79dc3fcf87294d3652d173363af458188c8b3295\"}}",
"project": "emscripten-releases",
"result_details_json": "{\"properties\": {}}",
"service_account": "emscripten-releases-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1571519117157212",
"tags": [
"builder:win",
"buildset:commit/git/79dc3fcf87294d3652d173363af458188c8b3295",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/79dc3fcf87294d3652d173363af458188c8b3295",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9064566549614184448",
"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/8899136487289221008/+/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": "1571519117157368",
"url": "https://ci.chromium.org/b/8899136487289221008",
"utcnow_ts": "1571519117587243"
}
}
[21:05:17.615] Task URL: https://ci.chromium.org/b/8899136487289221008
[21:05:17.615] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:2:0) after 1m0s
[21:06:08.851] Received PubSub notification, asking Buildbucket for the build status
[21:06:09.254] Build 8899136487289221008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:06:17.638] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:2:0)
[21:06:17.638] Timer tick, asking Buildbucket for the build status
[21:06:17.748] Build 8899136487289221008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:06:17.748] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:4:0) after 1m0s
[21:07:17.767] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:4:0)
[21:07:17.767] Timer tick, asking Buildbucket for the build status
[21:07:17.936] Build 8899136487289221008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:07:17.936] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:5:0) after 1m0s
[21:08:18.053] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:5:0)
[21:08:18.053] Timer tick, asking Buildbucket for the build status
[21:08:18.246] Build 8899136487289221008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:08:18.246] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:6:0) after 1m0s
[21:09:18.413] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:6:0)
[21:09:18.414] Timer tick, asking Buildbucket for the build status
[21:09:18.660] Build 8899136487289221008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:09:18.660] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:7:0) after 1m0s
[21:10:18.852] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:7:0)
[21:10:18.852] Timer tick, asking Buildbucket for the build status
[21:10:19.839] Build 8899136487289221008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:10:19.839] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:8:0) after 1m0s
[21:11:19.907] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:8:0)
[21:11:19.907] Timer tick, asking Buildbucket for the build status
[21:11:20.114] Build 8899136487289221008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:11:20.114] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:9:0) after 1m0s
[21:12:20.137] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:9:0)
[21:12:20.138] Timer tick, asking Buildbucket for the build status
[21:12:20.364] Build 8899136487289221008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:12:20.365] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:10:0) after 1m0s
[21:13:20.477] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:10:0)
[21:13:20.477] Timer tick, asking Buildbucket for the build status
[21:13:21.001] Build 8899136487289221008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:13:21.001] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:11:0) after 1m0s
[21:14:21.004] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:11:0)
[21:14:21.004] Timer tick, asking Buildbucket for the build status
[21:14:21.189] Build 8899136487289221008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:14:21.189] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:12:0) after 1m0s
[21:15:21.250] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:12:0)
[21:15:21.250] Timer tick, asking Buildbucket for the build status
[21:15:22.597] Build 8899136487289221008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:15:22.597] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:13:0) after 1m0s
[21:16:22.627] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:13:0)
[21:16:22.627] Timer tick, asking Buildbucket for the build status
[21:16:23.019] Build 8899136487289221008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:16:23.019] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:14:0) after 1m0s
[21:17:23.288] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:14:0)
[21:17:23.288] Timer tick, asking Buildbucket for the build status
[21:17:23.544] Build 8899136487289221008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:17:23.544] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:15:0) after 1m0s
[21:18:23.569] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:15:0)
[21:18:23.569] Timer tick, asking Buildbucket for the build status
[21:18:23.836] Build 8899136487289221008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:18:23.836] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:16:0) after 1m0s
[21:19:23.819] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:16:0)
[21:19:23.819] Timer tick, asking Buildbucket for the build status
[21:19:24.052] Build 8899136487289221008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:19:24.052] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:17:0) after 1m0s
[21:20:24.129] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:17:0)
[21:20:24.129] Timer tick, asking Buildbucket for the build status
[21:20:24.942] Build 8899136487289221008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:20:24.942] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:18:0) after 1m0s
[21:21:25.065] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:18:0)
[21:21:25.065] Timer tick, asking Buildbucket for the build status
[21:21:25.342] Build 8899136487289221008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:21:25.342] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:19:0) after 1m0s
[21:22:25.343] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:19:0)
[21:22:25.343] Timer tick, asking Buildbucket for the build status
[21:22:25.590] Build 8899136487289221008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:22:25.590] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:20:0) after 1m0s
[21:23:25.633] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:20:0)
[21:23:25.633] Timer tick, asking Buildbucket for the build status
[21:23:27.254] Build 8899136487289221008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:23:27.254] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:21:0) after 1m0s
[21:24:27.240] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:21:0)
[21:24:27.240] Timer tick, asking Buildbucket for the build status
[21:24:27.511] Build 8899136487289221008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:24:27.511] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:22:0) after 1m0s
[21:25:27.550] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:22:0)
[21:25:27.550] Timer tick, asking Buildbucket for the build status
[21:25:27.662] Build 8899136487289221008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:25:27.663] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:23:0) after 1m0s
[21:26:27.737] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:23:0)
[21:26:27.737] Timer tick, asking Buildbucket for the build status
[21:26:28.142] Build 8899136487289221008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:26:28.142] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:24:0) after 1m0s
[21:27:28.164] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:24:0)
[21:27:28.164] Timer tick, asking Buildbucket for the build status
[21:27:28.538] Build 8899136487289221008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:27:28.538] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:25:0) after 1m0s
[21:28:28.710] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:25:0)
[21:28:28.710] Timer tick, asking Buildbucket for the build status
[21:28:28.839] Build 8899136487289221008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:28:28.839] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:26:0) after 1m0s
[21:29:28.917] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:26:0)
[21:29:28.917] Timer tick, asking Buildbucket for the build status
[21:29:30.167] Build 8899136487289221008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:29:30.167] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:27:0) after 1m0s
[21:30:30.192] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:27:0)
[21:30:30.192] Timer tick, asking Buildbucket for the build status
[21:30:30.340] Build 8899136487289221008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:30:30.340] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:28:0) after 1m0s
[21:31:30.365] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:28:0)
[21:31:30.365] Timer tick, asking Buildbucket for the build status
[21:31:30.814] Build 8899136487289221008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:31:30.815] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:29:0) after 1m0s
[21:32:30.926] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:29:0)
[21:32:30.926] Timer tick, asking Buildbucket for the build status
[21:32:31.133] Build 8899136487289221008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:32:31.133] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:30:0) after 1m0s
[21:33:31.175] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:30:0)
[21:33:31.175] Timer tick, asking Buildbucket for the build status
[21:33:31.326] Build 8899136487289221008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:33:31.326] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:31:0) after 1m0s
[21:34:31.308] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:31:0)
[21:34:31.308] Timer tick, asking Buildbucket for the build status
[21:34:31.502] Build 8899136487289221008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:34:31.502] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:32:0) after 1m0s
[21:35:31.604] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:32:0)
[21:35:31.604] Timer tick, asking Buildbucket for the build status
[21:35:31.837] Build 8899136487289221008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:35:31.837] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:33:0) after 1m0s
[21:36:31.857] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:33:0)
[21:36:31.857] Timer tick, asking Buildbucket for the build status
[21:36:32.236] Build 8899136487289221008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:36:32.236] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:34:0) after 1m0s
[21:37:32.256] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:34:0)
[21:37:32.256] Timer tick, asking Buildbucket for the build status
[21:37:32.625] Build 8899136487289221008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:37:32.625] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:35:0) after 1m0s
[21:38:39.489] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:35:0)
[21:38:39.489] Timer tick, asking Buildbucket for the build status
[21:38:39.637] Build 8899136487289221008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:38:39.637] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:36:0) after 1m0s
[21:39:39.657] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:36:0)
[21:39:39.657] Timer tick, asking Buildbucket for the build status
[21:39:40.776] Build 8899136487289221008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:39:40.776] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:37:0) after 1m0s
[21:40:40.842] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:37:0)
[21:40:40.842] Timer tick, asking Buildbucket for the build status
[21:40:40.997] Build 8899136487289221008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:40:40.997] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:38:0) after 1m0s
[21:41:40.979] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:38:0)
[21:41:40.979] Timer tick, asking Buildbucket for the build status
[21:41:41.253] Build 8899136487289221008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:41:41.253] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:39:0) after 1m0s
[21:42:41.287] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:39:0)
[21:42:41.287] Timer tick, asking Buildbucket for the build status
[21:42:41.421] Build 8899136487289221008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:42:41.421] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:40:0) after 1m0s
[21:43:41.632] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:40:0)
[21:43:41.632] Timer tick, asking Buildbucket for the build status
[21:43:41.878] Build 8899136487289221008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:43:41.879] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:41:0) after 1m0s
[21:44:42.027] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:41:0)
[21:44:42.027] Timer tick, asking Buildbucket for the build status
[21:44:42.168] Build 8899136487289221008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:44:42.168] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:42:0) after 1m0s
[21:45:42.559] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:42:0)
[21:45:42.559] Timer tick, asking Buildbucket for the build status
[21:45:42.777] Build 8899136487289221008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:45:42.777] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:43:0) after 1m0s
[21:46:43.112] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:43:0)
[21:46:43.112] Timer tick, asking Buildbucket for the build status
[21:46:43.347] Build 8899136487289221008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:46:43.347] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:44:0) after 1m0s
[21:47:43.609] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:44:0)
[21:47:43.609] Timer tick, asking Buildbucket for the build status
[21:47:43.740] Build 8899136487289221008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:47:43.740] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:45:0) after 1m0s
[21:48:43.974] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:45:0)
[21:48:43.974] Timer tick, asking Buildbucket for the build status
[21:48:44.073] Build 8899136487289221008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:48:44.073] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:46:0) after 1m0s
[21:49:44.428] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:46:0)
[21:49:44.428] Timer tick, asking Buildbucket for the build status
[21:49:44.575] Build 8899136487289221008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:49:44.575] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:47:0) after 1m0s
[21:50:44.892] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:47:0)
[21:50:44.892] Timer tick, asking Buildbucket for the build status
[21:50:45.149] Build 8899136487289221008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:50:45.149] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:48:0) after 1m0s
[21:51:45.507] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:48:0)
[21:51:45.507] Timer tick, asking Buildbucket for the build status
[21:51:45.622] Build 8899136487289221008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:51:45.622] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:49:0) after 1m0s
[21:52:45.908] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:49:0)
[21:52:45.908] Timer tick, asking Buildbucket for the build status
[21:52:46.098] Build 8899136487289221008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:52:46.098] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:50:0) after 1m0s
[21:53:46.268] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:50:0)
[21:53:46.268] Timer tick, asking Buildbucket for the build status
[21:53:46.440] Build 8899136487289221008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:53:46.440] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:51:0) after 1m0s
[21:54:46.532] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:51:0)
[21:54:46.532] Timer tick, asking Buildbucket for the build status
[21:54:46.658] Build 8899136487289221008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:54:46.658] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:52:0) after 1m0s
[21:55:46.724] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:52:0)
[21:55:46.724] Timer tick, asking Buildbucket for the build status
[21:55:46.861] Build 8899136487289221008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:55:46.861] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:53:0) after 1m0s
[21:56:46.988] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:53:0)
[21:56:46.988] Timer tick, asking Buildbucket for the build status
[21:56:47.108] Build 8899136487289221008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:56:47.108] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:54:0) after 1m0s
[21:57:47.179] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:54:0)
[21:57:47.179] Timer tick, asking Buildbucket for the build status
[21:57:47.414] Build 8899136487289221008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:57:47.414] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:55:0) after 1m0s
[21:58:47.476] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:55:0)
[21:58:47.476] Timer tick, asking Buildbucket for the build status
[21:58:47.783] Build 8899136487289221008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:58:47.783] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:56:0) after 1m0s
[21:59:47.877] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:56:0)
[21:59:47.877] Timer tick, asking Buildbucket for the build status
[21:59:48.604] Build 8899136487289221008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:59:48.604] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:57:0) after 1m0s
[22:00:48.973] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:57:0)
[22:00:48.973] Timer tick, asking Buildbucket for the build status
[22:00:49.374] Build 8899136487289221008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:00:49.374] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:58:0) after 1m0s
[22:01:49.575] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:58:0)
[22:01:49.575] Timer tick, asking Buildbucket for the build status
[22:01:49.844] Build 8899136487289221008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:01:49.844] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:59:0) after 1m0s
[22:02:50.136] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:59:0)
[22:02:50.136] Timer tick, asking Buildbucket for the build status
[22:02:50.401] Build 8899136487289221008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:02:50.401] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:60:0) after 1m0s
[22:03:50.604] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:60:0)
[22:03:50.604] Timer tick, asking Buildbucket for the build status
[22:03:50.821] Build 8899136487289221008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:03:50.821] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:61:0) after 1m0s
[22:04:50.861] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:61:0)
[22:04:50.861] Timer tick, asking Buildbucket for the build status
[22:04:51.123] Build 8899136487289221008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:04:51.123] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:62:0) after 1m0s
[22:05:51.354] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:62:0)
[22:05:51.354] Timer tick, asking Buildbucket for the build status
[22:05:51.594] Build 8899136487289221008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:05:51.594] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:63:0) after 1m0s
[22:06:51.630] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:63:0)
[22:06:51.630] Timer tick, asking Buildbucket for the build status
[22:06:51.853] Build 8899136487289221008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:06:51.853] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:64:0) after 1m0s
[22:07:51.875] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:64:0)
[22:07:51.875] Timer tick, asking Buildbucket for the build status
[22:07:51.991] Build 8899136487289221008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:07:51.991] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:65:0) after 1m0s
[22:08:52.099] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:65:0)
[22:08:52.099] Timer tick, asking Buildbucket for the build status
[22:08:52.324] Build 8899136487289221008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:08:52.324] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:66:0) after 1m0s
[22:09:52.366] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:66:0)
[22:09:52.366] Timer tick, asking Buildbucket for the build status
[22:09:52.572] Build 8899136487289221008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:09:52.572] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:67:0) after 1m0s
[22:10:52.612] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:67:0)
[22:10:52.612] Timer tick, asking Buildbucket for the build status
[22:10:52.769] Build 8899136487289221008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:10:52.769] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:68:0) after 1m0s
[22:11:52.843] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:68:0)
[22:11:52.843] Timer tick, asking Buildbucket for the build status
[22:11:53.396] Build 8899136487289221008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:11:53.396] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:69:0) after 1m0s
[22:12:53.421] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:69:0)
[22:12:53.421] Timer tick, asking Buildbucket for the build status
[22:12:53.555] Build 8899136487289221008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:12:53.555] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:70:0) after 1m0s
[22:13:53.583] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:70:0)
[22:13:53.583] Timer tick, asking Buildbucket for the build status
[22:13:53.731] Build 8899136487289221008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:13:53.731] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:71:0) after 1m0s
[22:14:53.796] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:71:0)
[22:14:53.796] Timer tick, asking Buildbucket for the build status
[22:14:53.988] Build 8899136487289221008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:14:53.988] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:72:0) after 1m0s
[22:15:54.089] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:72:0)
[22:15:54.089] Timer tick, asking Buildbucket for the build status
[22:15:54.242] Build 8899136487289221008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:15:54.242] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:73:0) after 1m0s
[22:16:54.320] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:73:0)
[22:16:54.320] Timer tick, asking Buildbucket for the build status
[22:16:54.475] Build 8899136487289221008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:16:54.475] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:74:0) after 1m0s
[22:17:54.601] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:74:0)
[22:17:54.601] Timer tick, asking Buildbucket for the build status
[22:17:54.925] Build 8899136487289221008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:17:54.925] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:75:0) after 1m0s
[22:18:55.062] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:75:0)
[22:18:55.062] Timer tick, asking Buildbucket for the build status
[22:18:59.949] Build 8899136487289221008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:18:59.949] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:76:0) after 1m0s
[22:20:00.145] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:76:0)
[22:20:00.145] Timer tick, asking Buildbucket for the build status
[22:20:00.293] Build 8899136487289221008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:20:00.293] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:77:0) after 1m0s
[22:21:00.326] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:77:0)
[22:21:00.326] Timer tick, asking Buildbucket for the build status
[22:21:00.694] Build 8899136487289221008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:21:00.694] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:78:0) after 1m0s
[22:22:00.800] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:78:0)
[22:22:00.800] Timer tick, asking Buildbucket for the build status
[22:22:01.107] Build 8899136487289221008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:22:01.107] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:79:0) after 1m0s
[22:23:01.142] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:79:0)
[22:23:01.142] Timer tick, asking Buildbucket for the build status
[22:23:01.274] Build 8899136487289221008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:23:01.274] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:80:0) after 1m0s
[22:24:01.301] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:80:0)
[22:24:01.301] Timer tick, asking Buildbucket for the build status
[22:24:01.705] Build 8899136487289221008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:24:01.705] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:81:0) after 1m0s
[22:25:01.731] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:81:0)
[22:25:01.731] Timer tick, asking Buildbucket for the build status
[22:25:01.990] Build 8899136487289221008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:25:01.990] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:82:0) after 1m0s
[22:26:02.113] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:82:0)
[22:26:02.113] Timer tick, asking Buildbucket for the build status
[22:26:02.601] Build 8899136487289221008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:26:02.601] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:83:0) after 1m0s
[22:27:02.720] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:83:0)
[22:27:02.720] Timer tick, asking Buildbucket for the build status
[22:27:03.049] Build 8899136487289221008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:27:03.049] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:84:0) after 1m0s
[22:28:03.093] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:84:0)
[22:28:03.093] Timer tick, asking Buildbucket for the build status
[22:28:03.284] Build 8899136487289221008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:28:03.284] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:85:0) after 1m0s
[22:29:03.332] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:85:0)
[22:29:03.332] Timer tick, asking Buildbucket for the build status
[22:29:03.625] Build 8899136487289221008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:29:03.625] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:86:0) after 1m0s
[22:30:03.670] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:86:0)
[22:30:03.670] Timer tick, asking Buildbucket for the build status
[22:30:03.879] Build 8899136487289221008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:30:03.879] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:87:0) after 1m0s
[22:31:03.977] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:87:0)
[22:31:03.977] Timer tick, asking Buildbucket for the build status
[22:31:04.211] Build 8899136487289221008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:31:04.211] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:88:0) after 1m0s
[22:32:04.351] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:88:0)
[22:32:04.351] Timer tick, asking Buildbucket for the build status
[22:32:05.742] Build 8899136487289221008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:32:05.742] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:89:0) after 1m0s
[22:33:05.903] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:89:0)
[22:33:05.903] Timer tick, asking Buildbucket for the build status
[22:33:06.106] Build 8899136487289221008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:33:06.106] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:90:0) after 1m0s
[22:34:06.239] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:90:0)
[22:34:06.239] Timer tick, asking Buildbucket for the build status
[22:34:06.471] Build 8899136487289221008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:34:06.471] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:91:0) after 1m0s
[22:35:06.551] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:91:0)
[22:35:06.551] Timer tick, asking Buildbucket for the build status
[22:35:06.661] Build 8899136487289221008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:35:06.661] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:92:0) after 1m0s
[22:36:06.739] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:92:0)
[22:36:06.739] Timer tick, asking Buildbucket for the build status
[22:36:07.065] Build 8899136487289221008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:36:07.065] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:93:0) after 1m0s
[22:37:07.242] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:93:0)
[22:37:07.242] Timer tick, asking Buildbucket for the build status
[22:37:07.598] Build 8899136487289221008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:37:07.598] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:94:0) after 1m0s
[22:38:07.631] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:94:0)
[22:38:07.631] Timer tick, asking Buildbucket for the build status
[22:38:07.864] Build 8899136487289221008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:38:07.864] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:95:0) after 1m0s
[22:39:07.858] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:95:0)
[22:39:07.858] Timer tick, asking Buildbucket for the build status
[22:39:07.990] Build 8899136487289221008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:39:07.990] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:96:0) after 1m0s
[22:40:08.026] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:96:0)
[22:40:08.026] Timer tick, asking Buildbucket for the build status
[22:40:08.343] Build 8899136487289221008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:40:08.343] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:97:0) after 1m0s
[22:41:08.392] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:97:0)
[22:41:08.392] Timer tick, asking Buildbucket for the build status
[22:41:08.591] Build 8899136487289221008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:41:08.592] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:98:0) after 1m0s
[22:42:08.673] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:98:0)
[22:42:08.673] Timer tick, asking Buildbucket for the build status
[22:42:09.051] Build 8899136487289221008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:42:09.051] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:99:0) after 1m0s
[22:43:09.219] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:99:0)
[22:43:09.219] Timer tick, asking Buildbucket for the build status
[22:43:09.422] Build 8899136487289221008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:43:09.422] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:100:0) after 1m0s
[22:44:09.451] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:100:0)
[22:44:09.451] Timer tick, asking Buildbucket for the build status
[22:44:09.597] Build 8899136487289221008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:44:09.598] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:101:0) after 1m0s
[22:45:09.637] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:101:0)
[22:45:09.637] Timer tick, asking Buildbucket for the build status
[22:45:10.967] Build 8899136487289221008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:45:10.967] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:102:0) after 1m0s
[22:46:10.991] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:102:0)
[22:46:10.992] Timer tick, asking Buildbucket for the build status
[22:46:11.268] Build 8899136487289221008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:46:11.268] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:103:0) after 1m0s
[22:47:11.414] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:103:0)
[22:47:11.414] Timer tick, asking Buildbucket for the build status
[22:47:11.539] Build 8899136487289221008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:47:11.539] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:104:0) after 1m0s
[22:48:11.539] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:104:0)
[22:48:11.539] Timer tick, asking Buildbucket for the build status
[22:48:11.927] Build 8899136487289221008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:48:11.927] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:105:0) after 1m0s
[22:49:11.959] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:105:0)
[22:49:11.959] Timer tick, asking Buildbucket for the build status
[22:49:12.584] Build 8899136487289221008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:49:12.584] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:106:0) after 1m0s
[22:50:12.719] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:106:0)
[22:50:12.719] Timer tick, asking Buildbucket for the build status
[22:50:13.031] Build 8899136487289221008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:50:13.031] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:107:0) after 1m0s
[22:51:13.028] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:107:0)
[22:51:13.028] Timer tick, asking Buildbucket for the build status
[22:51:13.352] Build 8899136487289221008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:51:13.352] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:108:0) after 1m0s
[22:52:13.334] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:108:0)
[22:52:13.335] Timer tick, asking Buildbucket for the build status
[22:52:13.482] Build 8899136487289221008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:52:13.482] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:109:0) after 1m0s
[22:53:13.712] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:109:0)
[22:53:13.712] Timer tick, asking Buildbucket for the build status
[22:53:13.862] Build 8899136487289221008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:53:13.862] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:110:0) after 1m0s
[22:54:13.879] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:110:0)
[22:54:13.879] Timer tick, asking Buildbucket for the build status
[22:54:14.063] Build 8899136487289221008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:54:14.063] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:111:0) after 1m0s
[22:55:14.125] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:111:0)
[22:55:14.125] Timer tick, asking Buildbucket for the build status
[22:55:14.402] Build 8899136487289221008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:55:14.402] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:112:0) after 1m0s
[22:56:14.549] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:112:0)
[22:56:14.549] Timer tick, asking Buildbucket for the build status
[22:56:14.911] Build 8899136487289221008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:56:14.911] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:113:0) after 1m0s
[22:57:14.972] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:113:0)
[22:57:14.972] Timer tick, asking Buildbucket for the build status
[22:57:16.393] Build 8899136487289221008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:57:16.393] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:114:0) after 1m0s
[22:58:16.432] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:114:0)
[22:58:16.432] Timer tick, asking Buildbucket for the build status
[22:58:16.888] Build 8899136487289221008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:58:16.888] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:115:0) after 1m0s
[22:59:18.119] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:115:0)
[22:59:18.119] Timer tick, asking Buildbucket for the build status
[22:59:18.271] Build 8899136487289221008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:59:18.271] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:116:0) after 1m0s
[23:00:18.361] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:116:0)
[23:00:18.361] Timer tick, asking Buildbucket for the build status
[23:00:18.637] Build 8899136487289221008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:00:18.637] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:117:0) after 1m0s
[23:01:18.710] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:117:0)
[23:01:18.710] Timer tick, asking Buildbucket for the build status
[23:01:20.312] Build 8899136487289221008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:01:20.312] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:118:0) after 1m0s
[23:02:20.419] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:118:0)
[23:02:20.419] Timer tick, asking Buildbucket for the build status
[23:02:20.581] Build 8899136487289221008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:02:20.581] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:119:0) after 1m0s
[23:03:20.605] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:119:0)
[23:03:20.605] Timer tick, asking Buildbucket for the build status
[23:03:21.113] Build 8899136487289221008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:03:21.113] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:120:0) after 1m0s
[23:04:21.143] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:120:0)
[23:04:21.143] Timer tick, asking Buildbucket for the build status
[23:04:21.508] Build 8899136487289221008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:04:21.508] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:121:0) after 1m0s
[23:05:21.593] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:121:0)
[23:05:21.593] Timer tick, asking Buildbucket for the build status
[23:05:22.129] Build 8899136487289221008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:05:22.129] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:122:0) after 1m0s
[23:06:22.229] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:122:0)
[23:06:22.229] Timer tick, asking Buildbucket for the build status
[23:06:22.554] Build 8899136487289221008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:06:22.554] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:123:0) after 1m0s
[23:07:22.597] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:123:0)
[23:07:22.597] Timer tick, asking Buildbucket for the build status
[23:07:22.725] Build 8899136487289221008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:07:22.725] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:124:0) after 1m0s
[23:08:22.897] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:124:0)
[23:08:22.897] Timer tick, asking Buildbucket for the build status
[23:08:24.562] Build 8899136487289221008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:08:24.562] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:125:0) after 1m0s
[23:09:24.715] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:125:0)
[23:09:24.715] Timer tick, asking Buildbucket for the build status
[23:09:24.916] Build 8899136487289221008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:09:24.916] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9064566549614184448:126:0) after 1m0s
[23:10:20.883] Received PubSub notification, asking Buildbucket for the build status
[23:10:21.341] Build 8899136487289221008: status "COMPLETED", result "CANCELED", failure_reason "", cancelation_reason "TIMEOUT"
[23:10:21.341] Buildbucket build:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary": true,
"canary_preference": "CANARY",
"cancelation_reason": "TIMEOUT",
"completed_ts": "1571526596272858",
"created_by": "project:emscripten-releases",
"created_ts": "1571519116086562",
"id": "8899136487289221008",
"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\": \"79dc3fcf87294d3652d173363af458188c8b3295\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@79dc3fcf87294d3652d173363af458188c8b3295\", \"title\": \"79dc3fcf87294d3652d173363af458188c8b3295\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/79dc3fcf87294d3652d173363af458188c8b3295\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"79dc3fcf87294d3652d173363af458188c8b3295\"}}",
"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\": \"79dc3fcf87294d3652d173363af458188c8b3295\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@79dc3fcf87294d3652d173363af458188c8b3295\", \"title\": \"79dc3fcf87294d3652d173363af458188c8b3295\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/79dc3fcf87294d3652d173363af458188c8b3295\"}]}, \"bot_id\": \"luci-emscripten-releases-ci-win10-0-1ew3\", \"branch\": \"refs/heads/master\", \"buildername\": \"win\", \"got_revision\": \"79dc3fcf87294d3652d173363af458188c8b3295\", \"path_config\": \"generic\", \"recipe\": \"emscripten_releases\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"79dc3fcf87294d3652d173363af458188c8b3295\"}, \"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-1ew3\"], \"image\": [\"chrome-win10-1703-19101700-60651c0b41e\"], \"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\": [\"4542-cd693bf\"], \"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": "1571519167402779",
"status": "COMPLETED",
"status_changed_ts": "1571526596641666",
"tags": [
"builder:win",
"buildset:commit/git/79dc3fcf87294d3652d173363af458188c8b3295",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/79dc3fcf87294d3652d173363af458188c8b3295",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9064566549614184448",
"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/8899136487289221008/+/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:47fea84299971c10",
"user_agent:luci-scheduler"
],
"updated_ts": "1571526596681492",
"url": "https://ci.chromium.org/b/8899136487289221008",
"utcnow_ts": "1571526621327454"
}
}
[23:10:21.341] Invocation finished in 2h5m6.856506718s with status FAILED