[02:15:24.719] New invocation is queued and will start shortly
[02:15:26.169] Starting the invocation (attempt 1)
[02:15:26.226] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[02:15:26.226] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[02:15:26.226] Buildbucket request:
{
"bucket": "luci.emscripten-releases.ci",
"client_operation_id": "9063369274828416576",
"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\":\"079b41ab9c25ebb788323570e9a81d0392ab028b\"},\"id\":\"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@079b41ab9c25ebb788323570e9a81d0392ab028b\",\"title\":\"079b41ab9c25ebb788323570e9a81d0392ab028b\",\"url\":\"https://chromium.googlesource.com/emscripten-releases/+/079b41ab9c25ebb788323570e9a81d0392ab028b\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/emscripten-releases\",\"revision\":\"079b41ab9c25ebb788323570e9a81d0392ab028b\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win",
"scheduler_invocation_id:9063369274828416576",
"scheduler_job_id:emscripten-releases/win",
"user_agent:luci-scheduler",
"buildset:commit/git/079b41ab9c25ebb788323570e9a81d0392ab028b",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/079b41ab9c25ebb788323570e9a81d0392ab028b",
"gitiles_ref:refs/heads/master"
]
}
[02:15:30.540] Buildbucket response:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary_preference": "PROD",
"created_by": "project:emscripten-releases",
"created_ts": "1572660926797965",
"id": "8897939211980822832",
"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\": \"079b41ab9c25ebb788323570e9a81d0392ab028b\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@079b41ab9c25ebb788323570e9a81d0392ab028b\", \"title\": \"079b41ab9c25ebb788323570e9a81d0392ab028b\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/079b41ab9c25ebb788323570e9a81d0392ab028b\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"079b41ab9c25ebb788323570e9a81d0392ab028b\"}}",
"project": "emscripten-releases",
"result_details_json": "{\"properties\": {}}",
"service_account": "emscripten-releases-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1572660929545289",
"tags": [
"builder:win",
"buildset:commit/git/079b41ab9c25ebb788323570e9a81d0392ab028b",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/079b41ab9c25ebb788323570e9a81d0392ab028b",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9063369274828416576",
"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/8897939211980822832/+/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": "1572660929546269",
"url": "https://ci.chromium.org/b/8897939211980822832",
"utcnow_ts": "1572660930523007"
}
}
[02:15:30.540] Task URL: https://ci.chromium.org/b/8897939211980822832
[02:15:30.540] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:2:0) after 1m0s
[02:16:30.576] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:2:0)
[02:16:30.576] Timer tick, asking Buildbucket for the build status
[02:16:30.888] Build 8897939211980822832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:16:30.888] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:3:0) after 1m0s
[02:17:30.986] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:3:0)
[02:17:30.986] Timer tick, asking Buildbucket for the build status
[02:17:31.106] Build 8897939211980822832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:17:31.106] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:4:0) after 1m0s
[02:18:31.208] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:4:0)
[02:18:31.208] Timer tick, asking Buildbucket for the build status
[02:18:31.494] Build 8897939211980822832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:18:31.494] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:5:0) after 1m0s
[02:19:31.518] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:5:0)
[02:19:31.518] Timer tick, asking Buildbucket for the build status
[02:19:31.676] Build 8897939211980822832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:19:31.676] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:6:0) after 1m0s
[02:20:31.729] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:6:0)
[02:20:31.729] Timer tick, asking Buildbucket for the build status
[02:20:31.920] Build 8897939211980822832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:20:31.920] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:7:0) after 1m0s
[02:21:32.051] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:7:0)
[02:21:32.051] Timer tick, asking Buildbucket for the build status
[02:21:32.229] Build 8897939211980822832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:21:32.229] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:8:0) after 1m0s
[02:22:32.490] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:8:0)
[02:22:32.490] Timer tick, asking Buildbucket for the build status
[02:22:32.632] Build 8897939211980822832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:22:32.632] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:9:0) after 1m0s
[02:23:32.968] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:9:0)
[02:23:32.968] Timer tick, asking Buildbucket for the build status
[02:23:33.230] Build 8897939211980822832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:23:33.230] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:10:0) after 1m0s
[02:24:33.337] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:10:0)
[02:24:33.337] Timer tick, asking Buildbucket for the build status
[02:24:33.445] Build 8897939211980822832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:24:33.445] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:11:0) after 1m0s
[02:25:33.557] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:11:0)
[02:25:33.557] Timer tick, asking Buildbucket for the build status
[02:25:33.741] Build 8897939211980822832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:25:33.741] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:12:0) after 1m0s
[02:26:33.827] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:12:0)
[02:26:33.827] Timer tick, asking Buildbucket for the build status
[02:26:34.168] Build 8897939211980822832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:26:34.168] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:13:0) after 1m0s
[02:27:34.215] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:13:0)
[02:27:34.215] Timer tick, asking Buildbucket for the build status
[02:27:34.412] Build 8897939211980822832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:27:34.412] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:14:0) after 1m0s
[02:28:34.442] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:14:0)
[02:28:34.442] Timer tick, asking Buildbucket for the build status
[02:28:34.639] Build 8897939211980822832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:28:34.639] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:15:0) after 1m0s
[02:29:34.661] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:15:0)
[02:29:34.661] Timer tick, asking Buildbucket for the build status
[02:29:36.156] Build 8897939211980822832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:29:36.156] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:16:0) after 1m0s
[02:30:36.181] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:16:0)
[02:30:36.181] Timer tick, asking Buildbucket for the build status
[02:30:36.366] Build 8897939211980822832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:30:36.366] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:17:0) after 1m0s
[02:31:36.416] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:17:0)
[02:31:36.416] Timer tick, asking Buildbucket for the build status
[02:31:36.597] Build 8897939211980822832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:31:36.598] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:18:0) after 1m0s
[02:32:36.610] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:18:0)
[02:32:36.610] Timer tick, asking Buildbucket for the build status
[02:32:37.158] Build 8897939211980822832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:32:37.158] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:19:0) after 1m0s
[02:33:37.158] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:19:0)
[02:33:37.158] Timer tick, asking Buildbucket for the build status
[02:33:37.390] Build 8897939211980822832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:33:37.390] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:20:0) after 1m0s
[02:34:37.493] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:20:0)
[02:34:37.493] Timer tick, asking Buildbucket for the build status
[02:34:37.772] Build 8897939211980822832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:34:37.772] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:21:0) after 1m0s
[02:35:37.938] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:21:0)
[02:35:37.938] Timer tick, asking Buildbucket for the build status
[02:35:38.960] Build 8897939211980822832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:35:38.960] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:22:0) after 1m0s
[02:36:39.060] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:22:0)
[02:36:39.060] Timer tick, asking Buildbucket for the build status
[02:36:39.352] Build 8897939211980822832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:36:39.352] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:23:0) after 1m0s
[02:37:39.398] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:23:0)
[02:37:39.398] Timer tick, asking Buildbucket for the build status
[02:37:40.318] Build 8897939211980822832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:37:40.318] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:24:0) after 1m0s
[02:38:40.346] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:24:0)
[02:38:40.346] Timer tick, asking Buildbucket for the build status
[02:38:40.711] Build 8897939211980822832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:38:40.711] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:25:0) after 1m0s
[02:39:40.751] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:25:0)
[02:39:40.751] Timer tick, asking Buildbucket for the build status
[02:39:40.906] Build 8897939211980822832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:39:40.906] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:26:0) after 1m0s
[02:40:40.887] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:26:0)
[02:40:40.887] Timer tick, asking Buildbucket for the build status
[02:40:41.354] Build 8897939211980822832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:40:41.354] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:27:0) after 1m0s
[02:41:41.381] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:27:0)
[02:41:41.381] Timer tick, asking Buildbucket for the build status
[02:41:43.571] Build 8897939211980822832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:41:43.571] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:28:0) after 1m0s
[02:42:43.618] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:28:0)
[02:42:43.618] Timer tick, asking Buildbucket for the build status
[02:42:43.955] Build 8897939211980822832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:42:43.955] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:29:0) after 1m0s
[02:43:44.133] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:29:0)
[02:43:44.133] Timer tick, asking Buildbucket for the build status
[02:43:44.323] Build 8897939211980822832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:43:44.323] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:30:0) after 1m0s
[02:44:44.397] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:30:0)
[02:44:44.397] Timer tick, asking Buildbucket for the build status
[02:44:44.529] Build 8897939211980822832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:44:44.529] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:31:0) after 1m0s
[02:45:44.606] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:31:0)
[02:45:44.606] Timer tick, asking Buildbucket for the build status
[02:45:44.884] Build 8897939211980822832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:45:44.884] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:32:0) after 1m0s
[02:46:45.004] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:32:0)
[02:46:45.004] Timer tick, asking Buildbucket for the build status
[02:46:46.587] Build 8897939211980822832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:46:46.588] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:33:0) after 1m0s
[02:47:46.636] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:33:0)
[02:47:46.636] Timer tick, asking Buildbucket for the build status
[02:47:48.247] Build 8897939211980822832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:47:48.247] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:34:0) after 1m0s
[02:48:48.306] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:34:0)
[02:48:48.306] Timer tick, asking Buildbucket for the build status
[02:48:48.456] Build 8897939211980822832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:48:48.456] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:35:0) after 1m0s
[02:49:48.534] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:35:0)
[02:49:48.534] Timer tick, asking Buildbucket for the build status
[02:49:48.783] Build 8897939211980822832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:49:48.784] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:36:0) after 1m0s
[02:50:48.896] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:36:0)
[02:50:48.896] Timer tick, asking Buildbucket for the build status
[02:50:49.200] Build 8897939211980822832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:50:49.200] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:37:0) after 1m0s
[02:51:49.347] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:37:0)
[02:51:49.347] Timer tick, asking Buildbucket for the build status
[02:51:49.805] Build 8897939211980822832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:51:49.805] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:38:0) after 1m0s
[02:52:49.830] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:38:0)
[02:52:49.830] Timer tick, asking Buildbucket for the build status
[02:52:49.920] Build 8897939211980822832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:52:49.920] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:39:0) after 1m0s
[02:53:50.180] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:39:0)
[02:53:50.180] Timer tick, asking Buildbucket for the build status
[02:53:50.302] Build 8897939211980822832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:53:50.302] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:40:0) after 1m0s
[02:54:50.339] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:40:0)
[02:54:50.339] Timer tick, asking Buildbucket for the build status
[02:54:50.550] Build 8897939211980822832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:54:50.551] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:41:0) after 1m0s
[02:55:50.675] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:41:0)
[02:55:50.675] Timer tick, asking Buildbucket for the build status
[02:55:51.046] Build 8897939211980822832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:55:51.046] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:42:0) after 1m0s
[02:56:51.031] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:42:0)
[02:56:51.031] Timer tick, asking Buildbucket for the build status
[02:56:52.304] Build 8897939211980822832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:56:52.304] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:43:0) after 1m0s
[02:57:52.337] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:43:0)
[02:57:52.337] Timer tick, asking Buildbucket for the build status
[02:57:52.507] Build 8897939211980822832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:57:52.507] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:44:0) after 1m0s
[02:58:52.553] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:44:0)
[02:58:52.553] Timer tick, asking Buildbucket for the build status
[02:58:52.778] Build 8897939211980822832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:58:52.778] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:45:0) after 1m0s
[02:59:53.117] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:45:0)
[02:59:53.117] Timer tick, asking Buildbucket for the build status
[02:59:53.212] Build 8897939211980822832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[02:59:53.212] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:46:0) after 1m0s
[03:00:53.343] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:46:0)
[03:00:53.343] Timer tick, asking Buildbucket for the build status
[03:00:53.580] Build 8897939211980822832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:00:53.580] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:47:0) after 1m0s
[03:01:53.713] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:47:0)
[03:01:53.713] Timer tick, asking Buildbucket for the build status
[03:01:53.867] Build 8897939211980822832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:01:53.867] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:48:0) after 1m0s
[03:02:53.910] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:48:0)
[03:02:53.910] Timer tick, asking Buildbucket for the build status
[03:02:54.410] Build 8897939211980822832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:02:54.410] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:49:0) after 1m0s
[03:03:54.539] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:49:0)
[03:03:54.539] Timer tick, asking Buildbucket for the build status
[03:03:54.710] Build 8897939211980822832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:03:54.710] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:50:0) after 1m0s
[03:04:54.925] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:50:0)
[03:04:54.925] Timer tick, asking Buildbucket for the build status
[03:04:55.152] Build 8897939211980822832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:04:55.152] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:51:0) after 1m0s
[03:05:55.274] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:51:0)
[03:05:55.274] Timer tick, asking Buildbucket for the build status
[03:05:55.520] Build 8897939211980822832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:05:55.520] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:52:0) after 1m0s
[03:06:55.653] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:52:0)
[03:06:55.653] Timer tick, asking Buildbucket for the build status
[03:06:55.960] Build 8897939211980822832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:06:55.960] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:53:0) after 1m0s
[03:07:55.992] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:53:0)
[03:07:55.992] Timer tick, asking Buildbucket for the build status
[03:07:56.199] Build 8897939211980822832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:07:56.199] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:54:0) after 1m0s
[03:08:54.536] Received PubSub notification, asking Buildbucket for the build status
[03:08:54.745] Build 8897939211980822832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:08:56.232] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:54:0)
[03:08:56.232] Timer tick, asking Buildbucket for the build status
[03:08:56.466] Build 8897939211980822832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:08:56.466] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:56:0) after 1m0s
[03:09:56.503] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:56:0)
[03:09:56.503] Timer tick, asking Buildbucket for the build status
[03:09:56.657] Build 8897939211980822832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:09:56.657] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:57:0) after 1m0s
[03:10:56.712] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:57:0)
[03:10:56.712] Timer tick, asking Buildbucket for the build status
[03:10:57.014] Build 8897939211980822832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:10:57.014] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:58:0) after 1m0s
[03:11:57.095] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:58:0)
[03:11:57.095] Timer tick, asking Buildbucket for the build status
[03:11:57.447] Build 8897939211980822832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:11:57.447] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:59:0) after 1m0s
[03:12:57.526] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:59:0)
[03:12:57.526] Timer tick, asking Buildbucket for the build status
[03:12:59.396] Build 8897939211980822832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:12:59.396] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:60:0) after 1m0s
[03:13:59.581] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:60:0)
[03:13:59.581] Timer tick, asking Buildbucket for the build status
[03:13:59.907] Build 8897939211980822832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:13:59.907] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:61:0) after 1m0s
[03:14:59.955] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:61:0)
[03:14:59.955] Timer tick, asking Buildbucket for the build status
[03:15:00.229] Build 8897939211980822832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:15:00.229] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:62:0) after 1m0s
[03:16:00.376] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:62:0)
[03:16:00.376] Timer tick, asking Buildbucket for the build status
[03:16:00.797] Build 8897939211980822832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:16:00.797] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:63:0) after 1m0s
[03:17:00.891] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:63:0)
[03:17:00.891] Timer tick, asking Buildbucket for the build status
[03:17:01.179] Build 8897939211980822832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:17:01.179] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:64:0) after 1m0s
[03:18:01.212] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:64:0)
[03:18:01.212] Timer tick, asking Buildbucket for the build status
[03:18:01.416] Build 8897939211980822832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:18:01.416] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:65:0) after 1m0s
[03:19:01.444] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:65:0)
[03:19:01.444] Timer tick, asking Buildbucket for the build status
[03:19:01.734] Build 8897939211980822832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:19:01.734] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:66:0) after 1m0s
[03:20:01.763] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:66:0)
[03:20:01.764] Timer tick, asking Buildbucket for the build status
[03:20:01.905] Build 8897939211980822832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:20:01.905] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:67:0) after 1m0s
[03:21:01.969] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:67:0)
[03:21:01.969] Timer tick, asking Buildbucket for the build status
[03:21:02.202] Build 8897939211980822832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:21:02.202] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:68:0) after 1m0s
[03:22:02.333] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:68:0)
[03:22:02.333] Timer tick, asking Buildbucket for the build status
[03:22:02.573] Build 8897939211980822832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:22:02.573] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:69:0) after 1m0s
[03:23:02.597] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:69:0)
[03:23:02.597] Timer tick, asking Buildbucket for the build status
[03:23:02.694] Build 8897939211980822832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:23:02.694] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:70:0) after 1m0s
[03:24:02.746] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:70:0)
[03:24:02.746] Timer tick, asking Buildbucket for the build status
[03:24:03.045] Build 8897939211980822832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:24:03.045] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:71:0) after 1m0s
[03:25:03.213] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:71:0)
[03:25:03.213] Timer tick, asking Buildbucket for the build status
[03:25:03.348] Build 8897939211980822832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:25:03.348] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:72:0) after 1m0s
[03:26:03.373] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:72:0)
[03:26:03.373] Timer tick, asking Buildbucket for the build status
[03:26:03.548] Build 8897939211980822832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:26:03.548] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:73:0) after 1m0s
[03:27:03.622] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:73:0)
[03:27:03.622] Timer tick, asking Buildbucket for the build status
[03:27:03.731] Build 8897939211980822832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:27:03.731] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:74:0) after 1m0s
[03:28:03.781] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:74:0)
[03:28:03.781] Timer tick, asking Buildbucket for the build status
[03:28:03.891] Build 8897939211980822832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:28:03.891] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:75:0) after 1m0s
[03:29:03.954] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:75:0)
[03:29:03.954] Timer tick, asking Buildbucket for the build status
[03:29:04.275] Build 8897939211980822832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:29:04.275] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:76:0) after 1m0s
[03:30:04.578] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:76:0)
[03:30:04.578] Timer tick, asking Buildbucket for the build status
[03:30:05.029] Build 8897939211980822832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:30:05.029] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:77:0) after 1m0s
[03:31:05.104] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:77:0)
[03:31:05.104] Timer tick, asking Buildbucket for the build status
[03:31:05.494] Build 8897939211980822832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:31:05.494] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:78:0) after 1m0s
[03:32:05.629] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:78:0)
[03:32:05.629] Timer tick, asking Buildbucket for the build status
[03:32:05.763] Build 8897939211980822832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:32:05.763] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:79:0) after 1m0s
[03:33:05.873] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:79:0)
[03:33:05.873] Timer tick, asking Buildbucket for the build status
[03:33:06.480] Build 8897939211980822832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:33:06.480] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:80:0) after 1m0s
[03:34:06.504] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:80:0)
[03:34:06.504] Timer tick, asking Buildbucket for the build status
[03:34:06.620] Build 8897939211980822832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:34:06.620] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:81:0) after 1m0s
[03:35:06.651] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:81:0)
[03:35:06.651] Timer tick, asking Buildbucket for the build status
[03:35:06.841] Build 8897939211980822832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:35:06.841] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:82:0) after 1m0s
[03:36:06.870] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:82:0)
[03:36:06.870] Timer tick, asking Buildbucket for the build status
[03:36:07.270] Build 8897939211980822832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:36:07.270] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:83:0) after 1m0s
[03:37:07.424] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:83:0)
[03:37:07.424] Timer tick, asking Buildbucket for the build status
[03:37:07.665] Build 8897939211980822832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:37:07.665] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:84:0) after 1m0s
[03:38:07.791] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:84:0)
[03:38:07.791] Timer tick, asking Buildbucket for the build status
[03:38:08.062] Build 8897939211980822832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:38:08.062] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:85:0) after 1m0s
[03:39:08.241] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:85:0)
[03:39:08.241] Timer tick, asking Buildbucket for the build status
[03:39:08.382] Build 8897939211980822832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:39:08.382] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:86:0) after 1m0s
[03:40:08.671] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:86:0)
[03:40:08.671] Timer tick, asking Buildbucket for the build status
[03:40:08.991] Build 8897939211980822832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:40:08.991] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:87:0) after 1m0s
[03:41:09.064] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:87:0)
[03:41:09.064] Timer tick, asking Buildbucket for the build status
[03:41:09.218] Build 8897939211980822832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:41:09.218] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:88:0) after 1m0s
[03:42:09.323] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:88:0)
[03:42:09.323] Timer tick, asking Buildbucket for the build status
[03:42:09.431] Build 8897939211980822832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:42:09.431] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:89:0) after 1m0s
[03:43:09.544] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:89:0)
[03:43:09.544] Timer tick, asking Buildbucket for the build status
[03:43:09.832] Build 8897939211980822832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:43:09.832] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:90:0) after 1m0s
[03:44:09.871] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:90:0)
[03:44:09.871] Timer tick, asking Buildbucket for the build status
[03:44:10.049] Build 8897939211980822832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:44:10.049] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:91:0) after 1m0s
[03:45:10.122] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:91:0)
[03:45:10.122] Timer tick, asking Buildbucket for the build status
[03:45:10.275] Build 8897939211980822832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:45:10.275] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:92:0) after 1m0s
[03:46:10.379] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:92:0)
[03:46:10.379] Timer tick, asking Buildbucket for the build status
[03:46:11.726] Build 8897939211980822832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:46:11.727] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:93:0) after 1m0s
[03:47:11.757] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:93:0)
[03:47:11.757] Timer tick, asking Buildbucket for the build status
[03:47:11.894] Build 8897939211980822832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:47:11.894] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:94:0) after 1m0s
[03:48:12.056] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:94:0)
[03:48:12.056] Timer tick, asking Buildbucket for the build status
[03:48:12.573] Build 8897939211980822832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:48:12.573] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:95:0) after 1m0s
[03:49:12.596] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:95:0)
[03:49:12.597] Timer tick, asking Buildbucket for the build status
[03:49:12.819] Build 8897939211980822832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:49:12.819] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:96:0) after 1m0s
[03:50:12.904] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:96:0)
[03:50:12.904] Timer tick, asking Buildbucket for the build status
[03:50:13.083] Build 8897939211980822832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:50:13.083] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:97:0) after 1m0s
[03:51:13.108] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:97:0)
[03:51:13.108] Timer tick, asking Buildbucket for the build status
[03:51:13.529] Build 8897939211980822832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:51:13.529] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:98:0) after 1m0s
[03:52:13.568] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:98:0)
[03:52:13.568] Timer tick, asking Buildbucket for the build status
[03:52:13.743] Build 8897939211980822832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:52:13.743] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:99:0) after 1m0s
[03:53:13.832] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:99:0)
[03:53:13.832] Timer tick, asking Buildbucket for the build status
[03:53:14.355] Build 8897939211980822832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:53:14.355] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:100:0) after 1m0s
[03:54:14.416] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:100:0)
[03:54:14.416] Timer tick, asking Buildbucket for the build status
[03:54:14.644] Build 8897939211980822832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:54:14.644] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:101:0) after 1m0s
[03:55:14.680] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:101:0)
[03:55:14.680] Timer tick, asking Buildbucket for the build status
[03:55:15.646] Build 8897939211980822832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:55:15.646] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:102:0) after 1m0s
[03:56:15.642] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:102:0)
[03:56:15.642] Timer tick, asking Buildbucket for the build status
[03:56:15.823] Build 8897939211980822832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:56:15.823] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:103:0) after 1m0s
[03:57:15.851] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:103:0)
[03:57:15.851] Timer tick, asking Buildbucket for the build status
[03:57:16.044] Build 8897939211980822832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:57:16.044] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:104:0) after 1m0s
[03:58:16.104] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:104:0)
[03:58:16.104] Timer tick, asking Buildbucket for the build status
[03:58:17.252] Build 8897939211980822832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:58:17.252] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:105:0) after 1m0s
[03:59:17.294] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:105:0)
[03:59:17.294] Timer tick, asking Buildbucket for the build status
[03:59:17.388] Build 8897939211980822832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:59:17.388] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:106:0) after 1m0s
[04:00:17.427] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:106:0)
[04:00:17.427] Timer tick, asking Buildbucket for the build status
[04:00:17.567] Build 8897939211980822832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:00:17.567] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9063369274828416576:107:0) after 1m0s
[04:00:39.830] Received PubSub notification, asking Buildbucket for the build status
[04:00:40.087] Build 8897939211980822832: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[04:00:40.087] Buildbucket build:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary_preference": "PROD",
"completed_ts": "1572667238026569",
"created_by": "project:emscripten-releases",
"created_ts": "1572660926797965",
"id": "8897939211980822832",
"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\": \"079b41ab9c25ebb788323570e9a81d0392ab028b\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@079b41ab9c25ebb788323570e9a81d0392ab028b\", \"title\": \"079b41ab9c25ebb788323570e9a81d0392ab028b\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/079b41ab9c25ebb788323570e9a81d0392ab028b\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"079b41ab9c25ebb788323570e9a81d0392ab028b\"}}",
"project": "emscripten-releases",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$recipe_engine/path\": {\"cache_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\cache\", \"temp_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\tmp\\\\rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"079b41ab9c25ebb788323570e9a81d0392ab028b\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@079b41ab9c25ebb788323570e9a81d0392ab028b\", \"title\": \"079b41ab9c25ebb788323570e9a81d0392ab028b\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/079b41ab9c25ebb788323570e9a81d0392ab028b\"}]}, \"bot_id\": \"luci-emscripten-releases-ci-win10-0-b60w\", \"branch\": \"refs/heads/master\", \"buildername\": \"win\", \"got_revision\": \"079b41ab9c25ebb788323570e9a81d0392ab028b\", \"path_config\": \"generic\", \"recipe\": \"emscripten_releases\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"079b41ab9c25ebb788323570e9a81d0392ab028b\"}, \"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-b60w\"], \"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\": [\"4621-f8a5b1c\"], \"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": "1572664131993920",
"status": "COMPLETED",
"status_changed_ts": "1572667238378731",
"tags": [
"builder:win",
"buildset:commit/git/079b41ab9c25ebb788323570e9a81d0392ab028b",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/079b41ab9c25ebb788323570e9a81d0392ab028b",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9063369274828416576",
"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/8897939211980822832/+/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:4842b71ef5169e10",
"user_agent:luci-scheduler"
],
"updated_ts": "1572667238393482",
"url": "https://ci.chromium.org/b/8897939211980822832",
"utcnow_ts": "1572667240062992"
}
}
[04:00:40.087] Invocation finished in 1h45m15.389668659s with status SUCCEEDED