[17:15:08.254] New invocation is queued and will start shortly
[17:15:09.353] Starting the invocation (attempt 1)
[17:15:09.392] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[17:15:09.392] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[17:15:09.392] Buildbucket request:
{
"bucket": "luci.emscripten-releases.ci",
"client_operation_id": "9072372365625170896",
"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\":\"1f5b195ea8dacb1ccbfab3f20a3e75de59456e61\"},\"id\":\"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@1f5b195ea8dacb1ccbfab3f20a3e75de59456e61\",\"title\":\"1f5b195ea8dacb1ccbfab3f20a3e75de59456e61\",\"url\":\"https://chromium.googlesource.com/emscripten-releases/+/1f5b195ea8dacb1ccbfab3f20a3e75de59456e61\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/emscripten-releases\",\"revision\":\"1f5b195ea8dacb1ccbfab3f20a3e75de59456e61\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux",
"scheduler_invocation_id:9072372365625170896",
"scheduler_job_id:emscripten-releases/linux",
"user_agent:luci-scheduler",
"buildset:commit/git/1f5b195ea8dacb1ccbfab3f20a3e75de59456e61",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/1f5b195ea8dacb1ccbfab3f20a3e75de59456e61",
"gitiles_ref:refs/heads/master"
]
}
[17:15:11.093] Buildbucket response:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary_preference": "PROD",
"created_by": "project:emscripten-releases",
"created_ts": "1564074909579484",
"id": "8906942303571891504",
"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\": \"1f5b195ea8dacb1ccbfab3f20a3e75de59456e61\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@1f5b195ea8dacb1ccbfab3f20a3e75de59456e61\", \"title\": \"1f5b195ea8dacb1ccbfab3f20a3e75de59456e61\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/1f5b195ea8dacb1ccbfab3f20a3e75de59456e61\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"1f5b195ea8dacb1ccbfab3f20a3e75de59456e61\"}}",
"project": "emscripten-releases",
"result_details_json": "{\"properties\": {}}",
"service_account": "emscripten-releases-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1564074910785875",
"tags": [
"builder:linux",
"buildset:commit/git/1f5b195ea8dacb1ccbfab3f20a3e75de59456e61",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/1f5b195ea8dacb1ccbfab3f20a3e75de59456e61",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9072372365625170896",
"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/8906942303571891504/+/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": "1564074910786035",
"url": "https://ci.chromium.org/b/8906942303571891504",
"utcnow_ts": "1564074911085271"
}
}
[17:15:11.093] Task URL: https://ci.chromium.org/b/8906942303571891504
[17:15:11.093] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:2:0) after 1m0s
[17:16:11.325] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:2:0)
[17:16:11.325] Timer tick, asking Buildbucket for the build status
[17:16:11.469] Build 8906942303571891504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:16:11.469] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:3:0) after 1m0s
[17:17:11.658] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:3:0)
[17:17:11.658] Timer tick, asking Buildbucket for the build status
[17:17:11.865] Build 8906942303571891504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:17:11.865] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:4:0) after 1m0s
[17:18:11.931] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:4:0)
[17:18:11.931] Timer tick, asking Buildbucket for the build status
[17:18:12.107] Build 8906942303571891504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:18:12.107] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:5:0) after 1m0s
[17:19:12.178] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:5:0)
[17:19:12.178] Timer tick, asking Buildbucket for the build status
[17:19:12.312] Build 8906942303571891504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:19:12.312] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:6:0) after 1m0s
[17:20:12.379] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:6:0)
[17:20:12.379] Timer tick, asking Buildbucket for the build status
[17:20:12.709] Build 8906942303571891504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:20:12.710] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:7:0) after 1m0s
[17:21:12.837] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:7:0)
[17:21:12.837] Timer tick, asking Buildbucket for the build status
[17:21:13.339] Build 8906942303571891504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:21:13.339] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:8:0) after 1m0s
[17:22:13.377] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:8:0)
[17:22:13.377] Timer tick, asking Buildbucket for the build status
[17:22:13.533] Build 8906942303571891504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:22:13.533] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:9:0) after 1m0s
[17:23:13.753] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:9:0)
[17:23:13.753] Timer tick, asking Buildbucket for the build status
[17:23:13.863] Build 8906942303571891504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:23:13.863] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:10:0) after 1m0s
[17:24:14.007] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:10:0)
[17:24:14.007] Timer tick, asking Buildbucket for the build status
[17:24:14.185] Build 8906942303571891504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:24:14.185] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:11:0) after 1m0s
[17:25:14.203] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:11:0)
[17:25:14.203] Timer tick, asking Buildbucket for the build status
[17:25:14.418] Build 8906942303571891504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:25:14.418] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:12:0) after 1m0s
[17:26:14.598] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:12:0)
[17:26:14.598] Timer tick, asking Buildbucket for the build status
[17:26:14.755] Build 8906942303571891504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:26:14.755] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:13:0) after 1m0s
[17:27:14.795] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:13:0)
[17:27:14.795] Timer tick, asking Buildbucket for the build status
[17:27:14.906] Build 8906942303571891504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:27:14.906] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:14:0) after 1m0s
[17:28:15.008] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:14:0)
[17:28:15.008] Timer tick, asking Buildbucket for the build status
[17:28:17.543] Build 8906942303571891504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:28:17.543] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:15:0) after 1m0s
[17:29:17.705] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:15:0)
[17:29:17.705] Timer tick, asking Buildbucket for the build status
[17:29:18.057] Build 8906942303571891504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:29:18.057] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:16:0) after 1m0s
[17:30:18.138] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:16:0)
[17:30:18.138] Timer tick, asking Buildbucket for the build status
[17:30:18.473] Build 8906942303571891504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:30:18.473] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:17:0) after 1m0s
[17:31:18.500] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:17:0)
[17:31:18.500] Timer tick, asking Buildbucket for the build status
[17:31:18.693] Build 8906942303571891504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:31:18.693] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:18:0) after 1m0s
[17:32:18.745] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:18:0)
[17:32:18.745] Timer tick, asking Buildbucket for the build status
[17:32:18.890] Build 8906942303571891504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:32:18.890] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:19:0) after 1m0s
[17:33:18.871] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:19:0)
[17:33:18.871] Timer tick, asking Buildbucket for the build status
[17:33:19.049] Build 8906942303571891504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:33:19.049] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:20:0) after 1m0s
[17:34:19.098] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:20:0)
[17:34:19.098] Timer tick, asking Buildbucket for the build status
[17:34:19.205] Build 8906942303571891504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:34:19.205] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:21:0) after 1m0s
[17:35:19.333] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:21:0)
[17:35:19.333] Timer tick, asking Buildbucket for the build status
[17:35:19.942] Build 8906942303571891504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:35:19.942] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:22:0) after 1m0s
[17:36:19.968] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:22:0)
[17:36:19.968] Timer tick, asking Buildbucket for the build status
[17:36:20.182] Build 8906942303571891504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:36:20.182] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:23:0) after 1m0s
[17:37:20.246] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:23:0)
[17:37:20.246] Timer tick, asking Buildbucket for the build status
[17:37:20.533] Build 8906942303571891504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:37:20.533] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:24:0) after 1m0s
[17:38:20.616] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:24:0)
[17:38:20.616] Timer tick, asking Buildbucket for the build status
[17:38:20.791] Build 8906942303571891504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:38:20.791] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:25:0) after 1m0s
[17:39:20.874] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:25:0)
[17:39:20.874] Timer tick, asking Buildbucket for the build status
[17:39:20.984] Build 8906942303571891504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:39:20.984] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:26:0) after 1m0s
[17:40:21.108] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:26:0)
[17:40:21.108] Timer tick, asking Buildbucket for the build status
[17:40:27.933] Build 8906942303571891504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:40:27.933] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:27:0) after 1m0s
[17:41:28.115] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:27:0)
[17:41:28.115] Timer tick, asking Buildbucket for the build status
[17:41:28.240] Build 8906942303571891504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:41:28.240] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:28:0) after 1m0s
[17:42:28.316] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:28:0)
[17:42:28.316] Timer tick, asking Buildbucket for the build status
[17:42:28.964] Build 8906942303571891504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:42:28.964] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:29:0) after 1m0s
[17:43:29.052] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:29:0)
[17:43:29.052] Timer tick, asking Buildbucket for the build status
[17:43:29.409] Build 8906942303571891504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:43:29.409] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:30:0) after 1m0s
[17:44:29.605] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:30:0)
[17:44:29.605] Timer tick, asking Buildbucket for the build status
[17:44:29.745] Build 8906942303571891504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:44:29.745] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:31:0) after 1m0s
[17:45:29.915] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:31:0)
[17:45:29.915] Timer tick, asking Buildbucket for the build status
[17:45:30.268] Build 8906942303571891504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:45:30.268] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:32:0) after 1m0s
[17:46:30.376] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:32:0)
[17:46:30.376] Timer tick, asking Buildbucket for the build status
[17:46:30.645] Build 8906942303571891504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:46:30.645] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:33:0) after 1m0s
[17:47:30.673] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:33:0)
[17:47:30.673] Timer tick, asking Buildbucket for the build status
[17:47:30.821] Build 8906942303571891504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:47:30.821] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:34:0) after 1m0s
[17:48:30.887] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:34:0)
[17:48:30.887] Timer tick, asking Buildbucket for the build status
[17:48:31.013] Build 8906942303571891504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:48:31.013] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:35:0) after 1m0s
[17:49:31.043] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:35:0)
[17:49:31.043] Timer tick, asking Buildbucket for the build status
[17:49:31.193] Build 8906942303571891504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:49:31.193] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:36:0) after 1m0s
[17:50:31.256] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:36:0)
[17:50:31.257] Timer tick, asking Buildbucket for the build status
[17:50:32.893] Build 8906942303571891504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:50:32.893] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:37:0) after 1m0s
[17:51:32.998] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:37:0)
[17:51:32.998] Timer tick, asking Buildbucket for the build status
[17:51:33.455] Build 8906942303571891504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:51:33.455] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:38:0) after 1m0s
[17:52:33.460] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:38:0)
[17:52:33.460] Timer tick, asking Buildbucket for the build status
[17:52:33.598] Build 8906942303571891504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:52:33.598] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:39:0) after 1m0s
[17:53:33.664] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:39:0)
[17:53:33.664] Timer tick, asking Buildbucket for the build status
[17:53:33.796] Build 8906942303571891504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:53:33.796] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:40:0) after 1m0s
[17:54:33.833] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:40:0)
[17:54:33.833] Timer tick, asking Buildbucket for the build status
[17:54:34.042] Build 8906942303571891504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:54:34.042] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:41:0) after 1m0s
[17:55:34.084] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:41:0)
[17:55:34.084] Timer tick, asking Buildbucket for the build status
[17:55:34.241] Build 8906942303571891504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:55:34.241] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:42:0) after 1m0s
[17:56:34.274] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:42:0)
[17:56:34.274] Timer tick, asking Buildbucket for the build status
[17:56:34.786] Build 8906942303571891504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:56:34.786] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:43:0) after 1m0s
[17:57:34.799] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:43:0)
[17:57:34.799] Timer tick, asking Buildbucket for the build status
[17:57:34.935] Build 8906942303571891504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:57:34.935] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:44:0) after 1m0s
[17:58:35.150] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:44:0)
[17:58:35.150] Timer tick, asking Buildbucket for the build status
[17:58:35.340] Build 8906942303571891504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:58:35.340] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:45:0) after 1m0s
[17:59:35.589] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:45:0)
[17:59:35.589] Timer tick, asking Buildbucket for the build status
[17:59:35.767] Build 8906942303571891504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:59:35.767] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:46:0) after 1m0s
[18:00:36.199] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:46:0)
[18:00:36.199] Timer tick, asking Buildbucket for the build status
[18:00:36.374] Build 8906942303571891504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:00:36.374] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:47:0) after 1m0s
[18:01:36.484] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:47:0)
[18:01:36.484] Timer tick, asking Buildbucket for the build status
[18:01:36.842] Build 8906942303571891504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:01:36.842] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:48:0) after 1m0s
[18:02:36.957] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:48:0)
[18:02:36.957] Timer tick, asking Buildbucket for the build status
[18:02:37.184] Build 8906942303571891504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:02:37.185] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:49:0) after 1m0s
[18:03:37.234] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:49:0)
[18:03:37.234] Timer tick, asking Buildbucket for the build status
[18:03:37.343] Build 8906942303571891504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:03:37.343] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:50:0) after 1m0s
[18:04:37.477] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:50:0)
[18:04:37.477] Timer tick, asking Buildbucket for the build status
[18:04:37.641] Build 8906942303571891504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:04:37.641] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:51:0) after 1m0s
[18:05:37.923] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:51:0)
[18:05:37.923] Timer tick, asking Buildbucket for the build status
[18:05:38.023] Build 8906942303571891504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:05:38.023] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:52:0) after 1m0s
[18:06:38.009] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:52:0)
[18:06:38.009] Timer tick, asking Buildbucket for the build status
[18:06:38.168] Build 8906942303571891504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:06:38.168] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:53:0) after 1m0s
[18:07:38.215] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:53:0)
[18:07:38.215] Timer tick, asking Buildbucket for the build status
[18:07:38.448] Build 8906942303571891504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:07:38.448] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:54:0) after 1m0s
[18:08:38.484] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:54:0)
[18:08:38.484] Timer tick, asking Buildbucket for the build status
[18:08:38.672] Build 8906942303571891504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:08:38.672] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:55:0) after 1m0s
[18:09:38.753] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:55:0)
[18:09:38.753] Timer tick, asking Buildbucket for the build status
[18:09:40.076] Build 8906942303571891504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:09:40.076] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:56:0) after 1m0s
[18:10:40.174] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:56:0)
[18:10:40.174] Timer tick, asking Buildbucket for the build status
[18:10:40.333] Build 8906942303571891504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:10:40.333] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:57:0) after 1m0s
[18:11:40.653] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:57:0)
[18:11:40.653] Timer tick, asking Buildbucket for the build status
[18:11:40.845] Build 8906942303571891504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:11:40.845] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:58:0) after 1m0s
[18:12:40.910] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:58:0)
[18:12:40.910] Timer tick, asking Buildbucket for the build status
[18:12:41.594] Build 8906942303571891504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:12:41.594] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:59:0) after 1m0s
[18:13:41.695] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:59:0)
[18:13:41.695] Timer tick, asking Buildbucket for the build status
[18:13:41.950] Build 8906942303571891504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:13:41.950] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:60:0) after 1m0s
[18:14:41.973] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:60:0)
[18:14:41.973] Timer tick, asking Buildbucket for the build status
[18:14:42.183] Build 8906942303571891504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:14:42.183] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:61:0) after 1m0s
[18:15:42.217] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:61:0)
[18:15:42.217] Timer tick, asking Buildbucket for the build status
[18:15:42.374] Build 8906942303571891504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:15:42.374] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:62:0) after 1m0s
[18:16:42.483] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:62:0)
[18:16:42.483] Timer tick, asking Buildbucket for the build status
[18:16:42.739] Build 8906942303571891504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:16:42.739] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:63:0) after 1m0s
[18:17:42.790] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:63:0)
[18:17:42.790] Timer tick, asking Buildbucket for the build status
[18:17:42.932] Build 8906942303571891504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:17:42.932] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:64:0) after 1m0s
[18:18:43.045] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:64:0)
[18:18:43.045] Timer tick, asking Buildbucket for the build status
[18:18:43.267] Build 8906942303571891504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:18:43.267] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:65:0) after 1m0s
[18:18:51.454] Received PubSub notification, asking Buildbucket for the build status
[18:18:51.728] Build 8906942303571891504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:19:43.367] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:65:0)
[18:19:43.367] Timer tick, asking Buildbucket for the build status
[18:19:43.531] Build 8906942303571891504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:19:43.531] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:67:0) after 1m0s
[18:20:43.610] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:67:0)
[18:20:43.610] Timer tick, asking Buildbucket for the build status
[18:20:43.812] Build 8906942303571891504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:20:43.812] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:68:0) after 1m0s
[18:21:43.927] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:68:0)
[18:21:43.927] Timer tick, asking Buildbucket for the build status
[18:21:44.072] Build 8906942303571891504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:21:44.072] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:69:0) after 1m0s
[18:22:44.324] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:69:0)
[18:22:44.324] Timer tick, asking Buildbucket for the build status
[18:22:44.506] Build 8906942303571891504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:22:44.506] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:70:0) after 1m0s
[18:23:44.604] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:70:0)
[18:23:44.604] Timer tick, asking Buildbucket for the build status
[18:23:44.843] Build 8906942303571891504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:23:44.843] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:71:0) after 1m0s
[18:24:45.061] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:71:0)
[18:24:45.061] Timer tick, asking Buildbucket for the build status
[18:24:45.182] Build 8906942303571891504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:24:45.182] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:72:0) after 1m0s
[18:25:45.412] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:72:0)
[18:25:45.412] Timer tick, asking Buildbucket for the build status
[18:25:47.263] Build 8906942303571891504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:25:47.263] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:73:0) after 1m0s
[18:26:47.695] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:73:0)
[18:26:47.695] Timer tick, asking Buildbucket for the build status
[18:26:48.257] Build 8906942303571891504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:26:48.257] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:74:0) after 1m0s
[18:27:48.289] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:74:0)
[18:27:48.289] Timer tick, asking Buildbucket for the build status
[18:27:49.078] Build 8906942303571891504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:27:49.078] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:75:0) after 1m0s
[18:28:49.209] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:75:0)
[18:28:49.209] Timer tick, asking Buildbucket for the build status
[18:28:49.367] Build 8906942303571891504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:28:49.367] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:76:0) after 1m0s
[18:29:49.445] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:76:0)
[18:29:49.445] Timer tick, asking Buildbucket for the build status
[18:29:49.791] Build 8906942303571891504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:29:49.791] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:77:0) after 1m0s
[18:30:49.829] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:77:0)
[18:30:49.829] Timer tick, asking Buildbucket for the build status
[18:30:49.933] Build 8906942303571891504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:30:49.933] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:78:0) after 1m0s
[18:31:50.064] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:78:0)
[18:31:50.064] Timer tick, asking Buildbucket for the build status
[18:31:50.246] Build 8906942303571891504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:31:50.246] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:79:0) after 1m0s
[18:32:50.401] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:79:0)
[18:32:50.401] Timer tick, asking Buildbucket for the build status
[18:32:51.046] Build 8906942303571891504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:32:51.047] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:80:0) after 1m0s
[18:33:51.143] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:80:0)
[18:33:51.143] Timer tick, asking Buildbucket for the build status
[18:33:51.269] Build 8906942303571891504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:33:51.269] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:81:0) after 1m0s
[18:34:51.385] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:81:0)
[18:34:51.385] Timer tick, asking Buildbucket for the build status
[18:34:51.671] Build 8906942303571891504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:34:51.671] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:82:0) after 1m0s
[18:35:51.779] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:82:0)
[18:35:51.779] Timer tick, asking Buildbucket for the build status
[18:35:52.036] Build 8906942303571891504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:35:52.036] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:83:0) after 1m0s
[18:36:52.356] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:83:0)
[18:36:52.356] Timer tick, asking Buildbucket for the build status
[18:36:52.860] Build 8906942303571891504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:36:52.860] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:84:0) after 1m0s
[18:37:52.929] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:84:0)
[18:37:52.929] Timer tick, asking Buildbucket for the build status
[18:37:53.065] Build 8906942303571891504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:37:53.065] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:85:0) after 1m0s
[18:38:53.133] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:85:0)
[18:38:53.133] Timer tick, asking Buildbucket for the build status
[18:38:53.242] Build 8906942303571891504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:38:53.242] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:86:0) after 1m0s
[18:39:53.313] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:86:0)
[18:39:53.313] Timer tick, asking Buildbucket for the build status
[18:39:53.511] Build 8906942303571891504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:39:53.511] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:87:0) after 1m0s
[18:40:53.555] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:87:0)
[18:40:53.555] Timer tick, asking Buildbucket for the build status
[18:40:53.797] Build 8906942303571891504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:40:53.797] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:88:0) after 1m0s
[18:41:53.888] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:88:0)
[18:41:53.888] Timer tick, asking Buildbucket for the build status
[18:41:54.095] Build 8906942303571891504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:41:54.097] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:89:0) after 1m0s
[18:42:54.178] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:89:0)
[18:42:54.178] Timer tick, asking Buildbucket for the build status
[18:42:54.287] Build 8906942303571891504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:42:54.287] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:90:0) after 1m0s
[18:43:54.369] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:90:0)
[18:43:54.369] Timer tick, asking Buildbucket for the build status
[18:43:54.606] Build 8906942303571891504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:43:54.606] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:91:0) after 1m0s
[18:44:54.689] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:91:0)
[18:44:54.689] Timer tick, asking Buildbucket for the build status
[18:44:55.404] Build 8906942303571891504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:44:55.404] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:92:0) after 1m0s
[18:45:55.464] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:92:0)
[18:45:55.464] Timer tick, asking Buildbucket for the build status
[18:45:55.727] Build 8906942303571891504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:45:55.727] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:93:0) after 1m0s
[18:46:55.758] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:93:0)
[18:46:55.758] Timer tick, asking Buildbucket for the build status
[18:46:55.953] Build 8906942303571891504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:46:55.953] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:94:0) after 1m0s
[18:47:56.011] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:94:0)
[18:47:56.011] Timer tick, asking Buildbucket for the build status
[18:47:56.205] Build 8906942303571891504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:47:56.205] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:95:0) after 1m0s
[18:48:56.241] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:95:0)
[18:48:56.241] Timer tick, asking Buildbucket for the build status
[18:48:56.391] Build 8906942303571891504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:48:56.391] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:96:0) after 1m0s
[18:49:56.561] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:96:0)
[18:49:56.561] Timer tick, asking Buildbucket for the build status
[18:49:56.709] Build 8906942303571891504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:49:56.709] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:97:0) after 1m0s
[18:50:56.770] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:97:0)
[18:50:56.770] Timer tick, asking Buildbucket for the build status
[18:50:56.888] Build 8906942303571891504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:50:56.888] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:98:0) after 1m0s
[18:51:56.956] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:98:0)
[18:51:56.956] Timer tick, asking Buildbucket for the build status
[18:51:57.863] Build 8906942303571891504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:51:57.863] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:99:0) after 1m0s
[18:52:58.088] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:99:0)
[18:52:58.088] Timer tick, asking Buildbucket for the build status
[18:52:58.214] Build 8906942303571891504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:52:58.214] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:100:0) after 1m0s
[18:53:58.260] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:100:0)
[18:53:58.260] Timer tick, asking Buildbucket for the build status
[18:53:58.622] Build 8906942303571891504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:53:58.622] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:101:0) after 1m0s
[18:54:58.785] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:101:0)
[18:54:58.786] Timer tick, asking Buildbucket for the build status
[18:54:58.968] Build 8906942303571891504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:54:58.968] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:102:0) after 1m0s
[18:55:59.035] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:102:0)
[18:55:59.035] Timer tick, asking Buildbucket for the build status
[18:55:59.142] Build 8906942303571891504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:55:59.142] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:103:0) after 1m0s
[18:56:59.249] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:103:0)
[18:56:59.249] Timer tick, asking Buildbucket for the build status
[18:56:59.613] Build 8906942303571891504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:56:59.613] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:104:0) after 1m0s
[18:57:59.652] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:104:0)
[18:57:59.652] Timer tick, asking Buildbucket for the build status
[18:57:59.784] Build 8906942303571891504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:57:59.784] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:105:0) after 1m0s
[18:58:59.798] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:105:0)
[18:58:59.798] Timer tick, asking Buildbucket for the build status
[18:59:00.028] Build 8906942303571891504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:59:00.028] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:106:0) after 1m0s
[19:00:00.093] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:106:0)
[19:00:00.093] Timer tick, asking Buildbucket for the build status
[19:00:00.477] Build 8906942303571891504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:00:00.477] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:107:0) after 1m0s
[19:01:00.572] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:107:0)
[19:01:00.572] Timer tick, asking Buildbucket for the build status
[19:01:00.688] Build 8906942303571891504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:01:00.688] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:108:0) after 1m0s
[19:02:00.747] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:108:0)
[19:02:00.747] Timer tick, asking Buildbucket for the build status
[19:02:00.842] Build 8906942303571891504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:02:00.842] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:109:0) after 1m0s
[19:03:01.027] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:109:0)
[19:03:01.027] Timer tick, asking Buildbucket for the build status
[19:03:01.640] Build 8906942303571891504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:03:01.640] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:110:0) after 1m0s
[19:04:01.678] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:110:0)
[19:04:01.678] Timer tick, asking Buildbucket for the build status
[19:04:01.939] Build 8906942303571891504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:04:01.939] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:111:0) after 1m0s
[19:05:02.051] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:111:0)
[19:05:02.051] Timer tick, asking Buildbucket for the build status
[19:05:02.271] Build 8906942303571891504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:05:02.271] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:112:0) after 1m0s
[19:06:02.376] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:112:0)
[19:06:02.376] Timer tick, asking Buildbucket for the build status
[19:06:02.582] Build 8906942303571891504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:06:02.582] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:113:0) after 1m0s
[19:07:02.670] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:113:0)
[19:07:02.670] Timer tick, asking Buildbucket for the build status
[19:07:02.853] Build 8906942303571891504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:07:02.853] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:114:0) after 1m0s
[19:08:02.922] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:114:0)
[19:08:02.922] Timer tick, asking Buildbucket for the build status
[19:08:03.263] Build 8906942303571891504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:08:03.263] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:115:0) after 1m0s
[19:09:03.408] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:115:0)
[19:09:03.408] Timer tick, asking Buildbucket for the build status
[19:09:05.025] Build 8906942303571891504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:09:05.025] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:116:0) after 1m0s
[19:10:05.083] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:116:0)
[19:10:05.083] Timer tick, asking Buildbucket for the build status
[19:10:05.255] Build 8906942303571891504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:10:05.255] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:117:0) after 1m0s
[19:11:05.293] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:117:0)
[19:11:05.293] Timer tick, asking Buildbucket for the build status
[19:11:05.484] Build 8906942303571891504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:11:05.484] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:118:0) after 1m0s
[19:12:05.512] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:118:0)
[19:12:05.512] Timer tick, asking Buildbucket for the build status
[19:12:05.650] Build 8906942303571891504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:12:05.650] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:119:0) after 1m0s
[19:13:05.684] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:119:0)
[19:13:05.684] Timer tick, asking Buildbucket for the build status
[19:13:06.413] Build 8906942303571891504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:13:06.413] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:120:0) after 1m0s
[19:14:06.522] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:120:0)
[19:14:06.522] Timer tick, asking Buildbucket for the build status
[19:14:06.682] Build 8906942303571891504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:14:06.682] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:121:0) after 1m0s
[19:15:06.797] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:121:0)
[19:15:06.797] Timer tick, asking Buildbucket for the build status
[19:15:07.014] Build 8906942303571891504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:15:07.014] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:122:0) after 1m0s
[19:16:07.139] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:122:0)
[19:16:07.139] Timer tick, asking Buildbucket for the build status
[19:16:07.482] Build 8906942303571891504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:16:07.482] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:123:0) after 1m0s
[19:17:07.537] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:123:0)
[19:17:07.537] Timer tick, asking Buildbucket for the build status
[19:17:07.664] Build 8906942303571891504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:17:07.664] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:124:0) after 1m0s
[19:18:07.700] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:124:0)
[19:18:07.700] Timer tick, asking Buildbucket for the build status
[19:18:07.831] Build 8906942303571891504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:18:07.831] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:125:0) after 1m0s
[19:19:07.862] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:125:0)
[19:19:07.862] Timer tick, asking Buildbucket for the build status
[19:19:08.115] Build 8906942303571891504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:19:08.115] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:126:0) after 1m0s
[19:20:08.187] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:126:0)
[19:20:08.187] Timer tick, asking Buildbucket for the build status
[19:20:08.360] Build 8906942303571891504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:20:08.360] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:127:0) after 1m0s
[19:21:08.411] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:127:0)
[19:21:08.411] Timer tick, asking Buildbucket for the build status
[19:21:08.570] Build 8906942303571891504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:21:08.570] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:128:0) after 1m0s
[19:22:08.617] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:128:0)
[19:22:08.617] Timer tick, asking Buildbucket for the build status
[19:22:09.903] Build 8906942303571891504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:22:09.903] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9072372365625170896:129:0) after 1m0s
[19:22:54.731] Received PubSub notification, asking Buildbucket for the build status
[19:22:54.843] Build 8906942303571891504: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[19:22:54.843] Buildbucket build:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary_preference": "PROD",
"completed_ts": "1564082572943222",
"created_by": "project:emscripten-releases",
"created_ts": "1564074909579484",
"id": "8906942303571891504",
"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\": \"1f5b195ea8dacb1ccbfab3f20a3e75de59456e61\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@1f5b195ea8dacb1ccbfab3f20a3e75de59456e61\", \"title\": \"1f5b195ea8dacb1ccbfab3f20a3e75de59456e61\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/1f5b195ea8dacb1ccbfab3f20a3e75de59456e61\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"1f5b195ea8dacb1ccbfab3f20a3e75de59456e61\"}}",
"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\": \"1f5b195ea8dacb1ccbfab3f20a3e75de59456e61\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@1f5b195ea8dacb1ccbfab3f20a3e75de59456e61\", \"title\": \"1f5b195ea8dacb1ccbfab3f20a3e75de59456e61\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/1f5b195ea8dacb1ccbfab3f20a3e75de59456e61\"}]}, \"bot_id\": \"luci-emscripten-releases-ci-xenial-0-lm4n\", \"branch\": \"refs/heads/master\", \"buildername\": \"linux\", \"got_revision\": \"1f5b195ea8dacb1ccbfab3f20a3e75de59456e61\", \"path_config\": \"generic\", \"recipe\": \"emscripten_releases\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"1f5b195ea8dacb1ccbfab3f20a3e75de59456e61\"}, \"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-lm4n\"], \"image\": [\"chrome-xenial-19052201-9cb74617499\"], \"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\": [\"4382-5929880\"], \"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": "1564078730670906",
"status": "COMPLETED",
"status_changed_ts": "1564082573844272",
"tags": [
"builder:linux",
"buildset:commit/git/1f5b195ea8dacb1ccbfab3f20a3e75de59456e61",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/1f5b195ea8dacb1ccbfab3f20a3e75de59456e61",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9072372365625170896",
"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/8906942303571891504/+/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:4642f2ae82a24510",
"user_agent:luci-scheduler"
],
"updated_ts": "1564082574088059",
"url": "https://ci.chromium.org/b/8906942303571891504",
"utcnow_ts": "1564082574821376"
}
}
[19:22:54.843] Invocation finished in 2h7m46.606581119s with status SUCCEEDED