[00:28:23.124] New invocation is queued and will start shortly
[00:28:24.430] Starting the invocation (attempt 1)
[00:28:24.469] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[00:28:24.469] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[00:28:24.469] Buildbucket request:
{
"bucket": "luci.emscripten-releases.ci",
"client_operation_id": "9063557202288775568",
"parameters_json": "{\"builder_name\":\"linux\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/emscripten-releases\",\"revision\":\"a3a095b68bcf0f127635b1b6c548332bc7a8ef72\"},\"id\":\"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@a3a095b68bcf0f127635b1b6c548332bc7a8ef72\",\"title\":\"a3a095b68bcf0f127635b1b6c548332bc7a8ef72\",\"url\":\"https://chromium.googlesource.com/emscripten-releases/+/a3a095b68bcf0f127635b1b6c548332bc7a8ef72\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/emscripten-releases\",\"revision\":\"a3a095b68bcf0f127635b1b6c548332bc7a8ef72\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux",
"scheduler_invocation_id:9063557202288775568",
"scheduler_job_id:emscripten-releases/linux",
"user_agent:luci-scheduler",
"buildset:commit/git/a3a095b68bcf0f127635b1b6c548332bc7a8ef72",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/a3a095b68bcf0f127635b1b6c548332bc7a8ef72",
"gitiles_ref:refs/heads/master"
]
}
[00:28:25.344] Buildbucket response:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary_preference": "PROD",
"created_by": "project:emscripten-releases",
"created_ts": "1572481704579256",
"id": "8898127140097164256",
"parameters_json": "{\"builder_name\": \"linux\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"a3a095b68bcf0f127635b1b6c548332bc7a8ef72\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@a3a095b68bcf0f127635b1b6c548332bc7a8ef72\", \"title\": \"a3a095b68bcf0f127635b1b6c548332bc7a8ef72\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/a3a095b68bcf0f127635b1b6c548332bc7a8ef72\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"a3a095b68bcf0f127635b1b6c548332bc7a8ef72\"}}",
"project": "emscripten-releases",
"result_details_json": "{\"properties\": {}}",
"service_account": "emscripten-releases-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1572481705078155",
"tags": [
"builder:linux",
"buildset:commit/git/a3a095b68bcf0f127635b1b6c548332bc7a8ef72",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/a3a095b68bcf0f127635b1b6c548332bc7a8ef72",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9063557202288775568",
"scheduler_job_id:emscripten-releases/linux",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/emscripten-releases/buildbucket/cr-buildbucket.appspot.com/8898127140097164256/+/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": "1572481705078300",
"url": "https://ci.chromium.org/b/8898127140097164256",
"utcnow_ts": "1572481705326857"
}
}
[00:28:25.344] Task URL: https://ci.chromium.org/b/8898127140097164256
[00:28:25.344] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:2:0) after 1m0s
[00:29:25.406] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:2:0)
[00:29:25.406] Timer tick, asking Buildbucket for the build status
[00:29:25.617] Build 8898127140097164256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:29:25.617] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:3:0) after 1m0s
[00:30:25.710] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:3:0)
[00:30:25.710] Timer tick, asking Buildbucket for the build status
[00:30:25.880] Build 8898127140097164256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:30:25.880] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:4:0) after 1m0s
[00:31:25.930] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:4:0)
[00:31:25.930] Timer tick, asking Buildbucket for the build status
[00:31:26.295] Build 8898127140097164256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:31:26.295] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:5:0) after 1m0s
[00:32:26.327] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:5:0)
[00:32:26.327] Timer tick, asking Buildbucket for the build status
[00:32:26.524] Build 8898127140097164256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:32:26.524] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:6:0) after 1m0s
[00:33:26.576] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:6:0)
[00:33:26.576] Timer tick, asking Buildbucket for the build status
[00:33:26.702] Build 8898127140097164256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:33:26.702] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:7:0) after 1m0s
[00:34:26.759] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:7:0)
[00:34:26.759] Timer tick, asking Buildbucket for the build status
[00:34:26.992] Build 8898127140097164256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:34:26.992] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:8:0) after 1m0s
[00:35:27.016] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:8:0)
[00:35:27.016] Timer tick, asking Buildbucket for the build status
[00:35:27.209] Build 8898127140097164256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:35:27.209] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:9:0) after 1m0s
[00:36:27.263] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:9:0)
[00:36:27.263] Timer tick, asking Buildbucket for the build status
[00:36:27.664] Build 8898127140097164256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:36:27.664] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:10:0) after 1m0s
[00:37:27.660] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:10:0)
[00:37:27.660] Timer tick, asking Buildbucket for the build status
[00:37:27.922] Build 8898127140097164256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:37:27.922] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:11:0) after 1m0s
[00:38:27.971] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:11:0)
[00:38:27.971] Timer tick, asking Buildbucket for the build status
[00:38:29.077] Build 8898127140097164256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:38:29.077] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:12:0) after 1m0s
[00:39:29.156] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:12:0)
[00:39:29.156] Timer tick, asking Buildbucket for the build status
[00:39:29.394] Build 8898127140097164256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:39:29.394] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:13:0) after 1m0s
[00:40:29.427] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:13:0)
[00:40:29.427] Timer tick, asking Buildbucket for the build status
[00:40:29.668] Build 8898127140097164256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:40:29.669] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:14:0) after 1m0s
[00:41:29.746] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:14:0)
[00:41:29.746] Timer tick, asking Buildbucket for the build status
[00:41:29.926] Build 8898127140097164256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:41:29.926] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:15:0) after 1m0s
[00:42:30.031] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:15:0)
[00:42:30.031] Timer tick, asking Buildbucket for the build status
[00:42:30.169] Build 8898127140097164256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:42:30.169] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:16:0) after 1m0s
[00:43:30.188] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:16:0)
[00:43:30.188] Timer tick, asking Buildbucket for the build status
[00:43:30.354] Build 8898127140097164256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:43:30.354] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:17:0) after 1m0s
[00:44:30.382] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:17:0)
[00:44:30.382] Timer tick, asking Buildbucket for the build status
[00:44:32.080] Build 8898127140097164256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:44:32.080] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:18:0) after 1m0s
[00:45:32.114] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:18:0)
[00:45:32.114] Timer tick, asking Buildbucket for the build status
[00:45:32.574] Build 8898127140097164256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:45:32.574] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:19:0) after 1m0s
[00:46:32.663] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:19:0)
[00:46:32.663] Timer tick, asking Buildbucket for the build status
[00:46:34.085] Build 8898127140097164256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:46:34.085] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:20:0) after 1m0s
[00:47:34.141] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:20:0)
[00:47:34.141] Timer tick, asking Buildbucket for the build status
[00:47:34.836] Build 8898127140097164256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:47:34.836] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:21:0) after 1m0s
[00:48:34.888] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:21:0)
[00:48:34.888] Timer tick, asking Buildbucket for the build status
[00:48:35.103] Build 8898127140097164256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:48:35.103] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:22:0) after 1m0s
[00:49:35.157] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:22:0)
[00:49:35.157] Timer tick, asking Buildbucket for the build status
[00:49:35.902] Build 8898127140097164256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:49:35.902] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:23:0) after 1m0s
[00:50:35.999] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:23:0)
[00:50:35.999] Timer tick, asking Buildbucket for the build status
[00:50:36.384] Build 8898127140097164256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:50:36.384] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:24:0) after 1m0s
[00:51:36.592] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:24:0)
[00:51:36.592] Timer tick, asking Buildbucket for the build status
[00:51:36.685] Build 8898127140097164256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:51:36.685] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:25:0) after 1m0s
[00:52:36.720] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:25:0)
[00:52:36.720] Timer tick, asking Buildbucket for the build status
[00:52:37.083] Build 8898127140097164256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:52:37.083] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:26:0) after 1m0s
[00:53:37.120] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:26:0)
[00:53:37.120] Timer tick, asking Buildbucket for the build status
[00:53:39.026] Build 8898127140097164256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:53:39.026] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:27:0) after 1m0s
[00:54:39.075] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:27:0)
[00:54:39.075] Timer tick, asking Buildbucket for the build status
[00:54:39.570] Build 8898127140097164256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:54:39.570] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:28:0) after 1m0s
[00:55:39.663] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:28:0)
[00:55:39.663] Timer tick, asking Buildbucket for the build status
[00:55:39.835] Build 8898127140097164256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:55:39.835] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:29:0) after 1m0s
[00:56:39.891] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:29:0)
[00:56:39.891] Timer tick, asking Buildbucket for the build status
[00:56:40.078] Build 8898127140097164256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:56:40.078] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:30:0) after 1m0s
[00:57:40.098] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:30:0)
[00:57:40.098] Timer tick, asking Buildbucket for the build status
[00:57:40.321] Build 8898127140097164256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:57:40.321] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:31:0) after 1m0s
[00:58:40.345] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:31:0)
[00:58:40.345] Timer tick, asking Buildbucket for the build status
[00:58:40.524] Build 8898127140097164256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:58:40.524] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:32:0) after 1m0s
[00:59:40.803] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:32:0)
[00:59:40.803] Timer tick, asking Buildbucket for the build status
[00:59:41.053] Build 8898127140097164256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:59:41.053] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:33:0) after 1m0s
[01:00:41.211] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:33:0)
[01:00:41.211] Timer tick, asking Buildbucket for the build status
[01:00:41.469] Build 8898127140097164256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:00:41.469] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:34:0) after 1m0s
[01:01:41.608] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:34:0)
[01:01:41.608] Timer tick, asking Buildbucket for the build status
[01:01:41.798] Build 8898127140097164256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:01:41.798] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:35:0) after 1m0s
[01:02:41.909] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:35:0)
[01:02:41.909] Timer tick, asking Buildbucket for the build status
[01:02:42.488] Build 8898127140097164256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:02:42.488] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:36:0) after 1m0s
[01:03:42.576] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:36:0)
[01:03:42.576] Timer tick, asking Buildbucket for the build status
[01:03:42.809] Build 8898127140097164256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:03:42.809] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:37:0) after 1m0s
[01:04:42.867] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:37:0)
[01:04:42.867] Timer tick, asking Buildbucket for the build status
[01:04:43.056] Build 8898127140097164256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:04:43.056] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:38:0) after 1m0s
[01:05:43.160] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:38:0)
[01:05:43.160] Timer tick, asking Buildbucket for the build status
[01:05:43.453] Build 8898127140097164256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:05:43.453] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:39:0) after 1m0s
[01:06:43.482] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:39:0)
[01:06:43.482] Timer tick, asking Buildbucket for the build status
[01:06:43.684] Build 8898127140097164256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:06:43.684] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:40:0) after 1m0s
[01:07:43.691] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:40:0)
[01:07:43.691] Timer tick, asking Buildbucket for the build status
[01:07:43.833] Build 8898127140097164256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:07:43.833] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:41:0) after 1m0s
[01:08:43.862] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:41:0)
[01:08:43.862] Timer tick, asking Buildbucket for the build status
[01:08:44.057] Build 8898127140097164256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:08:44.057] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:42:0) after 1m0s
[01:09:44.087] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:42:0)
[01:09:44.087] Timer tick, asking Buildbucket for the build status
[01:09:44.286] Build 8898127140097164256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:09:44.286] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:43:0) after 1m0s
[01:10:44.341] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:43:0)
[01:10:44.341] Timer tick, asking Buildbucket for the build status
[01:10:44.671] Build 8898127140097164256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:10:44.671] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:44:0) after 1m0s
[01:11:44.720] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:44:0)
[01:11:44.720] Timer tick, asking Buildbucket for the build status
[01:11:45.087] Build 8898127140097164256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:11:45.087] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:45:0) after 1m0s
[01:12:45.154] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:45:0)
[01:12:45.154] Timer tick, asking Buildbucket for the build status
[01:12:45.505] Build 8898127140097164256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:12:45.505] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:46:0) after 1m0s
[01:13:45.512] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:46:0)
[01:13:45.512] Timer tick, asking Buildbucket for the build status
[01:13:45.761] Build 8898127140097164256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:13:45.761] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:47:0) after 1m0s
[01:14:45.882] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:47:0)
[01:14:45.882] Timer tick, asking Buildbucket for the build status
[01:14:46.075] Build 8898127140097164256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:14:46.075] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:48:0) after 1m0s
[01:15:46.102] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:48:0)
[01:15:46.102] Timer tick, asking Buildbucket for the build status
[01:15:46.494] Build 8898127140097164256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:15:46.495] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:49:0) after 1m0s
[01:16:46.525] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:49:0)
[01:16:46.525] Timer tick, asking Buildbucket for the build status
[01:16:46.835] Build 8898127140097164256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:16:46.835] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:50:0) after 1m0s
[01:17:46.859] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:50:0)
[01:17:46.859] Timer tick, asking Buildbucket for the build status
[01:17:47.055] Build 8898127140097164256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:17:47.055] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:51:0) after 1m0s
[01:18:47.077] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:51:0)
[01:18:47.077] Timer tick, asking Buildbucket for the build status
[01:18:47.274] Build 8898127140097164256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:18:47.275] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:52:0) after 1m0s
[01:19:47.259] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:52:0)
[01:19:47.259] Timer tick, asking Buildbucket for the build status
[01:19:47.426] Build 8898127140097164256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:19:47.426] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:53:0) after 1m0s
[01:20:47.499] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:53:0)
[01:20:47.499] Timer tick, asking Buildbucket for the build status
[01:20:47.728] Build 8898127140097164256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:20:47.728] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:54:0) after 1m0s
[01:21:47.807] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:54:0)
[01:21:47.807] Timer tick, asking Buildbucket for the build status
[01:21:47.983] Build 8898127140097164256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:21:47.983] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:55:0) after 1m0s
[01:22:48.034] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:55:0)
[01:22:48.034] Timer tick, asking Buildbucket for the build status
[01:22:48.265] Build 8898127140097164256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:22:48.265] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:56:0) after 1m0s
[01:23:48.283] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:56:0)
[01:23:48.283] Timer tick, asking Buildbucket for the build status
[01:23:48.668] Build 8898127140097164256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:23:48.668] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:57:0) after 1m0s
[01:24:48.697] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:57:0)
[01:24:48.697] Timer tick, asking Buildbucket for the build status
[01:24:49.047] Build 8898127140097164256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:24:49.047] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:58:0) after 1m0s
[01:25:49.139] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:58:0)
[01:25:49.139] Timer tick, asking Buildbucket for the build status
[01:25:49.321] Build 8898127140097164256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:25:49.321] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:59:0) after 1m0s
[01:26:49.312] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:59:0)
[01:26:49.312] Timer tick, asking Buildbucket for the build status
[01:26:49.444] Build 8898127140097164256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:26:49.444] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:60:0) after 1m0s
[01:27:49.532] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:60:0)
[01:27:49.532] Timer tick, asking Buildbucket for the build status
[01:27:49.660] Build 8898127140097164256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:27:49.660] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:61:0) after 1m0s
[01:28:49.720] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:61:0)
[01:28:49.720] Timer tick, asking Buildbucket for the build status
[01:28:50.012] Build 8898127140097164256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:28:50.012] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:62:0) after 1m0s
[01:29:50.056] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:62:0)
[01:29:50.056] Timer tick, asking Buildbucket for the build status
[01:29:50.681] Build 8898127140097164256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:29:50.681] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:63:0) after 1m0s
[01:30:50.703] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:63:0)
[01:30:50.703] Timer tick, asking Buildbucket for the build status
[01:30:51.049] Build 8898127140097164256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:30:51.049] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:64:0) after 1m0s
[01:31:51.078] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:64:0)
[01:31:51.078] Timer tick, asking Buildbucket for the build status
[01:31:51.205] Build 8898127140097164256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:31:51.205] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:65:0) after 1m0s
[01:32:51.265] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:65:0)
[01:32:51.265] Timer tick, asking Buildbucket for the build status
[01:32:53.899] Build 8898127140097164256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:32:53.899] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:66:0) after 1m0s
[01:33:53.912] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:66:0)
[01:33:53.912] Timer tick, asking Buildbucket for the build status
[01:33:54.125] Build 8898127140097164256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:33:54.125] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:67:0) after 1m0s
[01:34:54.149] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:67:0)
[01:34:54.149] Timer tick, asking Buildbucket for the build status
[01:34:54.368] Build 8898127140097164256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:34:54.368] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:68:0) after 1m0s
[01:35:20.162] Received PubSub notification, asking Buildbucket for the build status
[01:35:20.297] Build 8898127140097164256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:35:54.392] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:68:0)
[01:35:54.392] Timer tick, asking Buildbucket for the build status
[01:35:54.534] Build 8898127140097164256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:35:54.534] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:70:0) after 1m0s
[01:36:54.622] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:70:0)
[01:36:54.622] Timer tick, asking Buildbucket for the build status
[01:36:54.899] Build 8898127140097164256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:36:54.899] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:71:0) after 1m0s
[01:37:54.924] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:71:0)
[01:37:54.924] Timer tick, asking Buildbucket for the build status
[01:37:55.072] Build 8898127140097164256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:37:55.072] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:72:0) after 1m0s
[01:38:55.097] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:72:0)
[01:38:55.097] Timer tick, asking Buildbucket for the build status
[01:38:55.294] Build 8898127140097164256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:38:55.294] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:73:0) after 1m0s
[01:39:55.424] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:73:0)
[01:39:55.424] Timer tick, asking Buildbucket for the build status
[01:39:55.549] Build 8898127140097164256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:39:55.549] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:74:0) after 1m0s
[01:40:55.731] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:74:0)
[01:40:55.731] Timer tick, asking Buildbucket for the build status
[01:40:57.243] Build 8898127140097164256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:40:57.243] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:75:0) after 1m0s
[01:41:57.264] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:75:0)
[01:41:57.264] Timer tick, asking Buildbucket for the build status
[01:41:57.389] Build 8898127140097164256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:41:57.389] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:76:0) after 1m0s
[01:42:57.470] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:76:0)
[01:42:57.470] Timer tick, asking Buildbucket for the build status
[01:42:57.590] Build 8898127140097164256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:42:57.590] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:77:0) after 1m0s
[01:43:57.847] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:77:0)
[01:43:57.847] Timer tick, asking Buildbucket for the build status
[01:43:58.078] Build 8898127140097164256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:43:58.078] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:78:0) after 1m0s
[01:44:58.178] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:78:0)
[01:44:58.178] Timer tick, asking Buildbucket for the build status
[01:44:58.491] Build 8898127140097164256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:44:58.491] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:79:0) after 1m0s
[01:45:58.557] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:79:0)
[01:45:58.557] Timer tick, asking Buildbucket for the build status
[01:45:58.693] Build 8898127140097164256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:45:58.693] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:80:0) after 1m0s
[01:46:58.705] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:80:0)
[01:46:58.705] Timer tick, asking Buildbucket for the build status
[01:46:58.929] Build 8898127140097164256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:46:58.929] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:81:0) after 1m0s
[01:47:58.982] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:81:0)
[01:47:58.982] Timer tick, asking Buildbucket for the build status
[01:48:00.171] Build 8898127140097164256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:48:00.171] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:82:0) after 1m0s
[01:49:00.268] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:82:0)
[01:49:00.268] Timer tick, asking Buildbucket for the build status
[01:49:00.581] Build 8898127140097164256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:49:00.581] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:83:0) after 1m0s
[01:50:00.744] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:83:0)
[01:50:00.744] Timer tick, asking Buildbucket for the build status
[01:50:01.003] Build 8898127140097164256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:50:01.003] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:84:0) after 1m0s
[01:51:01.186] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:84:0)
[01:51:01.186] Timer tick, asking Buildbucket for the build status
[01:51:01.770] Build 8898127140097164256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:51:01.770] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:85:0) after 1m0s
[01:52:01.941] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:85:0)
[01:52:01.941] Timer tick, asking Buildbucket for the build status
[01:52:02.344] Build 8898127140097164256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:52:02.344] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:86:0) after 1m0s
[01:53:02.379] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:86:0)
[01:53:02.379] Timer tick, asking Buildbucket for the build status
[01:53:02.543] Build 8898127140097164256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:53:02.543] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:87:0) after 1m0s
[01:54:02.652] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:87:0)
[01:54:02.652] Timer tick, asking Buildbucket for the build status
[01:54:02.856] Build 8898127140097164256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:54:02.856] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:88:0) after 1m0s
[01:55:02.946] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:88:0)
[01:55:02.946] Timer tick, asking Buildbucket for the build status
[01:55:03.109] Build 8898127140097164256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:55:03.109] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:89:0) after 1m0s
[01:56:03.180] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:89:0)
[01:56:03.180] Timer tick, asking Buildbucket for the build status
[01:56:03.445] Build 8898127140097164256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:56:03.445] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:90:0) after 1m0s
[01:57:03.495] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:90:0)
[01:57:03.495] Timer tick, asking Buildbucket for the build status
[01:57:03.637] Build 8898127140097164256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:57:03.637] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:91:0) after 1m0s
[01:58:03.697] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:91:0)
[01:58:03.697] Timer tick, asking Buildbucket for the build status
[01:58:03.955] Build 8898127140097164256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:58:03.955] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:92:0) after 1m0s
[01:59:03.977] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:92:0)
[01:59:03.977] Timer tick, asking Buildbucket for the build status
[01:59:04.076] Build 8898127140097164256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:59:04.076] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:93:0) after 1m0s
[02:00:04.220] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:93:0)
[02:00:04.220] Timer tick, asking Buildbucket for the build status
[02:00:04.534] Build 8898127140097164256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:00:04.534] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:94:0) after 1m0s
[02:01:04.581] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:94:0)
[02:01:04.581] Timer tick, asking Buildbucket for the build status
[02:01:04.780] Build 8898127140097164256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:01:04.780] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:95:0) after 1m0s
[02:02:04.895] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:95:0)
[02:02:04.895] Timer tick, asking Buildbucket for the build status
[02:02:05.311] Build 8898127140097164256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:02:05.311] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:96:0) after 1m0s
[02:03:05.377] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:96:0)
[02:03:05.377] Timer tick, asking Buildbucket for the build status
[02:03:06.354] Build 8898127140097164256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:03:06.354] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:97:0) after 1m0s
[02:04:06.380] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:97:0)
[02:04:06.380] Timer tick, asking Buildbucket for the build status
[02:04:06.658] Build 8898127140097164256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:04:06.658] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:98:0) after 1m0s
[02:05:06.698] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:98:0)
[02:05:06.698] Timer tick, asking Buildbucket for the build status
[02:05:07.100] Build 8898127140097164256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:05:07.100] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:99:0) after 1m0s
[02:06:07.152] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:99:0)
[02:06:07.152] Timer tick, asking Buildbucket for the build status
[02:06:07.691] Build 8898127140097164256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:06:07.691] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:100:0) after 1m0s
[02:07:07.762] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:100:0)
[02:07:07.762] Timer tick, asking Buildbucket for the build status
[02:07:07.904] Build 8898127140097164256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:07:07.904] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:101:0) after 1m0s
[02:08:07.995] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:101:0)
[02:08:07.995] Timer tick, asking Buildbucket for the build status
[02:08:08.332] Build 8898127140097164256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:08:08.332] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:102:0) after 1m0s
[02:09:08.390] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:102:0)
[02:09:08.390] Timer tick, asking Buildbucket for the build status
[02:09:08.614] Build 8898127140097164256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:09:08.614] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:103:0) after 1m0s
[02:10:08.750] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:103:0)
[02:10:08.750] Timer tick, asking Buildbucket for the build status
[02:10:09.406] Build 8898127140097164256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:10:09.406] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:104:0) after 1m0s
[02:11:09.523] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:104:0)
[02:11:09.523] Timer tick, asking Buildbucket for the build status
[02:11:09.700] Build 8898127140097164256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:11:09.700] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:105:0) after 1m0s
[02:12:09.698] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:105:0)
[02:12:09.698] Timer tick, asking Buildbucket for the build status
[02:12:09.794] Build 8898127140097164256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:12:09.794] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:106:0) after 1m0s
[02:13:09.809] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:106:0)
[02:13:09.809] Timer tick, asking Buildbucket for the build status
[02:13:10.078] Build 8898127140097164256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:13:10.078] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:107:0) after 1m0s
[02:14:10.187] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:107:0)
[02:14:10.187] Timer tick, asking Buildbucket for the build status
[02:14:10.380] Build 8898127140097164256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:14:10.380] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:108:0) after 1m0s
[02:15:10.404] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:108:0)
[02:15:10.404] Timer tick, asking Buildbucket for the build status
[02:15:10.507] Build 8898127140097164256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:15:10.507] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:109:0) after 1m0s
[02:16:10.612] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:109:0)
[02:16:10.612] Timer tick, asking Buildbucket for the build status
[02:16:10.798] Build 8898127140097164256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:16:10.798] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:110:0) after 1m0s
[02:17:10.956] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:110:0)
[02:17:10.956] Timer tick, asking Buildbucket for the build status
[02:17:11.118] Build 8898127140097164256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:17:11.118] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:111:0) after 1m0s
[02:18:11.144] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:111:0)
[02:18:11.144] Timer tick, asking Buildbucket for the build status
[02:18:11.318] Build 8898127140097164256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:18:11.318] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:112:0) after 1m0s
[02:19:11.393] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:112:0)
[02:19:11.393] Timer tick, asking Buildbucket for the build status
[02:19:11.578] Build 8898127140097164256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:19:11.578] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:113:0) after 1m0s
[02:20:11.643] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:113:0)
[02:20:11.643] Timer tick, asking Buildbucket for the build status
[02:20:11.800] Build 8898127140097164256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:20:11.800] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:114:0) after 1m0s
[02:21:11.848] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:114:0)
[02:21:11.848] Timer tick, asking Buildbucket for the build status
[02:21:12.089] Build 8898127140097164256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:21:12.089] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:115:0) after 1m0s
[02:22:12.115] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:115:0)
[02:22:12.115] Timer tick, asking Buildbucket for the build status
[02:22:12.303] Build 8898127140097164256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:22:12.303] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:116:0) after 1m0s
[02:23:12.361] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:116:0)
[02:23:12.361] Timer tick, asking Buildbucket for the build status
[02:23:12.619] Build 8898127140097164256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:23:12.619] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:117:0) after 1m0s
[02:24:12.714] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:117:0)
[02:24:12.714] Timer tick, asking Buildbucket for the build status
[02:24:12.986] Build 8898127140097164256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:24:12.986] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:118:0) after 1m0s
[02:25:13.013] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:118:0)
[02:25:13.013] Timer tick, asking Buildbucket for the build status
[02:25:13.307] Build 8898127140097164256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:25:13.307] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:119:0) after 1m0s
[02:26:13.397] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:119:0)
[02:26:13.397] Timer tick, asking Buildbucket for the build status
[02:26:13.597] Build 8898127140097164256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:26:13.597] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:120:0) after 1m0s
[02:27:13.689] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:120:0)
[02:27:13.689] Timer tick, asking Buildbucket for the build status
[02:27:13.899] Build 8898127140097164256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:27:13.899] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:121:0) after 1m0s
[02:28:13.962] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:121:0)
[02:28:13.962] Timer tick, asking Buildbucket for the build status
[02:28:14.086] Build 8898127140097164256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:28:14.086] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:122:0) after 1m0s
[02:29:14.145] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:122:0)
[02:29:14.145] Timer tick, asking Buildbucket for the build status
[02:29:14.547] Build 8898127140097164256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:29:14.547] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:123:0) after 1m0s
[02:30:14.562] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:123:0)
[02:30:14.562] Timer tick, asking Buildbucket for the build status
[02:30:14.734] Build 8898127140097164256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:30:14.734] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:124:0) after 1m0s
[02:31:14.803] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:124:0)
[02:31:14.803] Timer tick, asking Buildbucket for the build status
[02:31:15.442] Build 8898127140097164256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:31:15.442] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:125:0) after 1m0s
[02:32:15.474] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:125:0)
[02:32:15.474] Timer tick, asking Buildbucket for the build status
[02:32:15.591] Build 8898127140097164256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:32:15.591] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:126:0) after 1m0s
[02:33:15.643] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:126:0)
[02:33:15.643] Timer tick, asking Buildbucket for the build status
[02:33:15.990] Build 8898127140097164256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:33:15.990] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:127:0) after 1m0s
[02:34:16.066] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:127:0)
[02:34:16.066] Timer tick, asking Buildbucket for the build status
[02:34:16.387] Build 8898127140097164256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:34:16.387] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:128:0) after 1m0s
[02:35:16.449] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:128:0)
[02:35:16.449] Timer tick, asking Buildbucket for the build status
[02:35:16.713] Build 8898127140097164256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:35:16.713] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:129:0) after 1m0s
[02:36:16.855] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:129:0)
[02:36:16.855] Timer tick, asking Buildbucket for the build status
[02:36:17.062] Build 8898127140097164256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:36:17.062] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:130:0) after 1m0s
[02:37:17.138] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:130:0)
[02:37:17.138] Timer tick, asking Buildbucket for the build status
[02:37:17.245] Build 8898127140097164256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:37:17.245] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:131:0) after 1m0s
[02:38:17.322] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:131:0)
[02:38:17.322] Timer tick, asking Buildbucket for the build status
[02:38:17.471] Build 8898127140097164256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:38:17.471] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:132:0) after 1m0s
[02:39:17.571] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:132:0)
[02:39:17.571] Timer tick, asking Buildbucket for the build status
[02:39:17.850] Build 8898127140097164256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:39:17.850] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:133:0) after 1m0s
[02:40:17.880] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:133:0)
[02:40:17.880] Timer tick, asking Buildbucket for the build status
[02:40:18.266] Build 8898127140097164256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:40:18.266] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:134:0) after 1m0s
[02:41:18.304] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:134:0)
[02:41:18.304] Timer tick, asking Buildbucket for the build status
[02:41:18.581] Build 8898127140097164256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:41:18.581] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:135:0) after 1m0s
[02:42:18.568] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:135:0)
[02:42:18.568] Timer tick, asking Buildbucket for the build status
[02:42:18.989] Build 8898127140097164256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:42:18.990] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9063557202288775568:136:0) after 1m0s
[02:43:15.269] Received PubSub notification, asking Buildbucket for the build status
[02:43:15.356] Build 8898127140097164256: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[02:43:15.356] Buildbucket build:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary_preference": "PROD",
"completed_ts": "1572489794446677",
"created_by": "project:emscripten-releases",
"created_ts": "1572481704579256",
"id": "8898127140097164256",
"parameters_json": "{\"builder_name\": \"linux\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"a3a095b68bcf0f127635b1b6c548332bc7a8ef72\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@a3a095b68bcf0f127635b1b6c548332bc7a8ef72\", \"title\": \"a3a095b68bcf0f127635b1b6c548332bc7a8ef72\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/a3a095b68bcf0f127635b1b6c548332bc7a8ef72\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"a3a095b68bcf0f127635b1b6c548332bc7a8ef72\"}}",
"project": "emscripten-releases",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/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\": \"a3a095b68bcf0f127635b1b6c548332bc7a8ef72\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@a3a095b68bcf0f127635b1b6c548332bc7a8ef72\", \"title\": \"a3a095b68bcf0f127635b1b6c548332bc7a8ef72\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/a3a095b68bcf0f127635b1b6c548332bc7a8ef72\"}]}, \"bot_id\": \"luci-emscripten-releases-ci-xenial-0-gl8j\", \"branch\": \"refs/heads/master\", \"buildername\": \"linux\", \"got_revision\": \"a3a095b68bcf0f127635b1b6c548332bc7a8ef72\", \"path_config\": \"generic\", \"recipe\": \"emscripten_releases\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"a3a095b68bcf0f127635b1b6c548332bc7a8ef72\"}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"builder_461cb3affc6f270af3ac9b28cfd8af8c60e3aebc1d1ae3ebd8f2aa2c00b55fae_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"host_class\": [\"default\"], \"id\": [\"luci-emscripten-releases-ci-xenial-0-gl8j\"], \"image\": [\"chrome-xenial-19102400-d35fedf5a0d\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"n1-standard-8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16.04\"], \"pool\": [\"luci.emscripten-releases.ci\"], \"python\": [\"2.7.12\"], \"server_version\": [\"4594-a77d3be\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-b\"]}}}",
"service_account": "emscripten-releases-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1572485719191434",
"status": "COMPLETED",
"status_changed_ts": "1572489794818869",
"tags": [
"builder:linux",
"buildset:commit/git/a3a095b68bcf0f127635b1b6c548332bc7a8ef72",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/a3a095b68bcf0f127635b1b6c548332bc7a8ef72",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9063557202288775568",
"scheduler_job_id:emscripten-releases/linux",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/emscripten-releases/buildbucket/cr-buildbucket.appspot.com/8898127140097164256/+/annotations",
"swarming_tag:luci_project:emscripten-releases",
"swarming_tag:os:Linux",
"swarming_tag:recipe_name:emscripten_releases",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:48380831509f9310",
"user_agent:luci-scheduler"
],
"updated_ts": "1572489794841347",
"url": "https://ci.chromium.org/b/8898127140097164256",
"utcnow_ts": "1572489795343730"
}
}
[02:43:15.356] Invocation finished in 2h14m52.249840012s with status SUCCEEDED