[00:01:13.151] New invocation is queued and will start shortly
[00:01:14.936] Starting the invocation (attempt 1)
[00:01:14.994] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[00:01:14.994] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[00:01:14.994] Buildbucket request:
{
"bucket": "luci.emscripten-releases.ci",
"client_operation_id": "9061746972138453632",
"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\":\"6540b36d40b2ca3d0aae062547c842057fd53039\"},\"id\":\"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@6540b36d40b2ca3d0aae062547c842057fd53039\",\"title\":\"6540b36d40b2ca3d0aae062547c842057fd53039\",\"url\":\"https://chromium.googlesource.com/emscripten-releases/+/6540b36d40b2ca3d0aae062547c842057fd53039\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/emscripten-releases\",\"revision\":\"6540b36d40b2ca3d0aae062547c842057fd53039\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux",
"scheduler_invocation_id:9061746972138453632",
"scheduler_job_id:emscripten-releases/linux",
"user_agent:luci-scheduler",
"buildset:commit/git/6540b36d40b2ca3d0aae062547c842057fd53039",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/6540b36d40b2ca3d0aae062547c842057fd53039",
"gitiles_ref:refs/heads/master"
]
}
[00:01:15.626] Buildbucket response:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary_preference": "PROD",
"created_by": "project:emscripten-releases",
"created_ts": "1574208075158524",
"id": "8896316909341890864",
"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\": \"6540b36d40b2ca3d0aae062547c842057fd53039\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@6540b36d40b2ca3d0aae062547c842057fd53039\", \"title\": \"6540b36d40b2ca3d0aae062547c842057fd53039\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/6540b36d40b2ca3d0aae062547c842057fd53039\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"6540b36d40b2ca3d0aae062547c842057fd53039\"}}",
"project": "emscripten-releases",
"result_details_json": "{\"properties\": {}}",
"service_account": "emscripten-releases-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1574208075466481",
"tags": [
"builder:linux",
"buildset:commit/git/6540b36d40b2ca3d0aae062547c842057fd53039",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/6540b36d40b2ca3d0aae062547c842057fd53039",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9061746972138453632",
"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/8896316909341890864/+/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": "1574208075466595",
"url": "https://ci.chromium.org/b/8896316909341890864",
"utcnow_ts": "1574208075616614"
}
}
[00:01:15.626] Task URL: https://ci.chromium.org/b/8896316909341890864
[00:01:15.626] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061746972138453632:2:0) after 1m0s
[00:01:44.604] Received PubSub notification, asking Buildbucket for the build status
[00:01:44.716] Build 8896316909341890864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:02:15.829] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061746972138453632:2:0)
[00:02:15.829] Timer tick, asking Buildbucket for the build status
[00:02:17.437] Build 8896316909341890864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:02:17.437] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061746972138453632:4:0) after 1m0s
[00:03:17.796] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061746972138453632:4:0)
[00:03:17.796] Timer tick, asking Buildbucket for the build status
[00:03:18.026] Build 8896316909341890864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:03:18.026] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061746972138453632:5:0) after 1m0s
[00:04:18.135] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061746972138453632:5:0)
[00:04:18.135] Timer tick, asking Buildbucket for the build status
[00:04:18.266] Build 8896316909341890864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:04:18.266] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061746972138453632:6:0) after 1m0s
[00:05:18.384] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061746972138453632:6:0)
[00:05:18.384] Timer tick, asking Buildbucket for the build status
[00:05:19.898] Build 8896316909341890864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:05:19.898] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061746972138453632:7:0) after 1m0s
[00:06:20.010] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061746972138453632:7:0)
[00:06:20.010] Timer tick, asking Buildbucket for the build status
[00:06:21.684] Build 8896316909341890864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:06:21.684] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061746972138453632:8:0) after 1m0s
[00:07:21.709] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061746972138453632:8:0)
[00:07:21.709] Timer tick, asking Buildbucket for the build status
[00:07:22.065] Build 8896316909341890864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:07:22.065] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061746972138453632:9:0) after 1m0s
[00:08:22.183] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061746972138453632:9:0)
[00:08:22.183] Timer tick, asking Buildbucket for the build status
[00:08:22.710] Build 8896316909341890864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:08:22.710] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061746972138453632:10:0) after 1m0s
[00:09:22.725] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061746972138453632:10:0)
[00:09:22.725] Timer tick, asking Buildbucket for the build status
[00:09:22.987] Build 8896316909341890864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:09:22.987] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061746972138453632:11:0) after 1m0s
[00:10:23.195] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061746972138453632:11:0)
[00:10:23.195] Timer tick, asking Buildbucket for the build status
[00:10:23.258] Build 8896316909341890864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:10:23.258] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061746972138453632:12:0) after 1m0s
[00:11:23.511] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061746972138453632:12:0)
[00:11:23.511] Timer tick, asking Buildbucket for the build status
[00:11:23.996] Build 8896316909341890864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:11:23.996] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061746972138453632:13:0) after 1m0s
[00:12:24.046] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061746972138453632:13:0)
[00:12:24.046] Timer tick, asking Buildbucket for the build status
[00:12:27.367] Build 8896316909341890864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:12:27.367] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061746972138453632:14:0) after 1m0s
[00:13:27.415] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061746972138453632:14:0)
[00:13:27.415] Timer tick, asking Buildbucket for the build status
[00:13:27.921] Build 8896316909341890864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:13:27.921] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061746972138453632:15:0) after 1m0s
[00:14:28.019] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061746972138453632:15:0)
[00:14:28.019] Timer tick, asking Buildbucket for the build status
[00:14:28.156] Build 8896316909341890864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:14:28.156] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061746972138453632:16:0) after 1m0s
[00:15:28.203] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061746972138453632:16:0)
[00:15:28.203] Timer tick, asking Buildbucket for the build status
[00:15:28.324] Build 8896316909341890864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:15:28.324] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061746972138453632:17:0) after 1m0s
[00:16:28.436] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061746972138453632:17:0)
[00:16:28.436] Timer tick, asking Buildbucket for the build status
[00:16:28.730] Build 8896316909341890864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:16:28.730] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061746972138453632:18:0) after 1m0s
[00:17:28.768] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061746972138453632:18:0)
[00:17:28.768] Timer tick, asking Buildbucket for the build status
[00:17:29.024] Build 8896316909341890864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:17:29.024] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061746972138453632:19:0) after 1m0s
[00:18:29.183] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061746972138453632:19:0)
[00:18:29.183] Timer tick, asking Buildbucket for the build status
[00:18:29.408] Build 8896316909341890864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:18:29.408] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061746972138453632:20:0) after 1m0s
[00:19:29.600] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061746972138453632:20:0)
[00:19:29.600] Timer tick, asking Buildbucket for the build status
[00:19:30.951] Build 8896316909341890864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:19:30.951] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061746972138453632:21:0) after 1m0s
[00:20:31.102] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061746972138453632:21:0)
[00:20:31.102] Timer tick, asking Buildbucket for the build status
[00:20:31.331] Build 8896316909341890864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:20:31.331] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061746972138453632:22:0) after 1m0s
[00:21:31.469] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061746972138453632:22:0)
[00:21:31.469] Timer tick, asking Buildbucket for the build status
[00:21:31.764] Build 8896316909341890864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:21:31.764] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061746972138453632:23:0) after 1m0s
[00:22:31.833] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061746972138453632:23:0)
[00:22:31.833] Timer tick, asking Buildbucket for the build status
[00:22:32.128] Build 8896316909341890864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:22:32.128] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061746972138453632:24:0) after 1m0s
[00:23:32.137] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061746972138453632:24:0)
[00:23:32.137] Timer tick, asking Buildbucket for the build status
[00:23:32.425] Build 8896316909341890864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:23:32.425] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061746972138453632:25:0) after 1m0s
[00:24:32.601] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061746972138453632:25:0)
[00:24:32.601] Timer tick, asking Buildbucket for the build status
[00:24:32.853] Build 8896316909341890864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:24:32.853] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061746972138453632:26:0) after 1m0s
[00:25:32.881] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061746972138453632:26:0)
[00:25:32.881] Timer tick, asking Buildbucket for the build status
[00:25:33.336] Build 8896316909341890864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:25:33.336] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061746972138453632:27:0) after 1m0s
[00:26:33.471] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061746972138453632:27:0)
[00:26:33.471] Timer tick, asking Buildbucket for the build status
[00:26:33.662] Build 8896316909341890864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:26:33.662] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061746972138453632:28:0) after 1m0s
[00:27:33.709] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061746972138453632:28:0)
[00:27:33.709] Timer tick, asking Buildbucket for the build status
[00:27:34.634] Build 8896316909341890864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:27:34.634] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061746972138453632:29:0) after 1m0s
[00:28:34.735] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061746972138453632:29:0)
[00:28:34.735] Timer tick, asking Buildbucket for the build status
[00:28:34.881] Build 8896316909341890864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:28:34.882] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061746972138453632:30:0) after 1m0s
[00:29:35.099] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061746972138453632:30:0)
[00:29:35.099] Timer tick, asking Buildbucket for the build status
[00:29:35.579] Build 8896316909341890864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:29:35.579] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061746972138453632:31:0) after 1m0s
[00:30:35.628] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061746972138453632:31:0)
[00:30:35.628] Timer tick, asking Buildbucket for the build status
[00:30:35.770] Build 8896316909341890864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:30:35.770] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061746972138453632:32:0) after 1m0s
[00:31:35.764] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061746972138453632:32:0)
[00:31:35.764] Timer tick, asking Buildbucket for the build status
[00:31:35.879] Build 8896316909341890864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:31:35.879] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061746972138453632:33:0) after 1m0s
[00:32:36.031] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061746972138453632:33:0)
[00:32:36.031] Timer tick, asking Buildbucket for the build status
[00:32:36.164] Build 8896316909341890864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:32:36.164] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061746972138453632:34:0) after 1m0s
[00:33:36.265] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061746972138453632:34:0)
[00:33:36.265] Timer tick, asking Buildbucket for the build status
[00:33:36.467] Build 8896316909341890864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:33:36.467] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061746972138453632:35:0) after 1m0s
[00:34:36.544] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061746972138453632:35:0)
[00:34:36.544] Timer tick, asking Buildbucket for the build status
[00:34:36.748] Build 8896316909341890864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:34:36.748] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061746972138453632:36:0) after 1m0s
[00:35:36.824] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061746972138453632:36:0)
[00:35:36.824] Timer tick, asking Buildbucket for the build status
[00:35:36.900] Build 8896316909341890864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:35:36.900] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061746972138453632:37:0) after 1m0s
[00:36:36.936] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061746972138453632:37:0)
[00:36:36.936] Timer tick, asking Buildbucket for the build status
[00:36:37.598] Build 8896316909341890864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:36:37.598] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061746972138453632:38:0) after 1m0s
[00:37:37.639] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061746972138453632:38:0)
[00:37:37.639] Timer tick, asking Buildbucket for the build status
[00:37:37.791] Build 8896316909341890864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:37:37.791] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061746972138453632:39:0) after 1m0s
[00:38:37.843] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061746972138453632:39:0)
[00:38:37.843] Timer tick, asking Buildbucket for the build status
[00:38:38.033] Build 8896316909341890864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:38:38.033] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061746972138453632:40:0) after 1m0s
[00:39:38.066] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061746972138453632:40:0)
[00:39:38.066] Timer tick, asking Buildbucket for the build status
[00:39:38.151] Build 8896316909341890864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:39:38.151] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061746972138453632:41:0) after 1m0s
[00:40:38.235] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061746972138453632:41:0)
[00:40:38.235] Timer tick, asking Buildbucket for the build status
[00:40:38.691] Build 8896316909341890864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:40:38.691] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061746972138453632:42:0) after 1m0s
[00:41:38.785] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061746972138453632:42:0)
[00:41:38.785] Timer tick, asking Buildbucket for the build status
[00:41:39.083] Build 8896316909341890864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:41:39.083] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061746972138453632:43:0) after 1m0s
[00:42:39.182] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061746972138453632:43:0)
[00:42:39.182] Timer tick, asking Buildbucket for the build status
[00:42:39.297] Build 8896316909341890864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:42:39.297] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061746972138453632:44:0) after 1m0s
[00:43:39.498] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061746972138453632:44:0)
[00:43:39.498] Timer tick, asking Buildbucket for the build status
[00:43:39.568] Build 8896316909341890864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:43:39.569] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061746972138453632:45:0) after 1m0s
[00:44:39.765] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061746972138453632:45:0)
[00:44:39.765] Timer tick, asking Buildbucket for the build status
[00:44:40.285] Build 8896316909341890864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:44:40.285] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061746972138453632:46:0) after 1m0s
[00:45:40.497] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061746972138453632:46:0)
[00:45:40.497] Timer tick, asking Buildbucket for the build status
[00:45:40.942] Build 8896316909341890864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:45:40.942] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061746972138453632:47:0) after 1m0s
[00:46:41.036] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061746972138453632:47:0)
[00:46:41.036] Timer tick, asking Buildbucket for the build status
[00:46:41.154] Build 8896316909341890864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:46:41.154] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061746972138453632:48:0) after 1m0s
[00:47:41.258] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061746972138453632:48:0)
[00:47:41.258] Timer tick, asking Buildbucket for the build status
[00:47:42.103] Build 8896316909341890864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:47:42.103] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061746972138453632:49:0) after 1m0s
[00:48:42.196] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061746972138453632:49:0)
[00:48:42.196] Timer tick, asking Buildbucket for the build status
[00:48:42.611] Build 8896316909341890864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:48:42.611] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061746972138453632:50:0) after 1m0s
[00:49:42.696] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061746972138453632:50:0)
[00:49:42.696] Timer tick, asking Buildbucket for the build status
[00:49:42.880] Build 8896316909341890864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:49:42.880] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061746972138453632:51:0) after 1m0s
[00:50:43.036] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061746972138453632:51:0)
[00:50:43.036] Timer tick, asking Buildbucket for the build status
[00:50:43.229] Build 8896316909341890864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:50:43.229] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061746972138453632:52:0) after 1m0s
[00:51:43.279] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061746972138453632:52:0)
[00:51:43.279] Timer tick, asking Buildbucket for the build status
[00:51:43.441] Build 8896316909341890864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:51:43.441] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061746972138453632:53:0) after 1m0s
[00:52:43.560] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061746972138453632:53:0)
[00:52:43.560] Timer tick, asking Buildbucket for the build status
[00:52:43.986] Build 8896316909341890864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:52:43.986] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061746972138453632:54:0) after 1m0s
[00:53:44.024] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061746972138453632:54:0)
[00:53:44.024] Timer tick, asking Buildbucket for the build status
[00:53:44.603] Build 8896316909341890864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:53:44.603] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061746972138453632:55:0) after 1m0s
[00:54:44.646] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061746972138453632:55:0)
[00:54:44.646] Timer tick, asking Buildbucket for the build status
[00:54:44.906] Build 8896316909341890864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:54:44.906] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061746972138453632:56:0) after 1m0s
[00:55:45.021] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061746972138453632:56:0)
[00:55:45.021] Timer tick, asking Buildbucket for the build status
[00:55:45.240] Build 8896316909341890864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:55:45.240] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061746972138453632:57:0) after 1m0s
[00:56:45.311] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061746972138453632:57:0)
[00:56:45.311] Timer tick, asking Buildbucket for the build status
[00:56:45.385] Build 8896316909341890864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:56:45.385] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061746972138453632:58:0) after 1m0s
[00:57:45.515] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061746972138453632:58:0)
[00:57:45.515] Timer tick, asking Buildbucket for the build status
[00:57:45.663] Build 8896316909341890864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:57:45.663] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061746972138453632:59:0) after 1m0s
[00:58:45.699] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061746972138453632:59:0)
[00:58:45.699] Timer tick, asking Buildbucket for the build status
[00:58:45.871] Build 8896316909341890864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:58:45.871] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061746972138453632:60:0) after 1m0s
[00:59:46.016] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061746972138453632:60:0)
[00:59:46.016] Timer tick, asking Buildbucket for the build status
[00:59:46.251] Build 8896316909341890864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:59:46.251] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061746972138453632:61:0) after 1m0s
[01:00:46.380] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061746972138453632:61:0)
[01:00:46.380] Timer tick, asking Buildbucket for the build status
[01:00:47.412] Build 8896316909341890864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:00:47.412] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061746972138453632:62:0) after 1m0s
[01:01:47.806] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061746972138453632:62:0)
[01:01:47.806] Timer tick, asking Buildbucket for the build status
[01:01:48.022] Build 8896316909341890864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:01:48.022] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061746972138453632:63:0) after 1m0s
[01:02:48.162] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061746972138453632:63:0)
[01:02:48.162] Timer tick, asking Buildbucket for the build status
[01:02:49.858] Build 8896316909341890864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:02:49.858] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061746972138453632:64:0) after 1m0s
[01:03:49.946] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061746972138453632:64:0)
[01:03:49.946] Timer tick, asking Buildbucket for the build status
[01:03:50.175] Build 8896316909341890864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:03:50.175] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061746972138453632:65:0) after 1m0s
[01:04:50.243] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061746972138453632:65:0)
[01:04:50.243] Timer tick, asking Buildbucket for the build status
[01:04:50.530] Build 8896316909341890864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:04:50.530] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061746972138453632:66:0) after 1m0s
[01:05:50.700] Handling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061746972138453632:66:0)
[01:05:50.701] Timer tick, asking Buildbucket for the build status
[01:05:52.507] Build 8896316909341890864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:05:52.507] Scheduling timer "check-buildbucket-build-status" (emscripten-releases/linux:9061746972138453632:67:0) after 1m0s
[01:06:13.969] Received PubSub notification, asking Buildbucket for the build status
[01:06:14.045] Build 8896316909341890864: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[01:06:14.045] Buildbucket build:
{
"build": {
"bucket": "luci.emscripten-releases.ci",
"canary_preference": "PROD",
"completed_ts": "1574211972999876",
"created_by": "project:emscripten-releases",
"created_ts": "1574208075158524",
"id": "8896316909341890864",
"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\": \"6540b36d40b2ca3d0aae062547c842057fd53039\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@6540b36d40b2ca3d0aae062547c842057fd53039\", \"title\": \"6540b36d40b2ca3d0aae062547c842057fd53039\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/6540b36d40b2ca3d0aae062547c842057fd53039\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"6540b36d40b2ca3d0aae062547c842057fd53039\"}}",
"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\": \"6540b36d40b2ca3d0aae062547c842057fd53039\"}, \"id\": \"https://chromium.googlesource.com/emscripten-releases/+/refs/heads/master@6540b36d40b2ca3d0aae062547c842057fd53039\", \"title\": \"6540b36d40b2ca3d0aae062547c842057fd53039\", \"url\": \"https://chromium.googlesource.com/emscripten-releases/+/6540b36d40b2ca3d0aae062547c842057fd53039\"}]}, \"bot_id\": \"luci-emscripten-releases-ci-xenial-0-j6yq\", \"branch\": \"refs/heads/master\", \"buildername\": \"linux\", \"got_revision\": \"6540b36d40b2ca3d0aae062547c842057fd53039\", \"path_config\": \"generic\", \"recipe\": \"emscripten_releases\", \"repository\": \"https://chromium.googlesource.com/emscripten-releases\", \"revision\": \"6540b36d40b2ca3d0aae062547c842057fd53039\"}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"builder_461cb3affc6f270af3ac9b28cfd8af8c60e3aebc1d1ae3ebd8f2aa2c00b55fae_v2\", \"builder_e437bc7da551d62da6a64b09ca05a66344db3b0846e125cb83052108c473fa36_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-j6yq\"], \"image\": [\"chrome-xenial-19111704-ac4c24a5ee0\"], \"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\": [\"4638-f6b223c\"], \"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": "1574208102758561",
"status": "COMPLETED",
"status_changed_ts": "1574211973439765",
"tags": [
"builder:linux",
"buildset:commit/git/6540b36d40b2ca3d0aae062547c842057fd53039",
"buildset:commit/gitiles/chromium.googlesource.com/emscripten-releases/+/6540b36d40b2ca3d0aae062547c842057fd53039",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9061746972138453632",
"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/8896316909341890864/+/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:489eeec2438f2d10",
"user_agent:luci-scheduler"
],
"updated_ts": "1574211973447965",
"url": "https://ci.chromium.org/b/8896316909341890864",
"utcnow_ts": "1574211974036338"
}
}
[01:06:14.045] Invocation finished in 1h5m0.938142472s with status SUCCEEDED