[22:22:32.679] New invocation is queued and will start shortly
[22:22:33.842] Starting the invocation (attempt 1)
[22:22:33.908] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[22:22:33.908] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[22:22:33.908] Buildbucket request:
{
"bucket": "luci.emscripten-releases.ci",
"client_operation_id": "9053508856242834128",
"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\":\"6fca0c180edd6c7842c6298d649e9d376f014bdc\"},\"id\":\"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@6fca0c180edd6c7842c6298d649e9d376f014bdc\",\"title\":\"6fca0c180edd6c7842c6298d649e9d376f014bdc\",\"url\":\"https://chromium.googlesource.com/emscripten-releases/+/6fca0c180edd6c7842c6298d649e9d376f014bdc\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/emscripten-releases\",\"revision\":\"6fca0c180edd6c7842c6298d649e9d376f014bdc\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win",
"scheduler_invocation_id:9053508856242834128",
"scheduler_job_id:emscripten-releases/win",
"user_agent:luci-scheduler",
"buildset:commit/git/6fca0c180edd6c7842c6298d649e9d376f014bdc",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/6fca0c180edd6c7842c6298d649e9d376f014bdc",
"gitiles_ref:refs/heads/master"
]
}
[22:22:34.975] Buildbucket response:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary_preference": "PROD",
"created_by": "project:emscripten-releases",
"created_ts": "1582064554029488",
"id": "8888078794152291504",
"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\": \"6fca0c180edd6c7842c6298d649e9d376f014bdc\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@6fca0c180edd6c7842c6298d649e9d376f014bdc\", \"title\": \"6fca0c180edd6c7842c6298d649e9d376f014bdc\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/6fca0c180edd6c7842c6298d649e9d376f014bdc\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"6fca0c180edd6c7842c6298d649e9d376f014bdc\"}}",
"project": "emscripten-releases",
"result_details_json": "{\"properties\": {}}",
"service_account": "emscripten-releases-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1582064554737812",
"tags": [
"builder:win",
"buildset:commit/git/6fca0c180edd6c7842c6298d649e9d376f014bdc",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/6fca0c180edd6c7842c6298d649e9d376f014bdc",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9053508856242834128",
"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/8888078794152291504/+/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": "1582064554737914",
"url": "https://ci.chromium.org/b/8888078794152291504",
"utcnow_ts": "1582064554948752"
}
}
[22:22:34.975] Task URL: https://ci.chromium.org/b/8888078794152291504
[22:22:34.975] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:2:0) after 1m0s
[22:23:34.963] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:2:0)
[22:23:34.963] Timer tick, asking Buildbucket for the build status
[22:23:35.276] Build 8888078794152291504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:23:35.276] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:3:0) after 1m0s
[22:24:35.361] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:3:0)
[22:24:35.361] Timer tick, asking Buildbucket for the build status
[22:24:35.592] Build 8888078794152291504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:24:35.592] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:4:0) after 1m0s
[22:25:35.714] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:4:0)
[22:25:35.714] Timer tick, asking Buildbucket for the build status
[22:25:35.920] Build 8888078794152291504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:25:35.920] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:5:0) after 1m0s
[22:26:35.957] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:5:0)
[22:26:35.957] Timer tick, asking Buildbucket for the build status
[22:26:36.064] Build 8888078794152291504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:26:36.064] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:6:0) after 1m0s
[22:27:36.143] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:6:0)
[22:27:36.143] Timer tick, asking Buildbucket for the build status
[22:27:36.588] Build 8888078794152291504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:27:36.588] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:7:0) after 1m0s
[22:28:36.694] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:7:0)
[22:28:36.694] Timer tick, asking Buildbucket for the build status
[22:28:36.791] Build 8888078794152291504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:28:36.791] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:8:0) after 1m0s
[22:29:36.797] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:8:0)
[22:29:36.797] Timer tick, asking Buildbucket for the build status
[22:29:37.177] Build 8888078794152291504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:29:37.177] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:9:0) after 1m0s
[22:30:37.243] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:9:0)
[22:30:37.243] Timer tick, asking Buildbucket for the build status
[22:30:37.367] Build 8888078794152291504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:30:37.367] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:10:0) after 1m0s
[22:31:37.478] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:10:0)
[22:31:37.478] Timer tick, asking Buildbucket for the build status
[22:31:37.636] Build 8888078794152291504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:31:37.636] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:11:0) after 1m0s
[22:32:37.724] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:11:0)
[22:32:37.724] Timer tick, asking Buildbucket for the build status
[22:32:37.832] Build 8888078794152291504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:32:37.832] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:12:0) after 1m0s
[22:33:37.906] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:12:0)
[22:33:37.906] Timer tick, asking Buildbucket for the build status
[22:33:38.006] Build 8888078794152291504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:33:38.006] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:13:0) after 1m0s
[22:34:38.103] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:13:0)
[22:34:38.103] Timer tick, asking Buildbucket for the build status
[22:34:38.241] Build 8888078794152291504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:34:38.241] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:14:0) after 1m0s
[22:35:38.305] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:14:0)
[22:35:38.305] Timer tick, asking Buildbucket for the build status
[22:35:38.442] Build 8888078794152291504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:35:38.442] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:15:0) after 1m0s
[22:36:38.464] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:15:0)
[22:36:38.464] Timer tick, asking Buildbucket for the build status
[22:36:38.766] Build 8888078794152291504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:36:38.766] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:16:0) after 1m0s
[22:37:38.844] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:16:0)
[22:37:38.844] Timer tick, asking Buildbucket for the build status
[22:37:39.069] Build 8888078794152291504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:37:39.069] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:17:0) after 1m0s
[22:38:39.227] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:17:0)
[22:38:39.227] Timer tick, asking Buildbucket for the build status
[22:38:39.352] Build 8888078794152291504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:38:39.352] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:18:0) after 1m0s
[22:39:39.770] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:18:0)
[22:39:39.770] Timer tick, asking Buildbucket for the build status
[22:39:40.032] Build 8888078794152291504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:39:40.032] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:19:0) after 1m0s
[22:40:40.365] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:19:0)
[22:40:40.365] Timer tick, asking Buildbucket for the build status
[22:40:40.466] Build 8888078794152291504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:40:40.466] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:20:0) after 1m0s
[22:41:40.767] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:20:0)
[22:41:40.767] Timer tick, asking Buildbucket for the build status
[22:41:40.857] Build 8888078794152291504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:41:40.857] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:21:0) after 1m0s
[22:42:41.189] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:21:0)
[22:42:41.189] Timer tick, asking Buildbucket for the build status
[22:42:41.423] Build 8888078794152291504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:42:41.423] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:22:0) after 1m0s
[22:43:41.513] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:22:0)
[22:43:41.514] Timer tick, asking Buildbucket for the build status
[22:43:41.696] Build 8888078794152291504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:43:41.696] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:23:0) after 1m0s
[22:44:41.778] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:23:0)
[22:44:41.778] Timer tick, asking Buildbucket for the build status
[22:44:41.886] Build 8888078794152291504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:44:41.886] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:24:0) after 1m0s
[22:45:41.981] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:24:0)
[22:45:41.981] Timer tick, asking Buildbucket for the build status
[22:45:42.094] Build 8888078794152291504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:45:42.094] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:25:0) after 1m0s
[22:46:42.144] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:25:0)
[22:46:42.144] Timer tick, asking Buildbucket for the build status
[22:46:42.460] Build 8888078794152291504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:46:42.460] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:26:0) after 1m0s
[22:47:42.807] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:26:0)
[22:47:42.807] Timer tick, asking Buildbucket for the build status
[22:47:42.993] Build 8888078794152291504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:47:42.993] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:27:0) after 1m0s
[22:48:43.006] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:27:0)
[22:48:43.006] Timer tick, asking Buildbucket for the build status
[22:48:43.100] Build 8888078794152291504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:48:43.100] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:28:0) after 1m0s
[22:49:43.102] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:28:0)
[22:49:43.102] Timer tick, asking Buildbucket for the build status
[22:49:43.191] Build 8888078794152291504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:49:43.191] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:29:0) after 1m0s
[22:50:43.299] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:29:0)
[22:50:43.299] Timer tick, asking Buildbucket for the build status
[22:50:43.412] Build 8888078794152291504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:50:43.412] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:30:0) after 1m0s
[22:51:43.442] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:30:0)
[22:51:43.442] Timer tick, asking Buildbucket for the build status
[22:51:43.596] Build 8888078794152291504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:51:43.596] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:31:0) after 1m0s
[22:52:43.646] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:31:0)
[22:52:43.646] Timer tick, asking Buildbucket for the build status
[22:52:43.747] Build 8888078794152291504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:52:43.747] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:32:0) after 1m0s
[22:53:43.848] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:32:0)
[22:53:43.848] Timer tick, asking Buildbucket for the build status
[22:53:43.961] Build 8888078794152291504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:53:43.961] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:33:0) after 1m0s
[22:54:44.035] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:33:0)
[22:54:44.035] Timer tick, asking Buildbucket for the build status
[22:54:44.149] Build 8888078794152291504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:54:44.149] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:34:0) after 1m0s
[22:55:44.206] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:34:0)
[22:55:44.206] Timer tick, asking Buildbucket for the build status
[22:55:44.879] Build 8888078794152291504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:55:44.879] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:35:0) after 1m0s
[22:56:44.965] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:35:0)
[22:56:44.965] Timer tick, asking Buildbucket for the build status
[22:56:45.039] Build 8888078794152291504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:56:45.039] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:36:0) after 1m0s
[22:57:45.119] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:36:0)
[22:57:45.119] Timer tick, asking Buildbucket for the build status
[22:57:45.230] Build 8888078794152291504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:57:45.230] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:37:0) after 1m0s
[22:58:45.351] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:37:0)
[22:58:45.351] Timer tick, asking Buildbucket for the build status
[22:58:45.599] Build 8888078794152291504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:58:45.599] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:38:0) after 1m0s
[22:59:45.660] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:38:0)
[22:59:45.660] Timer tick, asking Buildbucket for the build status
[22:59:45.763] Build 8888078794152291504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:59:45.763] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:39:0) after 1m0s
[23:00:45.953] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:39:0)
[23:00:45.953] Timer tick, asking Buildbucket for the build status
[23:00:46.094] Build 8888078794152291504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:00:46.094] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:40:0) after 1m0s
[23:01:46.171] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:40:0)
[23:01:46.171] Timer tick, asking Buildbucket for the build status
[23:01:46.289] Build 8888078794152291504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:01:46.289] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:41:0) after 1m0s
[23:02:46.340] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:41:0)
[23:02:46.340] Timer tick, asking Buildbucket for the build status
[23:02:46.460] Build 8888078794152291504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:02:46.460] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:42:0) after 1m0s
[23:03:46.490] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:42:0)
[23:03:46.490] Timer tick, asking Buildbucket for the build status
[23:03:46.571] Build 8888078794152291504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:03:46.571] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:43:0) after 1m0s
[23:04:46.638] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:43:0)
[23:04:46.638] Timer tick, asking Buildbucket for the build status
[23:04:49.051] Build 8888078794152291504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:04:49.051] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:44:0) after 1m0s
[23:05:49.120] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:44:0)
[23:05:49.120] Timer tick, asking Buildbucket for the build status
[23:05:49.384] Build 8888078794152291504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:05:49.384] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:45:0) after 1m0s
[23:06:49.399] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:45:0)
[23:06:49.399] Timer tick, asking Buildbucket for the build status
[23:06:49.587] Build 8888078794152291504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:06:49.587] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:46:0) after 1m0s
[23:07:49.625] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:46:0)
[23:07:49.625] Timer tick, asking Buildbucket for the build status
[23:07:49.771] Build 8888078794152291504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:07:49.771] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:47:0) after 1m0s
[23:08:49.767] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:47:0)
[23:08:49.767] Timer tick, asking Buildbucket for the build status
[23:08:49.964] Build 8888078794152291504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:08:49.964] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:48:0) after 1m0s
[23:09:49.997] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:48:0)
[23:09:49.997] Timer tick, asking Buildbucket for the build status
[23:09:50.692] Build 8888078794152291504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:09:50.692] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:49:0) after 1m0s
[23:10:50.831] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:49:0)
[23:10:50.831] Timer tick, asking Buildbucket for the build status
[23:10:51.490] Build 8888078794152291504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:10:51.490] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:50:0) after 1m0s
[23:11:51.520] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:50:0)
[23:11:51.520] Timer tick, asking Buildbucket for the build status
[23:11:51.664] Build 8888078794152291504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:11:51.664] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:51:0) after 1m0s
[23:12:51.752] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:51:0)
[23:12:51.752] Timer tick, asking Buildbucket for the build status
[23:12:51.930] Build 8888078794152291504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:12:51.930] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:52:0) after 1m0s
[23:13:52.040] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:52:0)
[23:13:52.040] Timer tick, asking Buildbucket for the build status
[23:13:52.187] Build 8888078794152291504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:13:52.187] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:53:0) after 1m0s
[23:14:52.200] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:53:0)
[23:14:52.200] Timer tick, asking Buildbucket for the build status
[23:14:52.330] Build 8888078794152291504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:14:52.330] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:54:0) after 1m0s
[23:15:52.569] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:54:0)
[23:15:52.569] Timer tick, asking Buildbucket for the build status
[23:15:52.749] Build 8888078794152291504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:15:52.749] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:55:0) after 1m0s
[23:16:52.936] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:55:0)
[23:16:52.936] Timer tick, asking Buildbucket for the build status
[23:16:53.141] Build 8888078794152291504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:16:53.141] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:56:0) after 1m0s
[23:17:53.291] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:56:0)
[23:17:53.291] Timer tick, asking Buildbucket for the build status
[23:17:53.417] Build 8888078794152291504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:17:53.417] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:57:0) after 1m0s
[23:18:53.571] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:57:0)
[23:18:53.571] Timer tick, asking Buildbucket for the build status
[23:18:53.695] Build 8888078794152291504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:18:53.696] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:58:0) after 1m0s
[23:19:53.794] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:58:0)
[23:19:53.794] Timer tick, asking Buildbucket for the build status
[23:19:54.652] Build 8888078794152291504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:19:54.652] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:59:0) after 1m0s
[23:20:54.900] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:59:0)
[23:20:54.900] Timer tick, asking Buildbucket for the build status
[23:20:55.085] Build 8888078794152291504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:20:55.085] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:60:0) after 1m0s
[23:21:55.197] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:60:0)
[23:21:55.197] Timer tick, asking Buildbucket for the build status
[23:21:55.333] Build 8888078794152291504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:21:55.333] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:61:0) after 1m0s
[23:22:55.372] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:61:0)
[23:22:55.372] Timer tick, asking Buildbucket for the build status
[23:22:55.696] Build 8888078794152291504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:22:55.696] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:62:0) after 1m0s
[23:23:55.836] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:62:0)
[23:23:55.836] Timer tick, asking Buildbucket for the build status
[23:23:55.997] Build 8888078794152291504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:23:55.997] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:63:0) after 1m0s
[23:24:34.878] Received PubSub notification, asking Buildbucket for the build status
[23:24:35.231] Build 8888078794152291504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:24:56.253] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:63:0)
[23:24:56.253] Timer tick, asking Buildbucket for the build status
[23:24:56.613] Build 8888078794152291504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:24:56.613] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:65:0) after 1m0s
[23:25:56.758] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:65:0)
[23:25:56.758] Timer tick, asking Buildbucket for the build status
[23:25:56.941] Build 8888078794152291504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:25:56.941] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:66:0) after 1m0s
[23:26:57.046] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:66:0)
[23:26:57.046] Timer tick, asking Buildbucket for the build status
[23:26:57.215] Build 8888078794152291504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:26:57.215] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:67:0) after 1m0s
[23:27:57.275] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:67:0)
[23:27:57.275] Timer tick, asking Buildbucket for the build status
[23:27:57.445] Build 8888078794152291504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:27:57.445] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:68:0) after 1m0s
[23:28:57.501] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:68:0)
[23:28:57.501] Timer tick, asking Buildbucket for the build status
[23:28:58.127] Build 8888078794152291504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:28:58.127] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:69:0) after 1m0s
[23:29:58.233] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:69:0)
[23:29:58.233] Timer tick, asking Buildbucket for the build status
[23:29:58.411] Build 8888078794152291504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:29:58.411] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:70:0) after 1m0s
[23:30:58.467] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:70:0)
[23:30:58.467] Timer tick, asking Buildbucket for the build status
[23:30:58.638] Build 8888078794152291504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:30:58.638] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:71:0) after 1m0s
[23:31:58.825] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:71:0)
[23:31:58.825] Timer tick, asking Buildbucket for the build status
[23:31:59.040] Build 8888078794152291504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:31:59.040] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:72:0) after 1m0s
[23:32:59.093] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:72:0)
[23:32:59.093] Timer tick, asking Buildbucket for the build status
[23:32:59.198] Build 8888078794152291504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:32:59.198] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:73:0) after 1m0s
[23:33:59.293] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:73:0)
[23:33:59.293] Timer tick, asking Buildbucket for the build status
[23:33:59.467] Build 8888078794152291504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:33:59.467] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:74:0) after 1m0s
[23:34:59.559] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:74:0)
[23:34:59.559] Timer tick, asking Buildbucket for the build status
[23:34:59.699] Build 8888078794152291504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:34:59.699] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:75:0) after 1m0s
[23:35:59.703] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:75:0)
[23:35:59.703] Timer tick, asking Buildbucket for the build status
[23:35:59.855] Build 8888078794152291504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:35:59.855] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:76:0) after 1m0s
[23:37:00.027] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:76:0)
[23:37:00.027] Timer tick, asking Buildbucket for the build status
[23:37:00.246] Build 8888078794152291504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:37:00.246] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:77:0) after 1m0s
[23:38:00.332] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:77:0)
[23:38:00.332] Timer tick, asking Buildbucket for the build status
[23:38:00.560] Build 8888078794152291504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:38:00.560] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:78:0) after 1m0s
[23:39:00.712] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:78:0)
[23:39:00.712] Timer tick, asking Buildbucket for the build status
[23:39:00.898] Build 8888078794152291504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:39:00.898] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:79:0) after 1m0s
[23:40:01.094] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:79:0)
[23:40:01.094] Timer tick, asking Buildbucket for the build status
[23:40:01.461] Build 8888078794152291504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:40:01.461] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:80:0) after 1m0s
[23:41:01.618] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:80:0)
[23:41:01.618] Timer tick, asking Buildbucket for the build status
[23:41:02.016] Build 8888078794152291504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:41:02.016] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:81:0) after 1m0s
[23:42:02.042] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:81:0)
[23:42:02.042] Timer tick, asking Buildbucket for the build status
[23:42:02.215] Build 8888078794152291504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:42:02.215] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:82:0) after 1m0s
[23:43:02.236] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:82:0)
[23:43:02.236] Timer tick, asking Buildbucket for the build status
[23:43:02.554] Build 8888078794152291504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:43:02.555] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:83:0) after 1m0s
[23:44:02.614] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:83:0)
[23:44:02.614] Timer tick, asking Buildbucket for the build status
[23:44:02.762] Build 8888078794152291504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:44:02.762] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:84:0) after 1m0s
[23:45:02.842] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:84:0)
[23:45:02.842] Timer tick, asking Buildbucket for the build status
[23:45:03.045] Build 8888078794152291504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:45:03.045] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:85:0) after 1m0s
[23:46:03.079] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:85:0)
[23:46:03.079] Timer tick, asking Buildbucket for the build status
[23:46:03.478] Build 8888078794152291504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:46:03.478] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:86:0) after 1m0s
[23:47:03.591] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:86:0)
[23:47:03.591] Timer tick, asking Buildbucket for the build status
[23:47:03.849] Build 8888078794152291504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:47:03.849] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:87:0) after 1m0s
[23:48:04.014] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:87:0)
[23:48:04.014] Timer tick, asking Buildbucket for the build status
[23:48:04.163] Build 8888078794152291504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:48:04.163] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:88:0) after 1m0s
[23:49:04.330] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:88:0)
[23:49:04.330] Timer tick, asking Buildbucket for the build status
[23:49:04.877] Build 8888078794152291504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:49:04.877] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:89:0) after 1m0s
[23:50:04.944] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:89:0)
[23:50:04.944] Timer tick, asking Buildbucket for the build status
[23:50:05.229] Build 8888078794152291504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:50:05.230] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:90:0) after 1m0s
[23:51:05.273] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:90:0)
[23:51:05.273] Timer tick, asking Buildbucket for the build status
[23:51:05.396] Build 8888078794152291504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:51:05.396] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:91:0) after 1m0s
[23:52:05.554] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:91:0)
[23:52:05.554] Timer tick, asking Buildbucket for the build status
[23:52:05.827] Build 8888078794152291504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:52:05.827] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:92:0) after 1m0s
[23:53:05.886] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:92:0)
[23:53:05.886] Timer tick, asking Buildbucket for the build status
[23:53:07.966] Build 8888078794152291504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:53:07.966] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:93:0) after 1m0s
[23:54:08.186] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:93:0)
[23:54:08.186] Timer tick, asking Buildbucket for the build status
[23:54:08.375] Build 8888078794152291504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:54:08.376] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:94:0) after 1m0s
[23:55:08.429] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:94:0)
[23:55:08.429] Timer tick, asking Buildbucket for the build status
[23:55:08.543] Build 8888078794152291504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:55:08.543] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:95:0) after 1m0s
[23:56:08.656] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:95:0)
[23:56:08.656] Timer tick, asking Buildbucket for the build status
[23:56:08.962] Build 8888078794152291504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:56:08.962] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:96:0) after 1m0s
[23:57:09.098] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:96:0)
[23:57:09.098] Timer tick, asking Buildbucket for the build status
[23:57:09.231] Build 8888078794152291504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:57:09.231] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:97:0) after 1m0s
[23:58:09.400] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:97:0)
[23:58:09.400] Timer tick, asking Buildbucket for the build status
[23:58:09.603] Build 8888078794152291504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:58:09.603] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:98:0) after 1m0s
[23:59:09.663] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:98:0)
[23:59:09.663] Timer tick, asking Buildbucket for the build status
[23:59:09.782] Build 8888078794152291504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:59:09.782] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:99:0) after 1m0s
[00:00:09.841] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:99:0)
[00:00:09.841] Timer tick, asking Buildbucket for the build status
[00:00:10.973] Build 8888078794152291504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:00:10.973] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:100:0) after 1m0s
[00:01:11.062] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:100:0)
[00:01:11.062] Timer tick, asking Buildbucket for the build status
[00:01:11.498] Build 8888078794152291504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:01:11.498] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:101:0) after 1m0s
[00:02:11.587] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:101:0)
[00:02:11.587] Timer tick, asking Buildbucket for the build status
[00:02:11.768] Build 8888078794152291504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:02:11.768] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:102:0) after 1m0s
[00:03:11.776] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:102:0)
[00:03:11.776] Timer tick, asking Buildbucket for the build status
[00:03:12.132] Build 8888078794152291504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:03:12.132] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:103:0) after 1m0s
[00:04:13.204] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:103:0)
[00:04:13.204] Timer tick, asking Buildbucket for the build status
[00:04:13.343] Build 8888078794152291504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:04:13.343] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:104:0) after 1m0s
[00:05:13.710] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:104:0)
[00:05:13.710] Timer tick, asking Buildbucket for the build status
[00:05:13.917] Build 8888078794152291504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:05:13.918] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:105:0) after 1m0s
[00:06:13.958] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:105:0)
[00:06:13.958] Timer tick, asking Buildbucket for the build status
[00:06:14.132] Build 8888078794152291504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:06:14.132] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:106:0) after 1m0s
[00:07:14.316] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:106:0)
[00:07:14.317] Timer tick, asking Buildbucket for the build status
[00:07:14.569] Build 8888078794152291504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:07:14.569] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:107:0) after 1m0s
[00:08:14.655] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:107:0)
[00:08:14.655] Timer tick, asking Buildbucket for the build status
[00:08:15.008] Build 8888078794152291504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:08:15.008] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:108:0) after 1m0s
[00:09:15.165] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:108:0)
[00:09:15.165] Timer tick, asking Buildbucket for the build status
[00:09:16.158] Build 8888078794152291504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:09:16.158] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:109:0) after 1m0s
[00:10:16.213] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:109:0)
[00:10:16.213] Timer tick, asking Buildbucket for the build status
[00:10:16.350] Build 8888078794152291504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:10:16.350] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:110:0) after 1m0s
[00:11:16.378] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:110:0)
[00:11:16.378] Timer tick, asking Buildbucket for the build status
[00:11:16.608] Build 8888078794152291504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:11:16.608] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:111:0) after 1m0s
[00:12:16.733] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:111:0)
[00:12:16.733] Timer tick, asking Buildbucket for the build status
[00:12:17.011] Build 8888078794152291504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:12:17.011] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:112:0) after 1m0s
[00:13:17.052] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:112:0)
[00:13:17.052] Timer tick, asking Buildbucket for the build status
[00:13:17.291] Build 8888078794152291504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:13:17.291] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:113:0) after 1m0s
[00:14:17.423] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:113:0)
[00:14:17.423] Timer tick, asking Buildbucket for the build status
[00:14:19.094] Build 8888078794152291504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:14:19.094] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:114:0) after 1m0s
[00:15:19.211] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:114:0)
[00:15:19.211] Timer tick, asking Buildbucket for the build status
[00:15:19.573] Build 8888078794152291504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:15:19.573] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:115:0) after 1m0s
[00:16:19.622] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:115:0)
[00:16:19.622] Timer tick, asking Buildbucket for the build status
[00:16:20.044] Build 8888078794152291504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:16:20.044] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:116:0) after 1m0s
[00:17:20.189] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:116:0)
[00:17:20.189] Timer tick, asking Buildbucket for the build status
[00:17:20.329] Build 8888078794152291504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:17:20.329] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:117:0) after 1m0s
[00:18:20.491] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:117:0)
[00:18:20.491] Timer tick, asking Buildbucket for the build status
[00:18:21.553] Build 8888078794152291504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:18:21.553] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:118:0) after 1m0s
[00:19:21.605] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:118:0)
[00:19:21.605] Timer tick, asking Buildbucket for the build status
[00:19:22.113] Build 8888078794152291504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:19:22.113] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:119:0) after 1m0s
[00:20:22.284] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:119:0)
[00:20:22.284] Timer tick, asking Buildbucket for the build status
[00:20:22.420] Build 8888078794152291504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:20:22.420] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:120:0) after 1m0s
[00:21:22.596] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:120:0)
[00:21:22.596] Timer tick, asking Buildbucket for the build status
[00:21:22.754] Build 8888078794152291504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:21:22.754] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:121:0) after 1m0s
[00:22:22.945] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:121:0)
[00:22:22.945] Timer tick, asking Buildbucket for the build status
[00:22:23.099] Build 8888078794152291504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:22:23.099] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:122:0) after 1m0s
[00:23:23.122] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:122:0)
[00:23:23.122] Timer tick, asking Buildbucket for the build status
[00:23:23.505] Build 8888078794152291504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:23:23.505] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:123:0) after 1m0s
[00:24:23.571] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:123:0)
[00:24:23.571] Timer tick, asking Buildbucket for the build status
[00:24:24.227] Build 8888078794152291504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:24:24.227] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:124:0) after 1m0s
[00:25:24.366] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:124:0)
[00:25:24.366] Timer tick, asking Buildbucket for the build status
[00:25:24.696] Build 8888078794152291504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:25:24.696] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:125:0) after 1m0s
[00:26:24.782] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:125:0)
[00:26:24.782] Timer tick, asking Buildbucket for the build status
[00:26:25.082] Build 8888078794152291504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:26:25.082] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:126:0) after 1m0s
[00:27:25.184] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:126:0)
[00:27:25.184] Timer tick, asking Buildbucket for the build status
[00:27:25.569] Build 8888078794152291504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:27:25.569] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:127:0) after 1m0s
[00:28:25.688] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:127:0)
[00:28:25.688] Timer tick, asking Buildbucket for the build status
[00:28:25.831] Build 8888078794152291504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:28:25.831] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:128:0) after 1m0s
[00:29:25.859] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:128:0)
[00:29:25.859] Timer tick, asking Buildbucket for the build status
[00:29:26.159] Build 8888078794152291504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:29:26.159] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:129:0) after 1m0s
[00:30:26.167] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:129:0)
[00:30:26.167] Timer tick, asking Buildbucket for the build status
[00:30:26.313] Build 8888078794152291504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:30:26.313] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:130:0) after 1m0s
[00:31:26.364] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:130:0)
[00:31:26.364] Timer tick, asking Buildbucket for the build status
[00:31:26.475] Build 8888078794152291504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:31:26.475] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:131:0) after 1m0s
[00:32:26.547] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:131:0)
[00:32:26.547] Timer tick, asking Buildbucket for the build status
[00:32:26.725] Build 8888078794152291504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:32:26.725] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:132:0) after 1m0s
[00:33:26.779] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:132:0)
[00:33:26.779] Timer tick, asking Buildbucket for the build status
[00:33:26.887] Build 8888078794152291504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:33:26.887] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:133:0) after 1m0s
[00:34:27.129] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:133:0)
[00:34:27.129] Timer tick, asking Buildbucket for the build status
[00:34:27.343] Build 8888078794152291504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:34:27.343] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:134:0) after 1m0s
[00:35:27.382] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:134:0)
[00:35:27.382] Timer tick, asking Buildbucket for the build status
[00:35:27.583] Build 8888078794152291504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:35:27.583] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:135:0) after 1m0s
[00:36:27.730] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:135:0)
[00:36:27.730] Timer tick, asking Buildbucket for the build status
[00:36:28.083] Build 8888078794152291504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:36:28.083] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:136:0) after 1m0s
[00:37:28.167] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:136:0)
[00:37:28.167] Timer tick, asking Buildbucket for the build status
[00:37:28.401] Build 8888078794152291504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:37:28.401] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:137:0) after 1m0s
[00:38:28.441] Handling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:137:0)
[00:38:28.441] Timer tick, asking Buildbucket for the build status
[00:38:28.560] Build 8888078794152291504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:38:28.560] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/win:9053508856242834128:138:0) after 1m0s
[00:38:55.278] Received PubSub notification, asking Buildbucket for the build status
[00:38:55.373] Build 8888078794152291504: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[00:38:55.373] Buildbucket build:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary_preference": "PROD",
"completed_ts": "1582072734503074",
"created_by": "project:emscripten-releases",
"created_ts": "1582064554029488",
"id": "8888078794152291504",
"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\": \"6fca0c180edd6c7842c6298d649e9d376f014bdc\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@6fca0c180edd6c7842c6298d649e9d376f014bdc\", \"title\": \"6fca0c180edd6c7842c6298d649e9d376f014bdc\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/6fca0c180edd6c7842c6298d649e9d376f014bdc\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"6fca0c180edd6c7842c6298d649e9d376f014bdc\"}}",
"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\": \"6fca0c180edd6c7842c6298d649e9d376f014bdc\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@6fca0c180edd6c7842c6298d649e9d376f014bdc\", \"title\": \"6fca0c180edd6c7842c6298d649e9d376f014bdc\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/6fca0c180edd6c7842c6298d649e9d376f014bdc\"}]}, \"bot_id\": \"luci-emscripten-releases-ci-win10-0-udk4\", \"branch\": \"refs/heads/master\", \"buildername\": \"win\", \"got_revision\": \"6fca0c180edd6c7842c6298d649e9d376f014bdc\", \"path_config\": \"generic\", \"recipe\": \"emscripten_releases\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"6fca0c180edd6c7842c6298d649e9d376f014bdc\"}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"builder_0175bca77590805533ed6e20351c9bfa7effd036e42d11a5b619a6ce75a8e486_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"host_class\": [\"default\"], \"id\": [\"luci-emscripten-releases-ci-win10-0-udk4\"], \"image\": [\"chrome-win10-1703-19112100-3085293b610\"], \"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\": [\"4821-6a867f1\"], \"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": "1582068274191682",
"status": "COMPLETED",
"status_changed_ts": "1582072734888321",
"tags": [
"builder:win",
"buildset:commit/git/6fca0c180edd6c7842c6298d649e9d376f014bdc",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/6fca0c180edd6c7842c6298d649e9d376f014bdc",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9053508856242834128",
"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/8888078794152291504/+/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:4a7336dc9fee7610",
"user_agent:luci-scheduler"
],
"updated_ts": "1582072734938444",
"url": "https://ci.chromium.org/b/8888078794152291504",
"utcnow_ts": "1582072735365103"
}
}
[00:38:55.374] Invocation finished in 2h16m22.721262391s with status SUCCEEDED