[08:42:08.808] New invocation is queued and will start shortly
[08:42:10.163] Starting the invocation (attempt 1)
[08:42:10.202] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[08:42:10.202] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[08:42:10.202] Buildbucket request:
{
"bucket": "luci.emscripten-releases.ci",
"client_operation_id": "9068327776727670736",
"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\":\"264ac551bf85116f3193857171446e591ec9398a\"},\"id\":\"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@264ac551bf85116f3193857171446e591ec9398a\",\"title\":\"264ac551bf85116f3193857171446e591ec9398a\",\"url\":\"https://chromium.googlesource.com/emscripten-releases/+/264ac551bf85116f3193857171446e591ec9398a\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/emscripten-releases\",\"revision\":\"264ac551bf85116f3193857171446e591ec9398a\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux",
"scheduler_invocation_id:9068327776727670736",
"scheduler_job_id:emscripten-releases/linux",
"user_agent:luci-scheduler",
"buildset:commit/git/264ac551bf85116f3193857171446e591ec9398a",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/264ac551bf85116f3193857171446e591ec9398a",
"gitiles_ref:refs/heads/master"
]
}
[08:42:13.058] Buildbucket response:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary_preference": "PROD",
"created_by": "project:emscripten-releases",
"created_ts": "1567932130390496",
"id": "8902897714402681376",
"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\": \"264ac551bf85116f3193857171446e591ec9398a\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@264ac551bf85116f3193857171446e591ec9398a\", \"title\": \"264ac551bf85116f3193857171446e591ec9398a\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/264ac551bf85116f3193857171446e591ec9398a\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"264ac551bf85116f3193857171446e591ec9398a\"}}",
"project": "emscripten-releases",
"result_details_json": "{\"properties\": {}}",
"service_account": "emscripten-releases-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1567932132641105",
"tags": [
"builder:linux",
"buildset:commit/git/264ac551bf85116f3193857171446e591ec9398a",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/264ac551bf85116f3193857171446e591ec9398a",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9068327776727670736",
"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/8902897714402681376/+/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": "1567932132641294",
"url": "https://ci.chromium.org/b/8902897714402681376",
"utcnow_ts": "1567932133006013"
}
}
[08:42:13.058] Task URL: https://ci.chromium.org/b/8902897714402681376
[08:42:13.058] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9068327776727670736:2:0) after 1m0s
[08:42:21.576] Received PubSub notification, asking Buildbucket for the build status
[08:42:21.663] Build 8902897714402681376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:43:13.165] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9068327776727670736:2:0)
[08:43:13.165] Timer tick, asking Buildbucket for the build status
[08:43:13.468] Build 8902897714402681376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:43:13.469] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9068327776727670736:4:0) after 1m0s
[08:44:13.640] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9068327776727670736:4:0)
[08:44:13.640] Timer tick, asking Buildbucket for the build status
[08:44:13.762] Build 8902897714402681376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:44:13.762] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9068327776727670736:5:0) after 1m0s
[08:45:14.358] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9068327776727670736:5:0)
[08:45:14.358] Timer tick, asking Buildbucket for the build status
[08:45:14.473] Build 8902897714402681376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:45:14.473] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9068327776727670736:6:0) after 1m0s
[08:46:14.708] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9068327776727670736:6:0)
[08:46:14.708] Timer tick, asking Buildbucket for the build status
[08:46:14.874] Build 8902897714402681376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:46:14.874] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9068327776727670736:7:0) after 1m0s
[08:47:14.898] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9068327776727670736:7:0)
[08:47:14.898] Timer tick, asking Buildbucket for the build status
[08:47:15.231] Build 8902897714402681376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:47:15.231] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9068327776727670736:8:0) after 1m0s
[08:48:15.440] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9068327776727670736:8:0)
[08:48:15.440] Timer tick, asking Buildbucket for the build status
[08:48:15.635] Build 8902897714402681376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:48:15.635] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9068327776727670736:9:0) after 1m0s
[08:49:15.767] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9068327776727670736:9:0)
[08:49:15.767] Timer tick, asking Buildbucket for the build status
[08:49:16.195] Build 8902897714402681376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:49:16.195] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9068327776727670736:10:0) after 1m0s
[08:50:16.334] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9068327776727670736:10:0)
[08:50:16.334] Timer tick, asking Buildbucket for the build status
[08:50:16.490] Build 8902897714402681376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:50:16.490] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9068327776727670736:11:0) after 1m0s
[08:51:16.534] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9068327776727670736:11:0)
[08:51:16.534] Timer tick, asking Buildbucket for the build status
[08:51:16.805] Build 8902897714402681376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:51:16.805] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9068327776727670736:12:0) after 1m0s
[08:52:16.910] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9068327776727670736:12:0)
[08:52:16.910] Timer tick, asking Buildbucket for the build status
[08:52:17.172] Build 8902897714402681376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:52:17.172] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9068327776727670736:13:0) after 1m0s
[08:53:17.191] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9068327776727670736:13:0)
[08:53:17.191] Timer tick, asking Buildbucket for the build status
[08:53:17.652] Build 8902897714402681376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:53:17.652] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9068327776727670736:14:0) after 1m0s
[08:54:17.687] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9068327776727670736:14:0)
[08:54:17.687] Timer tick, asking Buildbucket for the build status
[08:54:18.587] Build 8902897714402681376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:54:18.587] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9068327776727670736:15:0) after 1m0s
[08:55:18.606] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9068327776727670736:15:0)
[08:55:18.606] Timer tick, asking Buildbucket for the build status
[08:55:18.767] Build 8902897714402681376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:55:18.767] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9068327776727670736:16:0) after 1m0s
[08:56:18.789] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9068327776727670736:16:0)
[08:56:18.789] Timer tick, asking Buildbucket for the build status
[08:56:19.212] Build 8902897714402681376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:56:19.212] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9068327776727670736:17:0) after 1m0s
[08:57:19.233] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9068327776727670736:17:0)
[08:57:19.233] Timer tick, asking Buildbucket for the build status
[08:57:19.390] Build 8902897714402681376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:57:19.390] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9068327776727670736:18:0) after 1m0s
[08:58:19.404] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9068327776727670736:18:0)
[08:58:19.404] Timer tick, asking Buildbucket for the build status
[08:58:19.601] Build 8902897714402681376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:58:19.601] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9068327776727670736:19:0) after 1m0s
[08:59:19.720] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9068327776727670736:19:0)
[08:59:19.720] Timer tick, asking Buildbucket for the build status
[08:59:20.036] Build 8902897714402681376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:59:20.036] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9068327776727670736:20:0) after 1m0s
[09:00:20.240] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9068327776727670736:20:0)
[09:00:20.240] Timer tick, asking Buildbucket for the build status
[09:00:22.435] Build 8902897714402681376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:00:22.435] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9068327776727670736:21:0) after 1m0s
[09:01:22.548] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9068327776727670736:21:0)
[09:01:22.548] Timer tick, asking Buildbucket for the build status
[09:01:22.653] Build 8902897714402681376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:01:22.653] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9068327776727670736:22:0) after 1m0s
[09:02:22.677] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9068327776727670736:22:0)
[09:02:22.677] Timer tick, asking Buildbucket for the build status
[09:02:22.808] Build 8902897714402681376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:02:22.808] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9068327776727670736:23:0) after 1m0s
[09:03:22.905] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9068327776727670736:23:0)
[09:03:22.905] Timer tick, asking Buildbucket for the build status
[09:03:23.245] Build 8902897714402681376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:03:23.245] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9068327776727670736:24:0) after 1m0s
[09:04:23.383] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9068327776727670736:24:0)
[09:04:23.383] Timer tick, asking Buildbucket for the build status
[09:04:24.271] Build 8902897714402681376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:04:24.271] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9068327776727670736:25:0) after 1m0s
[09:05:24.425] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9068327776727670736:25:0)
[09:05:24.425] Timer tick, asking Buildbucket for the build status
[09:05:24.791] Build 8902897714402681376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:05:24.791] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9068327776727670736:26:0) after 1m0s
[09:06:24.817] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9068327776727670736:26:0)
[09:06:24.817] Timer tick, asking Buildbucket for the build status
[09:06:25.179] Build 8902897714402681376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:06:25.179] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9068327776727670736:27:0) after 1m0s
[09:07:25.208] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9068327776727670736:27:0)
[09:07:25.208] Timer tick, asking Buildbucket for the build status
[09:07:25.341] Build 8902897714402681376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:07:25.341] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9068327776727670736:28:0) after 1m0s
[09:08:25.664] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9068327776727670736:28:0)
[09:08:25.664] Timer tick, asking Buildbucket for the build status
[09:08:26.156] Build 8902897714402681376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:08:26.156] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9068327776727670736:29:0) after 1m0s
[09:09:26.158] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9068327776727670736:29:0)
[09:09:26.158] Timer tick, asking Buildbucket for the build status
[09:09:26.277] Build 8902897714402681376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:09:26.277] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9068327776727670736:30:0) after 1m0s
[09:10:26.580] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9068327776727670736:30:0)
[09:10:26.580] Timer tick, asking Buildbucket for the build status
[09:10:27.045] Build 8902897714402681376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:10:27.045] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9068327776727670736:31:0) after 1m0s
[09:11:27.066] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9068327776727670736:31:0)
[09:11:27.066] Timer tick, asking Buildbucket for the build status
[09:11:27.243] Build 8902897714402681376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:11:27.243] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9068327776727670736:32:0) after 1m0s
[09:12:27.361] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9068327776727670736:32:0)
[09:12:27.361] Timer tick, asking Buildbucket for the build status
[09:12:27.592] Build 8902897714402681376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:12:27.592] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9068327776727670736:33:0) after 1m0s
[09:13:27.829] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9068327776727670736:33:0)
[09:13:27.829] Timer tick, asking Buildbucket for the build status
[09:13:28.393] Build 8902897714402681376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:13:28.393] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9068327776727670736:34:0) after 1m0s
[09:14:28.580] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9068327776727670736:34:0)
[09:14:28.580] Timer tick, asking Buildbucket for the build status
[09:14:29.021] Build 8902897714402681376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:14:29.022] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9068327776727670736:35:0) after 1m0s
[09:15:29.118] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9068327776727670736:35:0)
[09:15:29.118] Timer tick, asking Buildbucket for the build status
[09:15:29.315] Build 8902897714402681376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:15:29.315] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9068327776727670736:36:0) after 1m0s
[09:16:29.397] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9068327776727670736:36:0)
[09:16:29.397] Timer tick, asking Buildbucket for the build status
[09:16:29.494] Build 8902897714402681376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:16:29.494] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9068327776727670736:37:0) after 1m0s
[09:17:29.548] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9068327776727670736:37:0)
[09:17:29.548] Timer tick, asking Buildbucket for the build status
[09:17:29.918] Build 8902897714402681376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:17:29.918] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9068327776727670736:38:0) after 1m0s
[09:18:30.078] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9068327776727670736:38:0)
[09:18:30.078] Timer tick, asking Buildbucket for the build status
[09:18:30.499] Build 8902897714402681376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:18:30.499] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9068327776727670736:39:0) after 1m0s
[09:19:30.524] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9068327776727670736:39:0)
[09:19:30.524] Timer tick, asking Buildbucket for the build status
[09:19:30.759] Build 8902897714402681376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:19:30.759] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9068327776727670736:40:0) after 1m0s
[09:20:30.853] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9068327776727670736:40:0)
[09:20:30.853] Timer tick, asking Buildbucket for the build status
[09:20:31.281] Build 8902897714402681376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:20:31.281] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9068327776727670736:41:0) after 1m0s
[09:21:31.302] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9068327776727670736:41:0)
[09:21:31.302] Timer tick, asking Buildbucket for the build status
[09:21:31.613] Build 8902897714402681376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:21:31.613] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9068327776727670736:42:0) after 1m0s
[09:22:31.814] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9068327776727670736:42:0)
[09:22:31.814] Timer tick, asking Buildbucket for the build status
[09:22:31.937] Build 8902897714402681376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:22:31.937] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9068327776727670736:43:0) after 1m0s
[09:23:32.007] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9068327776727670736:43:0)
[09:23:32.007] Timer tick, asking Buildbucket for the build status
[09:23:32.154] Build 8902897714402681376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:23:32.154] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9068327776727670736:44:0) after 1m0s
[09:24:32.281] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9068327776727670736:44:0)
[09:24:32.281] Timer tick, asking Buildbucket for the build status
[09:24:32.446] Build 8902897714402681376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:24:32.446] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9068327776727670736:45:0) after 1m0s
[09:25:32.566] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9068327776727670736:45:0)
[09:25:32.566] Timer tick, asking Buildbucket for the build status
[09:25:32.860] Build 8902897714402681376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:25:32.860] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9068327776727670736:46:0) after 1m0s
[09:26:32.887] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9068327776727670736:46:0)
[09:26:32.887] Timer tick, asking Buildbucket for the build status
[09:26:33.262] Build 8902897714402681376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:26:33.262] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9068327776727670736:47:0) after 1m0s
[09:27:33.283] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9068327776727670736:47:0)
[09:27:33.283] Timer tick, asking Buildbucket for the build status
[09:27:33.428] Build 8902897714402681376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:27:33.428] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9068327776727670736:48:0) after 1m0s
[09:28:33.474] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9068327776727670736:48:0)
[09:28:33.474] Timer tick, asking Buildbucket for the build status
[09:28:33.914] Build 8902897714402681376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:28:33.914] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9068327776727670736:49:0) after 1m0s
[09:29:33.939] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9068327776727670736:49:0)
[09:29:33.939] Timer tick, asking Buildbucket for the build status
[09:29:34.170] Build 8902897714402681376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:29:34.170] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9068327776727670736:50:0) after 1m0s
[09:30:34.152] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9068327776727670736:50:0)
[09:30:34.152] Timer tick, asking Buildbucket for the build status
[09:30:34.294] Build 8902897714402681376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:30:34.294] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9068327776727670736:51:0) after 1m0s
[09:31:34.389] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9068327776727670736:51:0)
[09:31:34.389] Timer tick, asking Buildbucket for the build status
[09:31:34.807] Build 8902897714402681376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:31:34.807] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9068327776727670736:52:0) after 1m0s
[09:32:34.872] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9068327776727670736:52:0)
[09:32:34.872] Timer tick, asking Buildbucket for the build status
[09:32:35.235] Build 8902897714402681376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:32:35.235] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9068327776727670736:53:0) after 1m0s
[09:33:35.260] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9068327776727670736:53:0)
[09:33:35.260] Timer tick, asking Buildbucket for the build status
[09:33:35.523] Build 8902897714402681376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:33:35.523] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9068327776727670736:54:0) after 1m0s
[09:34:35.580] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9068327776727670736:54:0)
[09:34:35.580] Timer tick, asking Buildbucket for the build status
[09:34:36.261] Build 8902897714402681376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:34:36.261] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9068327776727670736:55:0) after 1m0s
[09:35:36.407] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9068327776727670736:55:0)
[09:35:36.407] Timer tick, asking Buildbucket for the build status
[09:35:36.704] Build 8902897714402681376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:35:36.704] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9068327776727670736:56:0) after 1m0s
[09:36:36.726] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9068327776727670736:56:0)
[09:36:36.726] Timer tick, asking Buildbucket for the build status
[09:36:37.206] Build 8902897714402681376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:36:37.206] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9068327776727670736:57:0) after 1m0s
[09:37:37.237] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9068327776727670736:57:0)
[09:37:37.237] Timer tick, asking Buildbucket for the build status
[09:37:37.642] Build 8902897714402681376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:37:37.642] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9068327776727670736:58:0) after 1m0s
[09:38:37.735] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9068327776727670736:58:0)
[09:38:37.735] Timer tick, asking Buildbucket for the build status
[09:38:37.920] Build 8902897714402681376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:38:37.920] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9068327776727670736:59:0) after 1m0s
[09:39:37.953] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9068327776727670736:59:0)
[09:39:37.953] Timer tick, asking Buildbucket for the build status
[09:39:38.093] Build 8902897714402681376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:39:38.093] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9068327776727670736:60:0) after 1m0s
[09:40:44.018] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9068327776727670736:60:0)
[09:40:44.018] Timer tick, asking Buildbucket for the build status
[09:40:44.748] Build 8902897714402681376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:40:44.748] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9068327776727670736:61:0) after 1m0s
[09:41:45.065] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9068327776727670736:61:0)
[09:41:45.065] Timer tick, asking Buildbucket for the build status
[09:41:46.108] Build 8902897714402681376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:41:46.108] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9068327776727670736:62:0) after 1m0s
[09:42:46.584] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9068327776727670736:62:0)
[09:42:46.584] Timer tick, asking Buildbucket for the build status
[09:42:46.717] Build 8902897714402681376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:42:46.717] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9068327776727670736:63:0) after 1m0s
[09:43:47.154] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9068327776727670736:63:0)
[09:43:47.154] Timer tick, asking Buildbucket for the build status
[09:43:47.343] Build 8902897714402681376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:43:47.343] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9068327776727670736:64:0) after 1m0s
[09:44:47.880] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9068327776727670736:64:0)
[09:44:47.880] Timer tick, asking Buildbucket for the build status
[09:44:48.112] Build 8902897714402681376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:44:48.112] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9068327776727670736:65:0) after 1m0s
[09:45:48.314] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9068327776727670736:65:0)
[09:45:48.314] Timer tick, asking Buildbucket for the build status
[09:45:48.441] Build 8902897714402681376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:45:48.441] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9068327776727670736:66:0) after 1m0s
[09:46:48.570] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9068327776727670736:66:0)
[09:46:48.570] Timer tick, asking Buildbucket for the build status
[09:46:49.018] Build 8902897714402681376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:46:49.018] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9068327776727670736:67:0) after 1m0s
[09:47:49.195] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9068327776727670736:67:0)
[09:47:49.195] Timer tick, asking Buildbucket for the build status
[09:47:49.326] Build 8902897714402681376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:47:49.326] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9068327776727670736:68:0) after 1m0s
[09:48:01.466] Received PubSub notification, asking Buildbucket for the build status
[09:48:01.799] Build 8902897714402681376: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[09:48:01.799] Buildbucket build:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary_preference": "PROD",
"completed_ts": "1567936080554165",
"created_by": "project:emscripten-releases",
"created_ts": "1567932130390496",
"id": "8902897714402681376",
"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\": \"264ac551bf85116f3193857171446e591ec9398a\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@264ac551bf85116f3193857171446e591ec9398a\", \"title\": \"264ac551bf85116f3193857171446e591ec9398a\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/264ac551bf85116f3193857171446e591ec9398a\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"264ac551bf85116f3193857171446e591ec9398a\"}}",
"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\": \"264ac551bf85116f3193857171446e591ec9398a\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@264ac551bf85116f3193857171446e591ec9398a\", \"title\": \"264ac551bf85116f3193857171446e591ec9398a\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/264ac551bf85116f3193857171446e591ec9398a\"}]}, \"bot_id\": \"luci-emscripten-releases-ci-xenial-0-jxk6\", \"branch\": \"refs/heads/master\", \"buildername\": \"linux\", \"got_revision\": \"264ac551bf85116f3193857171446e591ec9398a\", \"path_config\": \"generic\", \"recipe\": \"emscripten_releases\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"264ac551bf85116f3193857171446e591ec9398a\"}, \"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-jxk6\"], \"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\": [\"4417-ad2503b\"], \"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": "1567932140708620",
"status": "COMPLETED",
"status_changed_ts": "1567936080935512",
"tags": [
"builder:linux",
"buildset:commit/git/264ac551bf85116f3193857171446e591ec9398a",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/264ac551bf85116f3193857171446e591ec9398a",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9068327776727670736",
"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/8902897714402681376/+/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:4728db379157b310",
"user_agent:luci-scheduler"
],
"updated_ts": "1567936080945339",
"url": "https://ci.chromium.org/b/8902897714402681376",
"utcnow_ts": "1567936081777009"
}
}
[09:48:01.799] Invocation finished in 1h5m53.009790662s with status SUCCEEDED